Index of ironic/77/586277/11/check-ironic/tempest-dsvm-ironic-agent_ipmitool/3238453/

Ran on IBM PowerKVM CI.
NameLast ModifiedSize
console.html2018-09-14 19:33:23 +0000213.5K
apache/2018-09-14 19:33:12 +00003
apache_config/2018-09-14 19:33:11 +00007
ara/2018-09-14 19:33:17 +00007
deprecations.txt.gz2018-09-14 19:33:10 +0000519.0
devstack-early.txt.gz2018-09-14 19:02:55 +0000323.6K
devstack-gate-cleanup-host.txt2018-09-14 19:33:12 +0000130.3K
devstack-gate-discover-hosts.txt.gz2018-09-14 19:03:06 +0000213.0
devstack-gate-pre_test_hook.txt.gz2018-09-14 18:25:27 +00005.4K
devstack-gate-setup-host.txt.gz2018-09-14 18:17:54 +000010.7K
devstack-gate-setup-workspace-new.txt.gz2018-09-14 18:25:12 +000027.0K
devstack-subnodes-early.txt.gz2018-09-14 19:02:56 +0000140.0
devstack.journal.xz2018-09-14 19:33:05 +00002.8M
devstacklog.summary.txt.gz2018-09-14 19:32:39 +0000753.0
devstacklog.txt.gz2018-09-14 19:33:19 +0000458.1K
df.txt.gz2018-09-14 19:33:06 +0000278.0
dpkg-l.txt.gz2018-09-14 19:33:08 +000025.9K
dstat-csv_log.txt.gz2018-09-14 19:33:05 +0000161.7K
etc/2018-09-14 19:32:39 +00007
iptables.txt.gz2018-09-14 19:33:05 +0000777.0
libvirt/2018-09-14 19:33:12 +00005
listen53.txt.gz2018-09-14 19:33:10 +000033.0
local.conf.txt.gz2018-09-14 19:32:39 +00001.9K
openvswitch/2018-09-14 19:33:12 +00002
pip2-freeze.txt.gz2018-09-14 19:33:07 +00002.5K
pip3-freeze.txt.gz2018-09-14 19:33:08 +0000161.0
ps.txt.gz2018-09-14 19:33:11 +00006.0K
rabbitmq/2018-09-14 19:33:11 +00004
reproduce.sh2018-09-14 18:17:16 +00004.9K
resolv_conf.txt.gz2018-09-14 19:33:10 +000072.0
screen-dstat.txt.gz2018-09-14 19:32:39 +0000166.8K
screen-g-api.txt.gz2018-09-14 19:32:39 +000036.6K
screen-g-reg.txt.gz2018-09-14 19:32:39 +00004.9K
screen-ir-api.txt.gz2018-09-14 19:32:39 +000084.3K
screen-ir-cond.txt.gz2018-09-14 19:32:40 +0000131.3K
screen-keystone.txt.gz2018-09-14 19:32:41 +0000502.7K
screen-n-api.txt.gz2018-09-14 19:32:42 +0000117.9K
screen-n-cond-cell1.txt.gz2018-09-14 19:32:42 +000016.5K
screen-n-cpu.txt.gz2018-09-14 19:32:42 +000075.5K
screen-n-sch.txt.gz2018-09-14 19:32:42 +000020.4K
screen-n-super-cond.txt.gz2018-09-14 19:32:42 +000018.4K
screen-placement-api.txt.gz2018-09-14 19:32:42 +0000135.0K
screen-q-agt.txt.gz2018-09-14 19:32:43 +0000100.2K
screen-q-dhcp.txt.gz2018-09-14 19:32:43 +000023.7K
screen-q-meta.txt.gz2018-09-14 19:32:43 +00008.7K
screen-q-metering.txt.gz2018-09-14 19:32:43 +00008.3K
screen-q-svc.txt.gz2018-09-14 19:32:43 +0000141.7K
screen-s-account.txt.gz2018-09-14 19:32:43 +0000951.0
screen-s-container-sync.txt.gz2018-09-14 19:32:43 +0000472.0
screen-s-container.txt.gz2018-09-14 19:32:43 +00002.7K
screen-s-object.txt.gz2018-09-14 19:32:44 +00002.6K
screen-s-proxy.txt.gz2018-09-14 19:32:44 +000014.7K
sudoers.d/2018-09-14 19:33:12 +00007
sudoers.txt.gz2018-09-14 19:32:38 +0000347.0
syslog.txt.gz2018-09-14 19:33:23 +000021.9K
tempest.txt.gz2018-09-14 19:33:10 +0000306.8K
tempest_conf.txt.gz2018-09-14 19:33:05 +00001.5K
testr_results.html.gz2018-09-14 19:33:10 +000012.9K
testrepository.subunit.gz2018-09-14 19:33:09 +0000179.0K


2018-09-14 18:15:51.407 | Started by user anonymous
2018-09-14 18:15:51.415 | [EnvInject] - Loading node environment variables.
2018-09-14 18:15:51.954 | Building remotely on devstack-ironic-ppc64-ironic-324880 (devstack-ironic) in workspace /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool
2018-09-14 18:15:52.038 | [tempest-dsvm-ironic-agent_ipmitool] $ /bin/sh /tmp/hudson6475189074046206177.sh
2018-09-14 18:15:52.230 | Detailed logs: https://dal05.objectstorage.softlayer.net/v1/AUTH_3d8e6ecb-f597-448c-8ec2-164e9f710dd6/pkvmci/ironic/77/586277/11/check-ironic/tempest-dsvm-ironic-agent_ipmitool/3238453/
2018-09-14 18:15:52.267 | [tempest-dsvm-ironic-agent_ipmitool] $ /bin/sh /tmp/hudson2542566249118268737.sh
2018-09-14 18:15:52.300 | Network interface addresses...
2018-09-14 18:15:52.304 | 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1000
2018-09-14 18:15:52.304 |     link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
2018-09-14 18:15:52.304 |     inet 127.0.0.1/8 scope host lo
2018-09-14 18:15:52.304 |        valid_lft forever preferred_lft forever
2018-09-14 18:15:52.304 |     inet6 ::1/128 scope host 
2018-09-14 18:15:52.305 |        valid_lft forever preferred_lft forever
2018-09-14 18:15:52.305 | 2: enp0s1: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP group default qlen 1000
2018-09-14 18:15:52.305 |     link/ether fa:16:3e:f5:f2:c6 brd ff:ff:ff:ff:ff:ff
2018-09-14 18:15:52.305 |     inet 9.114.112.113/24 brd 9.114.112.255 scope global enp0s1
2018-09-14 18:15:52.306 |        valid_lft forever preferred_lft forever
2018-09-14 18:15:52.306 |     inet6 fd55:faaf:e1ab:318:f816:3eff:fef5:f2c6/64 scope global mngtmpaddr dynamic 
2018-09-14 18:15:52.306 |        valid_lft 2591994sec preferred_lft 604794sec
2018-09-14 18:15:52.307 |     inet6 fe80::f816:3eff:fef5:f2c6/64 scope link 
2018-09-14 18:15:52.307 |        valid_lft forever preferred_lft forever
2018-09-14 18:15:52.307 | Network routing tables...
2018-09-14 18:15:52.307 | default via 9.114.112.254 dev enp0s1 onlink 
2018-09-14 18:15:52.307 | 9.114.112.0/24 dev enp0s1  proto kernel  scope link  src 9.114.112.113 
2018-09-14 18:15:52.307 | fd55:faaf:e1ab:318::/64 dev enp0s1  proto kernel  metric 256  expires 2591993sec pref medium
2018-09-14 18:15:52.307 | fe80::/64 dev enp0s1  proto kernel  metric 256  pref medium
2018-09-14 18:15:52.307 | default via fe80::226:8807:e757:b7f0 dev enp0s1  proto ra  metric 1024  expires 1793sec hoplimit 64 pref medium
2018-09-14 18:15:52.307 | Network neighbors...
2018-09-14 18:15:52.308 | 9.114.112.254 dev enp0s1 lladdr 00:26:88:57:b7:f0 REACHABLE
2018-09-14 18:15:52.308 | fe80::226:8807:e757:b7f0 dev enp0s1 lladdr 00:26:88:57:b7:f0 router DELAY
2018-09-14 18:15:52.320 | [tempest-dsvm-ironic-agent_ipmitool] $ /bin/bash -xe /tmp/hudson1496282439189882587.sh
2018-09-14 18:15:52.329 | + ref=refs/heads/master
2018-09-14 18:15:52.329 | + repo=http://git.novalocal/openstack-powerkvm-ci/ibm-devstack-gate.git
2018-09-14 18:15:52.330 | + git clone http://git.novalocal/openstack-powerkvm-ci/ibm-devstack-gate.git
2018-09-14 18:15:52.337 | Cloning into 'ibm-devstack-gate'...
2018-09-14 18:15:53.371 | + cd ibm-devstack-gate
2018-09-14 18:15:53.371 | ++ git ls-remote http://git.novalocal/openstack-powerkvm-ci/ibm-devstack-gate.git refs/heads/master
2018-09-14 18:15:53.371 | ++ tail -n1
2018-09-14 18:15:53.373 | ++ sort -V
2018-09-14 18:15:53.373 | ++ awk '{print $2}'
2018-09-14 18:15:53.548 | + latest=refs/heads/master
2018-09-14 18:15:53.548 | + git fetch http://git.novalocal/openstack-powerkvm-ci/ibm-devstack-gate.git refs/heads/master
2018-09-14 18:15:53.705 | From http://git.novalocal/openstack-powerkvm-ci/ibm-devstack-gate
2018-09-14 18:15:53.705 |  * branch            master     -> FETCH_HEAD
2018-09-14 18:15:53.707 | + git checkout -q FETCH_HEAD
2018-09-14 18:15:53.710 | + git rebase origin/master
2018-09-14 18:15:53.753 | Current branch HEAD is up to date.
2018-09-14 18:15:53.776 | [tempest-dsvm-ironic-agent_ipmitool] $ /bin/bash -xe /tmp/hudson5705421172257999233.sh
2018-09-14 18:15:53.793 | + cat
2018-09-14 18:15:53.793 | + /usr/zuul-env/bin/zuul-cloner -m clonemap.yaml --cache-dir /opt/git git://git.openstack.org openstack-infra/devstack-gate
2018-09-14 18:15:54.310 | INFO:zuul.Cloner:Loaded map containing 1 rules
2018-09-14 18:15:54.310 | INFO:zuul.CloneMapper:Workspace path set to: /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool
2018-09-14 18:15:54.311 | INFO:zuul.CloneMapper:Mapping projects to workspace...
2018-09-14 18:15:54.311 | INFO:zuul.CloneMapper:  openstack-infra/devstack-gate -> /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/devstack-gate
2018-09-14 18:15:54.312 | INFO:zuul.CloneMapper:Expansion completed.
2018-09-14 18:15:54.312 | INFO:zuul.Cloner:Preparing 1 repositories
2018-09-14 18:15:54.313 | INFO:zuul.Cloner:Creating repo openstack-infra/devstack-gate from cache file:///opt/git/openstack-infra/devstack-gate
2018-09-14 18:15:55.519 | INFO:zuul.Cloner:Updating origin remote in repo openstack-infra/devstack-gate to git://git.openstack.org/openstack-infra/devstack-gate
2018-09-14 18:16:01.548 | INFO:zuul.Cloner:upstream repo has branch master
2018-09-14 18:16:02.350 | INFO:zuul.Cloner:Falling back to branch master
2018-09-14 18:16:03.897 | INFO:zuul.Cloner:Prepared openstack-infra/devstack-gate repo with branch master at commit 95fa4343104eafa655375cce3546d27139211d13
2018-09-14 18:16:03.898 | INFO:zuul.Cloner:Prepared all repositories
2018-09-14 18:16:03.937 | [tempest-dsvm-ironic-agent_ipmitool] $ /bin/bash +xe /tmp/hudson8776466457937027776.sh
2018-09-14 18:16:04.399 | [tempest-dsvm-ironic-agent_ipmitool] $ /bin/bash +x /tmp/hudson699311501310064718.sh
2018-09-14 18:16:04.407 | + sudo blkid -t TYPE=swap -l -o device
2018-09-14 18:16:04.408 | + xargs sudo swapon
2018-09-14 18:16:04.433 | + sudo touch /etc/fixed_disk_layout
2018-09-14 18:16:04.442 | + sudo cat /proc/swaps
2018-09-14 18:16:04.448 | Filename				Type		Size	Used	Priority
2018-09-14 18:16:04.449 | /dev/vda                                partition	8388544	0	-2
2018-09-14 18:16:04.467 | [tempest-dsvm-ironic-agent_ipmitool] $ /bin/bash -xe /tmp/hudson1476283317029730149.sh
2018-09-14 18:16:04.477 | + export KEEP_LOCALRC=1
2018-09-14 18:16:04.477 | + KEEP_LOCALRC=1
2018-09-14 18:16:04.478 | + export 'PROJECTS=openstack/ironic '
2018-09-14 18:16:04.478 | + PROJECTS='openstack/ironic '
2018-09-14 18:16:04.479 | + export 'PROJECTS=openstack/ironic-lib openstack/ironic '
2018-09-14 18:16:04.479 | + PROJECTS='openstack/ironic-lib openstack/ironic '
2018-09-14 18:16:04.480 | + export 'PROJECTS=openstack/python-ironicclient openstack/ironic-lib openstack/ironic '
2018-09-14 18:16:04.480 | + PROJECTS='openstack/python-ironicclient openstack/ironic-lib openstack/ironic '
2018-09-14 18:16:04.481 | + export 'PROJECTS=openstack/ironic-tempest-plugin openstack/python-ironicclient openstack/ironic-lib openstack/ironic '
2018-09-14 18:16:04.481 | + PROJECTS='openstack/ironic-tempest-plugin openstack/python-ironicclient openstack/ironic-lib openstack/ironic '
2018-09-14 18:16:04.482 | + export PYTHONUNBUFFERED=true
2018-09-14 18:16:04.483 | + PYTHONUNBUFFERED=true
2018-09-14 18:16:04.483 | + export DEVSTACK_GATE_TIMEOUT=120
2018-09-14 18:16:04.484 | + DEVSTACK_GATE_TIMEOUT=120
2018-09-14 18:16:04.484 | + export DEVSTACK_GATE_TEMPEST=1
2018-09-14 18:16:04.484 | + DEVSTACK_GATE_TEMPEST=1
2018-09-14 18:16:04.485 | + export DEVSTACK_GATE_POSTGRES=0
2018-09-14 18:16:04.485 | + DEVSTACK_GATE_POSTGRES=0
2018-09-14 18:16:04.485 | + export DEVSTACK_GATE_IRONIC=1
2018-09-14 18:16:04.485 | + DEVSTACK_GATE_IRONIC=1
2018-09-14 18:16:04.485 | + export DEVSTACK_GATE_NEUTRON=1
2018-09-14 18:16:04.485 | + DEVSTACK_GATE_NEUTRON=1
2018-09-14 18:16:04.485 | + export DEVSTACK_GATE_VIRT_DRIVER=ironic
2018-09-14 18:16:04.486 | + DEVSTACK_GATE_VIRT_DRIVER=ironic
2018-09-14 18:16:04.486 | + export DEVSTACK_GATE_IRONIC_DRIVER=ipmi
2018-09-14 18:16:04.486 | + DEVSTACK_GATE_IRONIC_DRIVER=ipmi
2018-09-14 18:16:04.486 | + export DEVSTACK_GATE_IRONIC_BUILD_RAMDISK=0
2018-09-14 18:16:04.486 | + DEVSTACK_GATE_IRONIC_BUILD_RAMDISK=0
2018-09-14 18:16:04.486 | + export TEMPEST_CONCURRENCY=1
2018-09-14 18:16:04.486 | + TEMPEST_CONCURRENCY=1
2018-09-14 18:16:04.487 | + export DEVSTACK_GATE_OS_TEST_TIMEOUT=1800
2018-09-14 18:16:04.487 | + DEVSTACK_GATE_OS_TEST_TIMEOUT=1800
2018-09-14 18:16:04.487 | + export DEVSTACK_GATE_CONFIGDRIVE=1
2018-09-14 18:16:04.487 | + DEVSTACK_GATE_CONFIGDRIVE=1
2018-09-14 18:16:04.487 | + export OVERRIDE_ENABLED_SERVICES=ceilometer-acentral,ceilometer-acompute,ceilometer-alarm-evaluator,ceilometer-alarm-notifier,ceilometer-anotification,ceilometer-api,ceilometer-collector,dstat,g-api,g-reg,ironic,ir-api,ir-cond,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,placement-api,q-agt,q-dhcp,q-meta,q-metering,q-svc,quantum,rabbit,s-account,s-container,s-object,s-proxy,tempest
2018-09-14 18:16:04.487 | + OVERRIDE_ENABLED_SERVICES=ceilometer-acentral,ceilometer-acompute,ceilometer-alarm-evaluator,ceilometer-alarm-notifier,ceilometer-anotification,ceilometer-api,ceilometer-collector,dstat,g-api,g-reg,ironic,ir-api,ir-cond,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,placement-api,q-agt,q-dhcp,q-meta,q-metering,q-svc,quantum,rabbit,s-account,s-container,s-object,s-proxy,tempest
2018-09-14 18:16:04.487 | + export DEVSTACK_GATE_FIXED_RANGE=9.114.112.0/24
2018-09-14 18:16:04.488 | + DEVSTACK_GATE_FIXED_RANGE=9.114.112.0/24
2018-09-14 18:16:04.488 | + export BRANCH_OVERRIDE=default
2018-09-14 18:16:04.488 | + BRANCH_OVERRIDE=default
2018-09-14 18:16:04.488 | + '[' default '!=' default ']'
2018-09-14 18:16:04.488 | + export IRONICCLIENT_FROM_SOURCE=0
2018-09-14 18:16:04.488 | + IRONICCLIENT_FROM_SOURCE=0
2018-09-14 18:16:04.488 | + '[' 0 == 1 ']'
2018-09-14 18:16:04.488 | + export IRONIC_LIB_FROM_SOURCE=0
2018-09-14 18:16:04.489 | + IRONIC_LIB_FROM_SOURCE=0
2018-09-14 18:16:04.489 | + '[' 0 == 1 ']'
2018-09-14 18:16:04.489 | + export DEPLOY_WITH_IPA=1
2018-09-14 18:16:04.489 | + DEPLOY_WITH_IPA=1
2018-09-14 18:16:04.489 | + '[' 1 == 1 ']'
2018-09-14 18:16:04.489 | + DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.490 | IRONIC_DEPLOY_DRIVER_ISCSI_WITH_IPA=True'
2018-09-14 18:16:04.490 | + DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.490 | IRONIC_AGENT_RAMDISK_URL=http://ironic-image-server.pok.stglabs.ibm.com/images/ironic-agent-centos.initramfs'
2018-09-14 18:16:04.491 | + DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.491 | IRONIC_AGENT_KERNEL_URL=http://ironic-image-server.pok.stglabs.ibm.com/images/ironic-agent-centos.vmlinuz'
2018-09-14 18:16:04.492 | + DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.492 | DEFAULT_IMAGE_NAME=centos7-ppc64le'
2018-09-14 18:16:04.492 | + DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.492 | NETWORK_GATEWAY=9.114.112.254'
2018-09-14 18:16:04.493 | + DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.493 | IRONIC_VM_COUNT=0'
2018-09-14 18:16:04.493 | + DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.493 | Q_ML2_TENANT_NETWORK_TYPE=flat'
2018-09-14 18:16:04.493 | + DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.493 | IRONIC_CALLBACK_TIMEOUT=3600'
2018-09-14 18:16:04.494 | + DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.494 | BUILD_TIMEOUT=3600'
2018-09-14 18:16:04.495 | + DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.495 | enable_plugin ironic git://git.openstack.org/openstack/ironic'
2018-09-14 18:16:04.495 | + DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.496 | TEMPEST_SSH_CONNECT_METHOD=fixed'
2018-09-14 18:16:04.496 | + DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.496 | SWIFT_ENABLE_TEMPURLS=True'
2018-09-14 18:16:04.496 | + DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.497 | TEMPEST_PLUGINS=/opt/stack/new/ironic-tempest-plugin'
2018-09-14 18:16:04.497 | + export DEVSTACK_LOCAL_CONFIG
2018-09-14 18:16:04.497 | ++ source ibm-devstack-gate/regex_ironic 'ironic|baremetal'
2018-09-14 18:16:04.497 | +++ __default='ironic|baremetal'
2018-09-14 18:16:04.497 | +++ tr -d '\n'
2018-09-14 18:16:04.498 | +++ sed '/^#/d'
2018-09-14 18:16:04.498 | +++ echo '
2018-09-14 18:16:04.498 | (?!.*(
2018-09-14 18:16:04.499 | baremetal.test.to.skip1
2018-09-14 18:16:04.499 | |baremetal.test.to.skip2
2018-09-14 18:16:04.500 | ).*)
2018-09-14 18:16:04.500 | (ironic|baremetal)
2018-09-14 18:16:04.500 | '
2018-09-14 18:16:04.501 | + export 'DEVSTACK_GATE_TEMPEST_REGEX=(?!.*(baremetal.test.to.skip1|baremetal.test.to.skip2).*)(ironic|baremetal)'
2018-09-14 18:16:04.501 | + DEVSTACK_GATE_TEMPEST_REGEX='(?!.*(baremetal.test.to.skip1|baremetal.test.to.skip2).*)(ironic|baremetal)'
2018-09-14 18:16:04.501 | + export -f pre_test_hook
2018-09-14 18:16:04.502 | + test -f ibm-devstack-gate/commonrc
2018-09-14 18:16:04.502 | + source ibm-devstack-gate/commonrc
2018-09-14 18:16:04.502 | ++ '[' -z '' ']'
2018-09-14 18:16:04.502 | ++ export 'PROJECTS=openstack/devstack-plugin-mariadb openstack/ironic-tempest-plugin openstack/python-ironicclient openstack/ironic-lib openstack/ironic '
2018-09-14 18:16:04.502 | ++ PROJECTS='openstack/devstack-plugin-mariadb openstack/ironic-tempest-plugin openstack/python-ironicclient openstack/ironic-lib openstack/ironic '
2018-09-14 18:16:04.502 | ++ export 'DEVSTACK_LOCAL_CONFIG+=
2018-09-14 18:16:04.503 | grep -q -v primary /etc/nodepool/role || enable_plugin devstack-plugin-mariadb git://github.com/openstack/devstack-plugin-mariadb'
2018-09-14 18:16:04.503 | ++ DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.503 | grep -q -v primary /etc/nodepool/role || enable_plugin devstack-plugin-mariadb git://github.com/openstack/devstack-plugin-mariadb'
2018-09-14 18:16:04.503 | ++ export DEVSTACK_GATE_SETTINGS=ibm-devstack-gate/devstackgaterc
2018-09-14 18:16:04.504 | ++ DEVSTACK_GATE_SETTINGS=ibm-devstack-gate/devstackgaterc
2018-09-14 18:16:04.504 | ++ export NODEPOOL_MIRROR_HOST=aptcache
2018-09-14 18:16:04.505 | ++ NODEPOOL_MIRROR_HOST=aptcache
2018-09-14 18:16:04.505 | ++ test -f /etc/nodepool/provider
2018-09-14 18:16:04.506 | ++ echo NODEPOOL_MIRROR_HOST=aptcache
2018-09-14 18:16:04.506 | ++ export 'DEVSTACK_LOCAL_CONFIG+=
2018-09-14 18:16:04.506 | ETCD_DOWNLOAD_URL=http://aptcache.novalocal/etcd'
2018-09-14 18:16:04.507 | ++ DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.508 | ETCD_DOWNLOAD_URL=http://aptcache.novalocal/etcd'
2018-09-14 18:16:04.508 | ++ export 'DEVSTACK_LOCAL_CONFIG+=
2018-09-14 18:16:04.509 | ETCD_VERSION=v3.2.0'
2018-09-14 18:16:04.509 | ++ DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.510 | ETCD_VERSION=v3.2.0'
2018-09-14 18:16:04.510 | ++ export 'DEVSTACK_LOCAL_CONFIG+=
2018-09-14 18:16:04.510 | ETCD_SHA256=2466ec19d41290ccb15616c3b021e2e4edc7efa669c6b65f41da1d8c0a2a0fbc'
2018-09-14 18:16:04.510 | ++ DEVSTACK_LOCAL_CONFIG+='
2018-09-14 18:16:04.511 | ETCD_SHA256=2466ec19d41290ccb15616c3b021e2e4edc7efa669c6b65f41da1d8c0a2a0fbc'
2018-09-14 18:16:04.511 | ++ sudo apt-get update
2018-09-14 18:16:04.720 | Get:1 http://ports.ubuntu.com/ubuntu-ports xenial InRelease [247 kB]
2018-09-14 18:16:04.830 | Get:2 http://ports.ubuntu.com/ubuntu-ports xenial-updates InRelease [109 kB]
2018-09-14 18:16:04.925 | Get:3 http://ports.ubuntu.com/ubuntu-ports xenial-backports InRelease [107 kB]
2018-09-14 18:16:05.021 | Get:4 http://ports.ubuntu.com/ubuntu-ports xenial-security InRelease [107 kB]
2018-09-14 18:16:05.022 | Ign:5 http://ports.ubuntu.com/ubuntu-ports xenial/main ppc64el Packages
2018-09-14 18:16:05.022 | Ign:6 http://ports.ubuntu.com/ubuntu-ports xenial/universe ppc64el Packages
2018-09-14 18:16:05.025 | Ign:7 http://ports.ubuntu.com/ubuntu-ports xenial-updates/main ppc64el Packages
2018-09-14 18:16:05.026 | Ign:8 http://ports.ubuntu.com/ubuntu-ports xenial-updates/universe ppc64el Packages
2018-09-14 18:16:05.028 | Ign:9 http://ports.ubuntu.com/ubuntu-ports xenial-backports/main ppc64el Packages
2018-09-14 18:16:05.029 | Ign:10 http://ports.ubuntu.com/ubuntu-ports xenial-backports/universe ppc64el Packages
2018-09-14 18:16:05.119 | Get:5 http://ports.ubuntu.com/ubuntu-ports xenial/main ppc64el Packages [1134 kB]
2018-09-14 18:16:05.306 | Get:6 http://ports.ubuntu.com/ubuntu-ports xenial/universe ppc64el Packages [7269 kB]
2018-09-14 18:16:05.605 | Get:7 http://ports.ubuntu.com/ubuntu-ports xenial-updates/main ppc64el Packages [663 kB]
2018-09-14 18:16:05.776 | Get:8 http://ports.ubuntu.com/ubuntu-ports xenial-updates/universe ppc64el Packages [568 kB]
2018-09-14 18:16:05.950 | Get:9 http://ports.ubuntu.com/ubuntu-ports xenial-backports/main ppc64el Packages [6752 B]
2018-09-14 18:16:06.041 | Get:10 http://ports.ubuntu.com/ubuntu-ports xenial-backports/universe ppc64el Packages [6560 B]
2018-09-14 18:16:06.043 | Ign:11 http://ports.ubuntu.com/ubuntu-ports xenial-security/main ppc64el Packages
2018-09-14 18:16:06.045 | Ign:12 http://ports.ubuntu.com/ubuntu-ports xenial-security/universe ppc64el Packages
2018-09-14 18:16:06.136 | Get:11 http://ports.ubuntu.com/ubuntu-ports xenial-security/main ppc64el Packages [394 kB]
2018-09-14 18:16:06.327 | Get:12 http://ports.ubuntu.com/ubuntu-ports xenial-security/universe ppc64el Packages [282 kB]
2018-09-14 18:16:07.338 | Fetched 10.9 MB in 2s (3869 kB/s)
2018-09-14 18:16:08.145 | Reading package lists...
2018-09-14 18:16:08.178 | ++ sudo apt-get install -y libffi-dev libssl-dev
2018-09-14 18:16:08.215 | Reading package lists...
2018-09-14 18:16:08.368 | Building dependency tree...
2018-09-14 18:16:08.369 | Reading state information...
2018-09-14 18:16:08.497 | libffi-dev is already the newest version (3.2.1-4).
2018-09-14 18:16:08.497 | libssl-dev is already the newest version (1.0.2g-1ubuntu4.13).
2018-09-14 18:16:08.497 | 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
2018-09-14 18:16:08.498 | ++ sudo -H pip install --upgrade setuptools
2018-09-14 18:16:08.902 | Looking in indexes: http://pypi-mirror.novalocal/wheels, http://pypi-mirror.novalocal/simple, https://pypi.python.org/simple
2018-09-14 18:16:10.566 | Requirement already up-to-date: setuptools in /usr/local/lib/python2.7/dist-packages (40.2.0)
2018-09-14 18:16:10.853 | + cp devstack-gate/devstack-vm-gate-wrap.sh ./safe-devstack-vm-gate-wrap.sh
2018-09-14 18:16:10.855 | + ./safe-devstack-vm-gate-wrap.sh
2018-09-14 18:16:10.864 | apt-get is /usr/bin/apt-get
2018-09-14 18:16:17.788 | 14 Sep 18:16:17 ntpdate[1351]: adjust time server 74.117.214.3 offset 0.000038 sec
2018-09-14 18:16:18.961 | The PROJECTS list is:
2018-09-14 18:16:18.963 | openstack-dev/devstack openstack-infra/devstack-gate openstack-infra/tripleo-ci 
2018-09-14 18:16:18.964 | openstack/ceilometer openstack/ceilometermiddleware openstack/cinder 
2018-09-14 18:16:18.964 | openstack/devstack-plugin-mariadb openstack/glance openstack/glance_store 
2018-09-14 18:16:18.965 | openstack/heat openstack/heat-cfntools openstack/heat-templates 
2018-09-14 18:16:18.965 | openstack/ironic openstack/ironic-lib openstack/ironic-tempest-plugin 
2018-09-14 18:16:18.966 | openstack/keystone openstack/keystoneauth openstack/keystonemiddleware 
2018-09-14 18:16:18.966 | openstack/manila openstack/neutron openstack/neutron-fwaas 
2018-09-14 18:16:18.967 | openstack/neutron-lbaas openstack/neutron-vpnaas openstack/nova 
2018-09-14 18:16:18.967 | openstack/octavia openstack/os-apply-config openstack/os-brick 
2018-09-14 18:16:18.968 | openstack/os-client-config openstack/os-collect-config openstack/os-net-config 
2018-09-14 18:16:18.968 | openstack/os-refresh-config openstack/osc-lib openstack/python-ironicclient 
2018-09-14 18:16:18.968 | openstack/requirements openstack/swift openstack/tempest 
2018-09-14 18:16:18.969 | openstack/tripleo-heat-templates openstack/tripleo-image-elements 
2018-09-14 18:16:18.969 | openstack/zaqar
2018-09-14 18:16:18.970 | ---
2018-09-14 18:16:18.975 | Triggered by: https://review.openstack.org/586277 patchset 11
2018-09-14 18:16:18.976 | Pipeline: check-ironic
2018-09-14 18:16:18.977 | Timeout set to 115 minutes with 10 minutes reserved for cleanup.
2018-09-14 18:16:18.977 | Available disk space on this host:
2018-09-14 18:16:18.978 |     Filesystem      Size  Used Avail Use% Mounted on
2018-09-14 18:16:18.979 |     udev            2.0G     0  2.0G   0% /dev
2018-09-14 18:16:18.979 |     tmpfs           407M  7.8M  400M   2% /run
2018-09-14 18:16:18.980 |     /dev/vdb2        75G  4.6G   68G   7% /
2018-09-14 18:16:18.981 |     tmpfs           2.0G     0  2.0G   0% /dev/shm
2018-09-14 18:16:18.982 |     tmpfs           5.0M     0  5.0M   0% /run/lock
2018-09-14 18:16:18.982 |     tmpfs           2.0G     0  2.0G   0% /sys/fs/cgroup
2018-09-14 18:16:18.983 |     /dev/sr0        422K  422K     0 100% /mnt/config
2018-09-14 18:16:19.336 | Looking in indexes: http://pypi-mirror.novalocal/wheels, http://pypi-mirror.novalocal/simple, https://pypi.python.org/simple
2018-09-14 18:16:19.337 | Requirement already satisfied: virtualenv in /usr/local/lib/python2.7/dist-packages (16.0.0)
2018-09-14 18:16:19.491 | New python executable in /tmp/ansible/bin/python2
2018-09-14 18:16:19.495 | Also creating executable in /tmp/ansible/bin/python
2018-09-14 18:16:26.840 | Installing setuptools, pip, wheel...done.
2018-09-14 18:16:27.198 | Looking in indexes: http://pypi-mirror.novalocal/wheels, http://pypi-mirror.novalocal/simple, https://pypi.python.org/simple
2018-09-14 18:16:27.204 | Collecting pbr
2018-09-14 18:16:27.420 |   Downloading http://pypi-mirror.novalocal/wheels/pbr/pbr-4.2.0-py2.py3-none-any.whl (100kB)
2018-09-14 18:16:27.453 | Installing collected packages: pbr
2018-09-14 18:16:27.522 | Successfully installed pbr-4.2.0
2018-09-14 18:16:28.028 | Looking in indexes: http://pypi-mirror.novalocal/wheels, http://pypi-mirror.novalocal/simple, https://pypi.python.org/simple
2018-09-14 18:16:28.035 | Collecting ansible==2.5.7
2018-09-14 18:16:28.851 |   Downloading http://pypi-mirror.novalocal/wheels/ansible/ansible-2.5.7-py2-none-any.whl (7.4MB)
2018-09-14 18:16:29.406 | Collecting devstack-tools==0.4.0
2018-09-14 18:16:30.097 |   Downloading http://pypi-mirror.novalocal/wheels/devstack-tools/devstack_tools-0.4.0-py2-none-any.whl
2018-09-14 18:16:30.116 | Collecting ara
2018-09-14 18:16:30.525 |   Downloading http://pypi-mirror.novalocal/wheels/ara/ara-0.16.1-py2.py3-none-any.whl (152kB)
2018-09-14 18:16:30.582 | Collecting cmd2<0.9.0
2018-09-14 18:16:32.322 |   Downloading http://pypi-mirror.novalocal/wheels/cmd2/cmd2-0.8.9-py2.py3-none-any.whl (53kB)
2018-09-14 18:16:32.354 | Collecting paramiko (from ansible==2.5.7)
2018-09-14 18:16:33.486 |   Downloading http://pypi-mirror.novalocal/wheels/paramiko/paramiko-2.4.1-py2.py3-none-any.whl (194kB)
2018-09-14 18:16:33.516 | Collecting cryptography (from ansible==2.5.7)
2018-09-14 18:16:36.247 |   Downloading http://pypi-mirror.novalocal/wheels/cryptography/cryptography-2.3.1-cp27-cp27mu-linux_ppc64le.whl (859kB)
2018-09-14 18:16:36.333 | Collecting PyYAML (from ansible==2.5.7)
2018-09-14 18:16:36.436 |   Downloading http://pypi-mirror.novalocal/wheels/pyyaml/PyYAML-3.13-cp27-cp27mu-linux_ppc64le.whl (337kB)
2018-09-14 18:16:36.463 | Collecting jinja2 (from ansible==2.5.7)
2018-09-14 18:16:37.216 |   Downloading http://pypi-mirror.novalocal/wheels/jinja2/Jinja2-2.10-py2.py3-none-any.whl (126kB)
2018-09-14 18:16:37.235 | Requirement already satisfied: setuptools in /tmp/ansible/lib/python2.7/site-packages (from ansible==2.5.7) (40.2.0)
2018-09-14 18:16:37.242 | Requirement already satisfied: pbr>=1.8 in /tmp/ansible/lib/python2.7/site-packages (from devstack-tools==0.4.0) (4.2.0)
2018-09-14 18:16:37.249 | Collecting Flask-Migrate (from ara)
2018-09-14 18:16:37.332 |   Downloading http://pypi-mirror.novalocal/wheels/flask-migrate/Flask_Migrate-2.2.1-py2.py3-none-any.whl
2018-09-14 18:16:37.352 | Collecting XStatic-Bootstrap-SCSS>=3.3.7.1 (from ara)
2018-09-14 18:16:37.692 |   Downloading http://pypi-mirror.novalocal/wheels/xstatic-bootstrap-scss/XStatic_Bootstrap_SCSS-3.3.7.1-py2.py3-none-any.whl (247kB)
2018-09-14 18:16:37.724 | Collecting pygments>=1.6 (from ara)
2018-09-14 18:16:38.434 |   Downloading http://pypi-mirror.novalocal/wheels/pygments/Pygments-2.2.0-py2.py3-none-any.whl (841kB)
2018-09-14 18:16:38.499 | Collecting Flask!=0.12.3,>=0.11 (from ara)
2018-09-14 18:16:38.598 |   Downloading http://pypi-mirror.novalocal/wheels/flask/Flask-1.0.2-py2.py3-none-any.whl (91kB)
2018-09-14 18:16:38.638 | Collecting pyfakefs (from ara)
2018-09-14 18:16:38.714 |   Downloading http://pypi-mirror.novalocal/wheels/pyfakefs/pyfakefs-3.4.3-py2.py3-none-any.whl (153kB)
2018-09-14 18:16:38.740 | Collecting Flask-Script (from ara)
2018-09-14 18:16:38.813 |   Downloading http://pypi-mirror.novalocal/wheels/flask-script/Flask_Script-2.0.6-py2-none-any.whl
2018-09-14 18:16:38.830 | Collecting junit-xml>=1.7 (from ara)
2018-09-14 18:16:38.891 |   Downloading http://pypi-mirror.novalocal/wheels/junit-xml/junit_xml-1.8-py2-none-any.whl
2018-09-14 18:16:38.908 | Collecting Frozen-Flask (from ara)
2018-09-14 18:16:38.995 |   Downloading http://pypi-mirror.novalocal/wheels/frozen-flask/Frozen_Flask-0.15-py2.py3-none-any.whl
2018-09-14 18:16:39.013 | Collecting XStatic>=1.0.0 (from ara)
2018-09-14 18:16:39.340 |   Downloading http://pypi-mirror.novalocal/wheels/xstatic/XStatic-1.0.1-py2-none-any.whl
2018-09-14 18:16:39.355 | Collecting oslo.utils>=3.20.0 (from ara)
2018-09-14 18:16:39.832 |   Downloading http://pypi-mirror.novalocal/wheels/oslo-utils/oslo.utils-3.37.0-py2.py3-none-any.whl (92kB)
2018-09-14 18:16:39.872 | Collecting XStatic-jQuery>=1.8.2.1 (from ara)
2018-09-14 18:16:39.933 |   Downloading http://pypi-mirror.novalocal/wheels/xstatic-jquery/XStatic_jQuery-1.10.2.1-py2-none-any.whl (118kB)
2018-09-14 18:16:39.953 | Collecting XStatic-Patternfly>=3.21.0 (from ara)
2018-09-14 18:16:40.005 |   Downloading http://pypi-mirror.novalocal/wheels/xstatic-patternfly/XStatic_Patternfly-3.21.0.1-py2.py3-none-any.whl (5.8MB)
2018-09-14 18:16:40.191 | Collecting XStatic-DataTables>=1.10.15 (from ara)
2018-09-14 18:16:40.252 |   Downloading http://pypi-mirror.novalocal/wheels/xstatic-datatables/XStatic_DataTables-1.10.15.1-py2-none-any.whl (237kB)
2018-09-14 18:16:40.279 | Collecting oslo.serialization>=1.10.0 (from ara)
2018-09-14 18:16:41.009 |   Downloading http://pypi-mirror.novalocal/wheels/oslo-serialization/oslo.serialization-2.27.0-py2.py3-none-any.whl
2018-09-14 18:16:41.031 | Collecting Flask-SQLAlchemy (from ara)
2018-09-14 18:16:41.107 |   Downloading http://pypi-mirror.novalocal/wheels/flask-sqlalchemy/Flask_SQLAlchemy-2.3.2-py2.py3-none-any.whl
2018-09-14 18:16:41.127 | Collecting six (from ara)
2018-09-14 18:16:41.862 |   Downloading http://pypi-mirror.novalocal/wheels/six/six-1.11.0-py2.py3-none-any.whl
2018-09-14 18:16:41.877 | Collecting python-subunit (from ara)
2018-09-14 18:16:41.954 |   Downloading http://pypi-mirror.novalocal/wheels/python-subunit/python_subunit-1.3.0-py2.py3-none-any.whl (104kB)
2018-09-14 18:16:41.988 | Collecting XStatic-Patternfly-Bootstrap-Treeview>=2.1.3 (from ara)
2018-09-14 18:16:42.295 |   Downloading http://pypi-mirror.novalocal/wheels/xstatic-patternfly-bootstrap-treeview/XStatic_Patternfly_Bootstrap_Treeview-2.1.3.2-py2.py3-none-any.whl
2018-09-14 18:16:42.312 | Collecting cliff (from ara)
2018-09-14 18:16:43.040 |   Downloading http://pypi-mirror.novalocal/wheels/cliff/cliff-2.13.0-py2.py3-none-any.whl (71kB)
2018-09-14 18:16:43.078 | Collecting contextlib2; python_version < "3.5" (from cmd2<0.9.0)
2018-09-14 18:16:43.149 |   Downloading http://pypi-mirror.novalocal/wheels/contextlib2/contextlib2-0.5.5-py2.py3-none-any.whl
2018-09-14 18:16:43.165 | Collecting pyperclip (from cmd2<0.9.0)
2018-09-14 18:16:43.259 |   Downloading http://pypi-mirror.novalocal/wheels/pyperclip/pyperclip-1.6.4-py2-none-any.whl
2018-09-14 18:16:43.275 | Collecting enum34; python_version < "3.4" (from cmd2<0.9.0)
2018-09-14 18:16:43.663 |   Downloading http://pypi-mirror.novalocal/wheels/enum34/enum34-1.1.6-py2-none-any.whl
2018-09-14 18:16:43.679 | Collecting wcwidth; sys_platform != "win32" (from cmd2<0.9.0)
2018-09-14 18:16:43.744 |   Downloading http://pypi-mirror.novalocal/wheels/wcwidth/wcwidth-0.1.7-py2.py3-none-any.whl
2018-09-14 18:16:43.763 | Collecting subprocess32; python_version < "3.0" (from cmd2<0.9.0)
2018-09-14 18:16:43.835 |   Downloading http://pypi-mirror.novalocal/wheels/subprocess32/subprocess32-3.5.2-cp27-cp27mu-linux_ppc64le.whl (44kB)
2018-09-14 18:16:43.853 | Collecting pyparsing>=2.0.1 (from cmd2<0.9.0)
2018-09-14 18:16:44.085 |   Downloading http://pypi-mirror.novalocal/wheels/pyparsing/pyparsing-2.2.0-py2.py3-none-any.whl (56kB)
2018-09-14 18:16:44.103 | Collecting bcrypt>=3.1.3 (from paramiko->ansible==2.5.7)
2018-09-14 18:16:44.583 |   Downloading http://pypi-mirror.novalocal/wheels/bcrypt/bcrypt-3.1.4-cp27-cp27mu-linux_ppc64le.whl (51kB)
2018-09-14 18:16:44.604 | Collecting pyasn1>=0.1.7 (from paramiko->ansible==2.5.7)
2018-09-14 18:16:45.142 |   Downloading http://pypi-mirror.novalocal/wheels/pyasn1/pyasn1-0.4.4-py2.py3-none-any.whl (72kB)
2018-09-14 18:16:45.164 | Collecting pynacl>=1.0.1 (from paramiko->ansible==2.5.7)
2018-09-14 18:16:45.320 |   Downloading http://pypi-mirror.novalocal/wheels/pynacl/PyNaCl-1.2.1-cp27-cp27mu-linux_ppc64le.whl (413kB)
2018-09-14 18:16:45.366 | Collecting cffi!=1.11.3,>=1.7 (from cryptography->ansible==2.5.7)
2018-09-14 18:16:47.128 |   Downloading http://pypi-mirror.novalocal/wheels/cffi/cffi-1.11.5-cp27-cp27mu-linux_ppc64le.whl (303kB)
2018-09-14 18:16:47.157 | Collecting idna>=2.1 (from cryptography->ansible==2.5.7)
2018-09-14 18:16:47.504 |   Downloading http://pypi-mirror.novalocal/wheels/idna/idna-2.7-py2.py3-none-any.whl (58kB)
2018-09-14 18:16:47.523 | Collecting asn1crypto>=0.21.0 (from cryptography->ansible==2.5.7)
2018-09-14 18:16:48.264 |   Downloading http://pypi-mirror.novalocal/wheels/asn1crypto/asn1crypto-0.24.0-py2.py3-none-any.whl (101kB)
2018-09-14 18:16:48.287 | Collecting ipaddress; python_version < "3" (from cryptography->ansible==2.5.7)
2018-09-14 18:16:48.659 |   Downloading http://pypi-mirror.novalocal/wheels/ipaddress/ipaddress-1.0.22-py2.py3-none-any.whl
2018-09-14 18:16:48.677 | Collecting MarkupSafe>=0.23 (from jinja2->ansible==2.5.7)
2018-09-14 18:16:48.752 |   Downloading http://pypi-mirror.novalocal/wheels/markupsafe/MarkupSafe-1.0-cp27-cp27mu-linux_ppc64le.whl
2018-09-14 18:16:48.770 | Collecting alembic>=0.7 (from Flask-Migrate->ara)
2018-09-14 18:16:48.885 |   Downloading http://pypi-mirror.novalocal/wheels/alembic/alembic-1.0.0-py2.py3-none-any.whl (158kB)
2018-09-14 18:16:48.918 | Collecting itsdangerous>=0.24 (from Flask!=0.12.3,>=0.11->ara)
2018-09-14 18:16:48.987 |   Downloading http://pypi-mirror.novalocal/wheels/itsdangerous/itsdangerous-0.24-py2-none-any.whl
2018-09-14 18:16:49.003 | Collecting click>=5.1 (from Flask!=0.12.3,>=0.11->ara)
2018-09-14 18:16:49.673 |   Downloading http://pypi-mirror.novalocal/wheels/click/click-6.7-py2.py3-none-any.whl (71kB)
2018-09-14 18:16:49.693 | Collecting Werkzeug>=0.14 (from Flask!=0.12.3,>=0.11->ara)
2018-09-14 18:16:49.828 |   Downloading http://pypi-mirror.novalocal/wheels/werkzeug/Werkzeug-0.14.1-py2.py3-none-any.whl (322kB)
2018-09-14 18:16:49.866 | Collecting iso8601>=0.1.11 (from oslo.utils>=3.20.0->ara)
2018-09-14 18:16:49.957 |   Downloading http://pypi-mirror.novalocal/wheels/iso8601/iso8601-0.1.12-py2.py3-none-any.whl
2018-09-14 18:16:49.974 | Collecting netaddr>=0.7.18 (from oslo.utils>=3.20.0->ara)
2018-09-14 18:16:50.403 |   Downloading http://pypi-mirror.novalocal/wheels/netaddr/netaddr-0.7.19-py2.py3-none-any.whl (1.6MB)
2018-09-14 18:16:50.474 | Collecting oslo.i18n>=3.15.3 (from oslo.utils>=3.20.0->ara)
2018-09-14 18:16:50.624 |   Downloading http://pypi-mirror.novalocal/wheels/oslo-i18n/oslo.i18n-3.21.0-py2.py3-none-any.whl (40kB)
2018-09-14 18:16:50.648 | Collecting netifaces>=0.10.4 (from oslo.utils>=3.20.0->ara)
2018-09-14 18:16:51.081 |   Downloading http://pypi-mirror.novalocal/wheels/netifaces/netifaces-0.10.7-cp27-cp27mu-linux_ppc64le.whl
2018-09-14 18:16:51.096 | Collecting pytz>=2013.6 (from oslo.utils>=3.20.0->ara)
2018-09-14 18:16:52.596 |   Downloading http://pypi-mirror.novalocal/wheels/pytz/pytz-2018.5-py2.py3-none-any.whl (510kB)
2018-09-14 18:16:52.695 | Collecting funcsigs>=1.0.0; python_version == "2.7" or python_version == "2.6" (from oslo.utils>=3.20.0->ara)
2018-09-14 18:16:52.762 |   Downloading http://pypi-mirror.novalocal/wheels/funcsigs/funcsigs-1.0.2-py2.py3-none-any.whl
2018-09-14 18:16:52.784 | Collecting debtcollector>=1.2.0 (from oslo.utils>=3.20.0->ara)
2018-09-14 18:16:52.910 |   Downloading http://pypi-mirror.novalocal/wheels/debtcollector/debtcollector-1.20.0-py2.py3-none-any.whl
2018-09-14 18:16:52.936 | Collecting monotonic>=0.6 (from oslo.utils>=3.20.0->ara)
2018-09-14 18:16:53.299 |   Downloading http://pypi-mirror.novalocal/wheels/monotonic/monotonic-1.5-py2.py3-none-any.whl
2018-09-14 18:16:53.315 | Collecting msgpack>=0.5.2 (from oslo.serialization>=1.10.0->ara)
2018-09-14 18:16:53.442 |   Downloading http://pypi-mirror.novalocal/wheels/msgpack/msgpack-0.5.6-cp27-cp27mu-linux_ppc64le.whl (272kB)
2018-09-14 18:16:53.467 | Collecting SQLAlchemy>=0.8.0 (from Flask-SQLAlchemy->ara)
2018-09-14 18:16:55.086 |   Downloading http://pypi-mirror.novalocal/wheels/sqlalchemy/SQLAlchemy-1.2.11-cp27-cp27mu-linux_ppc64le.whl (1.1MB)
2018-09-14 18:16:55.174 | Collecting testtools>=0.9.34 (from python-subunit->ara)
2018-09-14 18:16:55.288 |   Downloading http://pypi-mirror.novalocal/wheels/testtools/testtools-2.3.0-py2.py3-none-any.whl (184kB)
2018-09-14 18:16:55.328 | Collecting extras (from python-subunit->ara)
2018-09-14 18:16:55.386 |   Downloading http://pypi-mirror.novalocal/wheels/extras/extras-1.0.0-py2.py3-none-any.whl
2018-09-14 18:16:55.403 | Collecting PrettyTable<0.8,>=0.7.2 (from cliff->ara)
2018-09-14 18:16:55.479 |   Downloading http://pypi-mirror.novalocal/wheels/prettytable/prettytable-0.7.2-py2-none-any.whl
2018-09-14 18:16:55.496 | Collecting unicodecsv>=0.8.0; python_version < "3.0" (from cliff->ara)
2018-09-14 18:16:55.846 |   Downloading http://pypi-mirror.novalocal/wheels/unicodecsv/unicodecsv-0.14.1-py2-none-any.whl
2018-09-14 18:16:55.863 | Collecting stevedore>=1.20.0 (from cliff->ara)
2018-09-14 18:16:56.022 |   Downloading http://pypi-mirror.novalocal/wheels/stevedore/stevedore-1.29.0-py2.py3-none-any.whl
2018-09-14 18:16:56.044 | Collecting pycparser (from cffi!=1.11.3,>=1.7->cryptography->ansible==2.5.7)
2018-09-14 18:16:56.398 |   Downloading http://pypi-mirror.novalocal/wheels/pycparser/pycparser-2.18-py2.py3-none-any.whl (206kB)
2018-09-14 18:16:56.421 | Collecting Mako (from alembic>=0.7->Flask-Migrate->ara)
2018-09-14 18:16:56.526 |   Downloading http://pypi-mirror.novalocal/wheels/mako/Mako-1.0.7-py2-none-any.whl (77kB)
2018-09-14 18:16:56.547 | Collecting python-dateutil (from alembic>=0.7->Flask-Migrate->ara)
2018-09-14 18:16:56.918 |   Downloading http://pypi-mirror.novalocal/wheels/python-dateutil/python_dateutil-2.7.3-py2.py3-none-any.whl (211kB)
2018-09-14 18:16:56.942 | Collecting python-editor>=0.3 (from alembic>=0.7->Flask-Migrate->ara)
2018-09-14 18:16:57.643 |   Downloading http://pypi-mirror.novalocal/wheels/python-editor/python_editor-1.0.3-py2-none-any.whl
2018-09-14 18:16:57.660 | Collecting Babel!=2.4.0,>=2.3.4 (from oslo.i18n>=3.15.3->oslo.utils>=3.20.0->ara)
2018-09-14 18:16:58.398 |   Downloading http://pypi-mirror.novalocal/wheels/babel/Babel-2.6.0-py2.py3-none-any.whl (8.1MB)
2018-09-14 18:16:58.763 | Collecting wrapt>=1.7.0 (from debtcollector>=1.2.0->oslo.utils>=3.20.0->ara)
2018-09-14 18:16:58.855 |   Downloading http://pypi-mirror.novalocal/wheels/wrapt/wrapt-1.10.11-cp27-cp27mu-linux_ppc64le.whl (61kB)
2018-09-14 18:16:58.874 | Collecting unittest2>=1.0.0 (from testtools>=0.9.34->python-subunit->ara)
2018-09-14 18:16:59.554 |   Downloading http://pypi-mirror.novalocal/wheels/unittest2/unittest2-1.1.0-py2.py3-none-any.whl (96kB)
2018-09-14 18:16:59.580 | Collecting fixtures>=1.3.0 (from testtools>=0.9.34->python-subunit->ara)
2018-09-14 18:16:59.669 |   Downloading http://pypi-mirror.novalocal/wheels/fixtures/fixtures-3.0.0-py2.py3-none-any.whl (67kB)
2018-09-14 18:16:59.697 | Collecting python-mimeparse (from testtools>=0.9.34->python-subunit->ara)
2018-09-14 18:17:00.067 |   Downloading http://pypi-mirror.novalocal/wheels/python-mimeparse/python_mimeparse-1.6.0-py2.py3-none-any.whl
2018-09-14 18:17:00.082 | Collecting traceback2 (from testtools>=0.9.34->python-subunit->ara)
2018-09-14 18:17:00.148 |   Downloading http://pypi-mirror.novalocal/wheels/traceback2/traceback2-1.4.0-py2.py3-none-any.whl
2018-09-14 18:17:00.159 | Requirement already satisfied: argparse in /usr/lib/python2.7 (from unittest2>=1.0.0->testtools>=0.9.34->python-subunit->ara) (1.2.1)
2018-09-14 18:17:00.166 | Collecting linecache2 (from traceback2->testtools>=0.9.34->python-subunit->ara)
2018-09-14 18:17:00.222 |   Downloading http://pypi-mirror.novalocal/wheels/linecache2/linecache2-1.0.0-py2.py3-none-any.whl
2018-09-14 18:17:00.502 | Installing collected packages: six, pycparser, cffi, bcrypt, pyasn1, pynacl, idna, enum34, asn1crypto, ipaddress, cryptography, paramiko, PyYAML, MarkupSafe, jinja2, ansible, devstack-tools, Mako, SQLAlchemy, python-dateutil, python-editor, alembic, itsdangerous, click, Werkzeug, Flask, Flask-SQLAlchemy, Flask-Migrate, XStatic-Bootstrap-SCSS, pygments, pyfakefs, Flask-Script, junit-xml, Frozen-Flask, XStatic, iso8601, netaddr, pytz, Babel, oslo.i18n, netifaces, funcsigs, wrapt, debtcollector, monotonic, pyparsing, oslo.utils, XStatic-jQuery, XStatic-Patternfly, XStatic-DataTables, msgpack, oslo.serialization, linecache2, traceback2, unittest2, extras, fixtures, python-mimeparse, testtools, python-subunit, XStatic-Patternfly-Bootstrap-Treeview, contextlib2, pyperclip, wcwidth, subprocess32, cmd2, PrettyTable, unicodecsv, stevedore, cliff, ara
2018-09-14 18:17:09.555 | Successfully installed Babel-2.6.0 Flask-1.0.2 Flask-Migrate-2.2.1 Flask-SQLAlchemy-2.3.2 Flask-Script-2.0.6 Frozen-Flask-0.15 Mako-1.0.7 MarkupSafe-1.0 PrettyTable-0.7.2 PyYAML-3.13 SQLAlchemy-1.2.11 Werkzeug-0.14.1 XStatic-1.0.1 XStatic-Bootstrap-SCSS-3.3.7.1 XStatic-DataTables-1.10.15.1 XStatic-Patternfly-3.21.0.1 XStatic-Patternfly-Bootstrap-Treeview-2.1.3.2 XStatic-jQuery-1.10.2.1 alembic-1.0.0 ansible-2.5.7 ara-0.16.1 asn1crypto-0.24.0 bcrypt-3.1.4 cffi-1.11.5 click-6.7 cliff-2.13.0 cmd2-0.8.9 contextlib2-0.5.5 cryptography-2.3.1 debtcollector-1.20.0 devstack-tools-0.4.0 enum34-1.1.6 extras-1.0.0 fixtures-3.0.0 funcsigs-1.0.2 idna-2.7 ipaddress-1.0.22 iso8601-0.1.12 itsdangerous-0.24 jinja2-2.10 junit-xml-1.8 linecache2-1.0.0 monotonic-1.5 msgpack-0.5.6 netaddr-0.7.19 netifaces-0.10.7 oslo.i18n-3.21.0 oslo.serialization-2.27.0 oslo.utils-3.37.0 paramiko-2.4.1 pyasn1-0.4.4 pycparser-2.18 pyfakefs-3.4.3 pygments-2.2.0 pynacl-1.2.1 pyparsing-2.2.0 pyperclip-1.6.4 python-dateutil-2.7.3 python-editor-1.0.3 python-mimeparse-1.6.0 python-subunit-1.3.0 pytz-2018.5 six-1.11.0 stevedore-1.29.0 subprocess32-3.5.2 testtools-2.3.0 traceback2-1.4.0 unicodecsv-0.14.1 unittest2-1.1.0 wcwidth-0.1.7 wrapt-1.10.11
2018-09-14 18:17:10.548 |  [WARNING]: Could not match supplied host pattern, ignoring: subnodes
2018-09-14 18:17:10.548 |  [WARNING]: No hosts matched, nothing to do
2018-09-14 18:17:12.846 |  [WARNING]: Could not match supplied host pattern, ignoring: subnodes
2018-09-14 18:17:12.846 |  [WARNING]: No hosts matched, nothing to do
2018-09-14 18:17:14.999 | localhost | SUCCESS => {
2018-09-14 18:17:14.999 |     "changed": false, 
2018-09-14 18:17:15.000 |     "path": "/home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/logs", 
2018-09-14 18:17:15.000 |     "state": "absent"
2018-09-14 18:17:15.000 | }
2018-09-14 18:17:16.574 | localhost | SUCCESS => {
2018-09-14 18:17:16.574 |     "changed": true, 
2018-09-14 18:17:16.575 |     "gid": 3001, 
2018-09-14 18:17:16.575 |     "group": "jenkins", 
2018-09-14 18:17:16.576 |     "mode": "0775", 
2018-09-14 18:17:16.576 |     "owner": "jenkins", 
2018-09-14 18:17:16.577 |     "path": "/home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/logs", 
2018-09-14 18:17:16.577 |     "size": 4096, 
2018-09-14 18:17:16.577 |     "state": "directory", 
2018-09-14 18:17:16.578 |     "uid": 3000
2018-09-14 18:17:16.578 | }
2018-09-14 18:17:16.648 | Setting up the hosts
2018-09-14 18:17:16.649 | ... this takes a few seconds (logs at logs/devstack-gate-setup-host.txt.gz)
2018-09-14 18:17:54.809 | Setting up the workspace
2018-09-14 18:17:54.810 | ... this takes 3 - 5 minutes (logs at logs/devstack-gate-setup-workspace-new.txt.gz)
2018-09-14 18:25:15.440 | localhost | SUCCESS | rc=0 >>
2018-09-14 18:25:15.441 | 
2018-09-14 18:25:15.508 | patching file tools/fixup_stuff.sh
2018-09-14 18:25:15.510 | Hunk #1 succeeded at 104 with fuzz 1 (offset -5 lines).
2018-09-14 18:25:15.512 | Job timeout set to: 102 minutes
2018-09-14 18:25:15.524 | Running pre_test_hook
2018-09-14 18:25:15.528 | + /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/devstack-gate/functions.sh:call_hook_if_defined:L82:   tsfilter pre_test_hook
2018-09-14 18:25:15.529 | + /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/devstack-gate/functions.sh:call_hook_if_defined:L82:   tee /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/devstack-gate-pre_test_hook.txt
2018-09-14 18:25:15.530 | + /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/devstack-gate/functions.sh:tsfilter:L101:   awk '
2018-09-14 18:25:15.530 |     {
2018-09-14 18:25:15.531 |         cmd ="date +\"%Y-%m-%d %H:%M:%S.%3N | \""
2018-09-14 18:25:15.531 |         cmd | getline now
2018-09-14 18:25:15.532 |         close("date +\"%Y-%m-%d %H:%M:%S.%3N | \"")
2018-09-14 18:25:15.532 |         sub(/^/, now)
2018-09-14 18:25:15.533 |         print
2018-09-14 18:25:15.533 |         fflush()
2018-09-14 18:25:15.534 |     }'
2018-09-14 18:25:15.535 | + /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/devstack-gate/functions.sh:tsfilter:L93:   pre_test_hook
2018-09-14 18:25:15.536 | 2018-09-14 18:25:15.534 | + :pre_test_hook:L0:   source ibm-devstack-gate/pre_test_hook_ironic.sh
2018-09-14 18:25:15.538 | 2018-09-14 18:25:15.537 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L3:   set -x
2018-09-14 18:25:15.543 | 2018-09-14 18:25:15.542 | ++++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L5:   dirname ibm-devstack-gate/pre_test_hook_ironic.sh
2018-09-14 18:25:15.545 | 2018-09-14 18:25:15.544 | +++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L5:   cd ibm-devstack-gate
2018-09-14 18:25:15.547 | 2018-09-14 18:25:15.546 | +++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L5:   pwd
2018-09-14 18:25:15.549 | 2018-09-14 18:25:15.549 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L5:   THISDIR=/home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/ibm-devstack-gate
2018-09-14 18:25:15.551 | 2018-09-14 18:25:15.551 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L6:   source /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/ibm-devstack-gate/functions
2018-09-14 18:25:15.554 | 2018-09-14 18:25:15.553 | +++ /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/ibm-devstack-gate/functions:source:L4:   source /opt/stack/new/devstack/inc/ini-config
2018-09-14 18:25:15.557 | 2018-09-14 18:25:15.556 | +++++ /opt/stack/new/devstack/inc/ini-config:source:L10:   set +o
2018-09-14 18:25:15.558 | 2018-09-14 18:25:15.558 | +++++ /opt/stack/new/devstack/inc/ini-config:source:L10:   grep xtrace
2018-09-14 18:25:15.561 | 2018-09-14 18:25:15.560 | ++++ /opt/stack/new/devstack/inc/ini-config:source:L10:   INC_CONF_TRACE='set -o xtrace'
2018-09-14 18:25:15.563 | 2018-09-14 18:25:15.562 | ++++ /opt/stack/new/devstack/inc/ini-config:source:L11:   set +o xtrace
2018-09-14 18:25:15.565 | 2018-09-14 18:25:15.564 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L7:   adhoc_fixes
2018-09-14 18:25:15.568 | 2018-09-14 18:25:15.567 | ++ /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/ibm-devstack-gate/functions:adhoc_fixes:L123:   set +e
2018-09-14 18:25:15.570 | 2018-09-14 18:25:15.569 | ++ /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/ibm-devstack-gate/functions:adhoc_fixes:L127:   sp_path=/tmp/ansible/lib/python2.7/site-packages
2018-09-14 18:25:15.574 | 2018-09-14 18:25:15.572 | ++ /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/ibm-devstack-gate/functions:adhoc_fixes:L128:   mkdir -p /tmp/ansible/lib/python2.7/site-packages
2018-09-14 18:25:15.580 | 2018-09-14 18:25:15.578 | ++ /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/ibm-devstack-gate/functions:adhoc_fixes:L129:   ln -s /tmp/ansible/lib/python3.5/site-packages/ara /tmp/ansible/lib/python2.7/site-packages/ara
2018-09-14 18:25:15.585 | 2018-09-14 18:25:15.584 | ++ /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/ibm-devstack-gate/functions:adhoc_fixes:L135:   sudo su -c '(gpg --keyserver keyserver.ubuntu.com --recv-keys 5EDB1B62EC4926EA ; gpg --export --armor 5EDB1B62EC4926EA | apt-key add - )'
2018-09-14 18:25:15.598 | 2018-09-14 18:25:15.598 | gpg: directory `/root/.gnupg' created
2018-09-14 18:25:15.604 | 2018-09-14 18:25:15.603 | gpg: new configuration file `/root/.gnupg/gpg.conf' created
2018-09-14 18:25:15.606 | 2018-09-14 18:25:15.605 | gpg: WARNING: options in `/root/.gnupg/gpg.conf' are not yet active during this run
2018-09-14 18:25:15.608 | 2018-09-14 18:25:15.607 | gpg: keyring `/root/.gnupg/secring.gpg' created
2018-09-14 18:25:15.610 | 2018-09-14 18:25:15.609 | gpg: keyring `/root/.gnupg/pubring.gpg' created
2018-09-14 18:25:15.613 | 2018-09-14 18:25:15.613 | gpg: requesting key EC4926EA from hkp server keyserver.ubuntu.com
2018-09-14 18:25:15.821 | 2018-09-14 18:25:15.821 | gpg: /root/.gnupg/trustdb.gpg: trustdb created
2018-09-14 18:25:15.824 | 2018-09-14 18:25:15.823 | gpg: key EC4926EA: public key "Canonical Cloud Archive Signing Key <ftpmaster@canonical.com>" imported
2018-09-14 18:25:15.826 | 2018-09-14 18:25:15.826 | gpg: Total number processed: 1
2018-09-14 18:25:15.829 | 2018-09-14 18:25:15.828 | gpg:               imported: 1  (RSA: 1)
2018-09-14 18:25:16.028 | 2018-09-14 18:25:16.027 | OK
2018-09-14 18:25:16.033 | 2018-09-14 18:25:16.032 | ++ /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/ibm-devstack-gate/functions:adhoc_fixes:L136:   sudo apt-key update
2018-09-14 18:25:16.142 | 2018-09-14 18:25:16.142 | gpg: key 437D05B5: "Ubuntu Archive Automatic Signing Key <ftpmaster@ubuntu.com>" not changed
2018-09-14 18:25:16.144 | 2018-09-14 18:25:16.144 | gpg: key FBB75451: "Ubuntu CD Image Automatic Signing Key <cdimage@ubuntu.com>" not changed
2018-09-14 18:25:16.146 | 2018-09-14 18:25:16.146 | gpg: key C0B21F32: "Ubuntu Archive Automatic Signing Key (2012) <ftpmaster@ubuntu.com>" not changed
2018-09-14 18:25:16.151 | 2018-09-14 18:25:16.150 | gpg: key EFE21092: "Ubuntu CD Image Automatic Signing Key (2012) <cdimage@ubuntu.com>" not changed
2018-09-14 18:25:16.153 | 2018-09-14 18:25:16.152 | gpg: Total number processed: 4
2018-09-14 18:25:16.154 | 2018-09-14 18:25:16.154 | gpg:              unchanged: 4
2018-09-14 18:25:16.206 | 2018-09-14 18:25:16.206 | ++ /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/ibm-devstack-gate/functions:adhoc_fixes:L137:   sudo apt-get update
2018-09-14 18:25:16.360 | 2018-09-14 18:25:16.360 | Hit:1 http://ports.ubuntu.com/ubuntu-ports xenial InRelease
2018-09-14 18:25:16.460 | 2018-09-14 18:25:16.459 | Hit:2 http://ports.ubuntu.com/ubuntu-ports xenial-updates InRelease
2018-09-14 18:25:16.551 | 2018-09-14 18:25:16.550 | Hit:3 http://ports.ubuntu.com/ubuntu-ports xenial-backports InRelease
2018-09-14 18:25:16.641 | 2018-09-14 18:25:16.640 | Hit:4 http://ports.ubuntu.com/ubuntu-ports xenial-security InRelease
2018-09-14 18:25:17.552 | 2018-09-14 18:25:17.551 | Reading package lists...
2018-09-14 18:25:17.586 | 2018-09-14 18:25:17.585 | ++ /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/ibm-devstack-gate/functions:adhoc_fixes:L138:   '[' ironic '!=' ironic ']'
2018-09-14 18:25:17.588 | 2018-09-14 18:25:17.587 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L9:   localconf=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.590 | 2018-09-14 18:25:17.590 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L13:   echo 'Our uid, etc is:'
2018-09-14 18:25:17.592 | 2018-09-14 18:25:17.592 | Our uid, etc is:
2018-09-14 18:25:17.594 | 2018-09-14 18:25:17.594 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L14:   id
2018-09-14 18:25:17.597 | 2018-09-14 18:25:17.597 | uid=3000(jenkins) gid=3001(jenkins) groups=3001(jenkins)
2018-09-14 18:25:17.600 | 2018-09-14 18:25:17.599 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L16:   localconf_set /opt/stack/new/devstack/local.conf post-config /etc/ironic/ironic.conf ipmi retry_timeout 150
2018-09-14 18:25:17.602 | 2018-09-14 18:25:17.602 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.603 | 2018-09-14 18:25:17.603 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.606 | 2018-09-14 18:25:17.606 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.608 | 2018-09-14 18:25:17.608 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.610 | 2018-09-14 18:25:17.610 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.612 | 2018-09-14 18:25:17.612 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L17:   localconf_set /opt/stack/new/devstack/local.conf post-config /etc/ironic/ironic.conf deploy erase_devices_metadata_priority 10
2018-09-14 18:25:17.615 | 2018-09-14 18:25:17.614 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.618 | 2018-09-14 18:25:17.618 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.620 | 2018-09-14 18:25:17.620 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.622 | 2018-09-14 18:25:17.622 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.624 | 2018-09-14 18:25:17.624 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.628 | 2018-09-14 18:25:17.628 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L18:   localconf_set /opt/stack/new/devstack/local.conf post-config /etc/neutron/neutron.conf DEFAULT service_plugins neutron.services.metering.metering_plugin.MeteringPlugin
2018-09-14 18:25:17.630 | 2018-09-14 18:25:17.630 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.633 | 2018-09-14 18:25:17.633 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.637 | 2018-09-14 18:25:17.637 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.639 | 2018-09-14 18:25:17.639 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.642 | 2018-09-14 18:25:17.641 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.644 | 2018-09-14 18:25:17.644 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L19:   localconf_set /opt/stack/new/devstack/local.conf test-config /opt/stack/new/tempest/etc/tempest.conf baremetal power_timeout 3600
2018-09-14 18:25:17.646 | 2018-09-14 18:25:17.646 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.648 | 2018-09-14 18:25:17.648 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.651 | 2018-09-14 18:25:17.650 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.653 | 2018-09-14 18:25:17.652 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.655 | 2018-09-14 18:25:17.655 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.658 | 2018-09-14 18:25:17.657 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L20:   localconf_set /opt/stack/new/devstack/local.conf test-config /opt/stack/new/tempest/etc/tempest.conf compute fixed_network_name public
2018-09-14 18:25:17.660 | 2018-09-14 18:25:17.660 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.662 | 2018-09-14 18:25:17.662 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.666 | 2018-09-14 18:25:17.665 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.668 | 2018-09-14 18:25:17.668 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.670 | 2018-09-14 18:25:17.670 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.672 | 2018-09-14 18:25:17.672 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L33:   IRONIC_HWINFO_FILE=/opt/stack/new/devstack/files/hardware_info
2018-09-14 18:25:17.675 | 2018-09-14 18:25:17.675 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L34:   IRONIC_IMAGE_NAME=centos7-ppc64le
2018-09-14 18:25:17.677 | 2018-09-14 18:25:17.677 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L36:   localrc_set /opt/stack/new/devstack/local.conf FORCE yes
2018-09-14 18:25:17.679 | 2018-09-14 18:25:17.679 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.681 | 2018-09-14 18:25:17.681 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:17.684 | 2018-09-14 18:25:17.684 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:17.687 | 2018-09-14 18:25:17.687 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:17.689 | 2018-09-14 18:25:17.689 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=FORCE
2018-09-14 18:25:17.691 | 2018-09-14 18:25:17.691 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=yes
2018-09-14 18:25:17.693 | 2018-09-14 18:25:17.693 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' FORCE yes
2018-09-14 18:25:17.694 | 2018-09-14 18:25:17.694 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.699 | 2018-09-14 18:25:17.699 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.702 | 2018-09-14 18:25:17.702 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.705 | 2018-09-14 18:25:17.705 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.707 | 2018-09-14 18:25:17.707 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.711 | 2018-09-14 18:25:17.710 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L37:   localrc_set /opt/stack/new/devstack/local.conf HOST_IP 9.114.112.113
2018-09-14 18:25:17.713 | 2018-09-14 18:25:17.712 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.715 | 2018-09-14 18:25:17.714 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:17.716 | 2018-09-14 18:25:17.716 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:17.718 | 2018-09-14 18:25:17.718 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:17.720 | 2018-09-14 18:25:17.720 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=HOST_IP
2018-09-14 18:25:17.722 | 2018-09-14 18:25:17.722 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=9.114.112.113
2018-09-14 18:25:17.724 | 2018-09-14 18:25:17.724 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' HOST_IP 9.114.112.113
2018-09-14 18:25:17.725 | 2018-09-14 18:25:17.725 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.729 | 2018-09-14 18:25:17.729 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.731 | 2018-09-14 18:25:17.731 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.733 | 2018-09-14 18:25:17.732 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.735 | 2018-09-14 18:25:17.735 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.737 | 2018-09-14 18:25:17.737 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L39:   localrc_set /opt/stack/new/devstack/local.conf DEFAULT_INSTANCE_TYPE baremetal
2018-09-14 18:25:17.740 | 2018-09-14 18:25:17.739 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.743 | 2018-09-14 18:25:17.742 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:17.745 | 2018-09-14 18:25:17.745 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:17.747 | 2018-09-14 18:25:17.746 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:17.749 | 2018-09-14 18:25:17.749 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=DEFAULT_INSTANCE_TYPE
2018-09-14 18:25:17.751 | 2018-09-14 18:25:17.750 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=baremetal
2018-09-14 18:25:17.753 | 2018-09-14 18:25:17.753 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' DEFAULT_INSTANCE_TYPE baremetal
2018-09-14 18:25:17.755 | 2018-09-14 18:25:17.755 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.759 | 2018-09-14 18:25:17.758 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.761 | 2018-09-14 18:25:17.760 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.763 | 2018-09-14 18:25:17.763 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.765 | 2018-09-14 18:25:17.765 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.767 | 2018-09-14 18:25:17.767 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L41:   localrc_set /opt/stack/new/devstack/local.conf IRONIC_DEPLOY_DRIVER ipmi
2018-09-14 18:25:17.770 | 2018-09-14 18:25:17.770 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.774 | 2018-09-14 18:25:17.774 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:17.777 | 2018-09-14 18:25:17.776 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:17.778 | 2018-09-14 18:25:17.778 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:17.781 | 2018-09-14 18:25:17.780 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=IRONIC_DEPLOY_DRIVER
2018-09-14 18:25:17.783 | 2018-09-14 18:25:17.782 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=ipmi
2018-09-14 18:25:17.785 | 2018-09-14 18:25:17.785 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' IRONIC_DEPLOY_DRIVER ipmi
2018-09-14 18:25:17.787 | 2018-09-14 18:25:17.787 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.789 | 2018-09-14 18:25:17.788 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.792 | 2018-09-14 18:25:17.791 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.794 | 2018-09-14 18:25:17.794 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.796 | 2018-09-14 18:25:17.795 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.799 | 2018-09-14 18:25:17.798 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L42:   localrc_set /opt/stack/new/devstack/local.conf IRONIC_CLEAN_NET_NAME public
2018-09-14 18:25:17.800 | 2018-09-14 18:25:17.800 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.802 | 2018-09-14 18:25:17.802 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:17.804 | 2018-09-14 18:25:17.804 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:17.806 | 2018-09-14 18:25:17.805 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:17.807 | 2018-09-14 18:25:17.807 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=IRONIC_CLEAN_NET_NAME
2018-09-14 18:25:17.809 | 2018-09-14 18:25:17.809 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=public
2018-09-14 18:25:17.811 | 2018-09-14 18:25:17.811 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' IRONIC_CLEAN_NET_NAME public
2018-09-14 18:25:17.812 | 2018-09-14 18:25:17.812 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.814 | 2018-09-14 18:25:17.814 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.816 | 2018-09-14 18:25:17.816 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.820 | 2018-09-14 18:25:17.820 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.822 | 2018-09-14 18:25:17.822 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.825 | 2018-09-14 18:25:17.824 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L43:   localrc_set /opt/stack/new/devstack/local.conf IRONIC_AUTOMATED_CLEAN_ENABLED True
2018-09-14 18:25:17.827 | 2018-09-14 18:25:17.827 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.829 | 2018-09-14 18:25:17.829 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:17.832 | 2018-09-14 18:25:17.832 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:17.834 | 2018-09-14 18:25:17.833 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:17.835 | 2018-09-14 18:25:17.835 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=IRONIC_AUTOMATED_CLEAN_ENABLED
2018-09-14 18:25:17.837 | 2018-09-14 18:25:17.837 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=True
2018-09-14 18:25:17.839 | 2018-09-14 18:25:17.839 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' IRONIC_AUTOMATED_CLEAN_ENABLED True
2018-09-14 18:25:17.840 | 2018-09-14 18:25:17.840 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.842 | 2018-09-14 18:25:17.842 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.844 | 2018-09-14 18:25:17.844 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.846 | 2018-09-14 18:25:17.845 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.849 | 2018-09-14 18:25:17.849 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.852 | 2018-09-14 18:25:17.851 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L44:   localrc_set /opt/stack/new/devstack/local.conf IRONIC_DEPLOY_LOGS_COLLECT never
2018-09-14 18:25:17.853 | 2018-09-14 18:25:17.853 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.855 | 2018-09-14 18:25:17.855 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:17.857 | 2018-09-14 18:25:17.856 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:17.859 | 2018-09-14 18:25:17.858 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:17.860 | 2018-09-14 18:25:17.860 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=IRONIC_DEPLOY_LOGS_COLLECT
2018-09-14 18:25:17.862 | 2018-09-14 18:25:17.862 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=never
2018-09-14 18:25:17.864 | 2018-09-14 18:25:17.863 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' IRONIC_DEPLOY_LOGS_COLLECT never
2018-09-14 18:25:17.865 | 2018-09-14 18:25:17.865 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.867 | 2018-09-14 18:25:17.867 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.870 | 2018-09-14 18:25:17.869 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.871 | 2018-09-14 18:25:17.871 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.875 | 2018-09-14 18:25:17.874 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.877 | 2018-09-14 18:25:17.876 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L46:   localrc_set /opt/stack/new/devstack/local.conf PROVIDER_NETWORK_TYPE flat
2018-09-14 18:25:17.878 | 2018-09-14 18:25:17.878 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.881 | 2018-09-14 18:25:17.880 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:17.882 | 2018-09-14 18:25:17.882 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:17.884 | 2018-09-14 18:25:17.884 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:17.885 | 2018-09-14 18:25:17.885 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=PROVIDER_NETWORK_TYPE
2018-09-14 18:25:17.887 | 2018-09-14 18:25:17.887 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=flat
2018-09-14 18:25:17.889 | 2018-09-14 18:25:17.889 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' PROVIDER_NETWORK_TYPE flat
2018-09-14 18:25:17.890 | 2018-09-14 18:25:17.890 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.892 | 2018-09-14 18:25:17.892 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.894 | 2018-09-14 18:25:17.893 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.896 | 2018-09-14 18:25:17.895 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.897 | 2018-09-14 18:25:17.897 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.899 | 2018-09-14 18:25:17.898 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L47:   localrc_set /opt/stack/new/devstack/local.conf Q_USE_PROVIDER_NETWORKING True
2018-09-14 18:25:17.900 | 2018-09-14 18:25:17.900 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.902 | 2018-09-14 18:25:17.902 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:17.904 | 2018-09-14 18:25:17.903 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:17.905 | 2018-09-14 18:25:17.905 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:17.907 | 2018-09-14 18:25:17.907 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=Q_USE_PROVIDER_NETWORKING
2018-09-14 18:25:17.909 | 2018-09-14 18:25:17.908 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=True
2018-09-14 18:25:17.910 | 2018-09-14 18:25:17.910 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' Q_USE_PROVIDER_NETWORKING True
2018-09-14 18:25:17.912 | 2018-09-14 18:25:17.912 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.914 | 2018-09-14 18:25:17.913 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.915 | 2018-09-14 18:25:17.915 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.917 | 2018-09-14 18:25:17.917 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.918 | 2018-09-14 18:25:17.918 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.920 | 2018-09-14 18:25:17.920 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L48:   localrc_set /opt/stack/new/devstack/local.conf Q_L3_ENABLED False
2018-09-14 18:25:17.922 | 2018-09-14 18:25:17.922 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.923 | 2018-09-14 18:25:17.923 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:17.925 | 2018-09-14 18:25:17.925 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:17.927 | 2018-09-14 18:25:17.927 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:17.928 | 2018-09-14 18:25:17.928 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=Q_L3_ENABLED
2018-09-14 18:25:17.930 | 2018-09-14 18:25:17.930 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=False
2018-09-14 18:25:17.932 | 2018-09-14 18:25:17.931 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' Q_L3_ENABLED False
2018-09-14 18:25:17.933 | 2018-09-14 18:25:17.933 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.935 | 2018-09-14 18:25:17.935 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.937 | 2018-09-14 18:25:17.936 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.938 | 2018-09-14 18:25:17.938 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.940 | 2018-09-14 18:25:17.940 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.941 | 2018-09-14 18:25:17.941 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L49:   localrc_set /opt/stack/new/devstack/local.conf IP_VERSION 4
2018-09-14 18:25:17.943 | 2018-09-14 18:25:17.943 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.945 | 2018-09-14 18:25:17.945 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:17.946 | 2018-09-14 18:25:17.946 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:17.948 | 2018-09-14 18:25:17.948 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:17.950 | 2018-09-14 18:25:17.949 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=IP_VERSION
2018-09-14 18:25:17.951 | 2018-09-14 18:25:17.951 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=4
2018-09-14 18:25:17.953 | 2018-09-14 18:25:17.953 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' IP_VERSION 4
2018-09-14 18:25:17.955 | 2018-09-14 18:25:17.954 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.956 | 2018-09-14 18:25:17.956 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.958 | 2018-09-14 18:25:17.958 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.959 | 2018-09-14 18:25:17.959 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.961 | 2018-09-14 18:25:17.961 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.963 | 2018-09-14 18:25:17.963 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L50:   localrc_set /opt/stack/new/devstack/local.conf OVS_PHYSICAL_BRIDGE br0
2018-09-14 18:25:17.964 | 2018-09-14 18:25:17.964 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.966 | 2018-09-14 18:25:17.966 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:17.968 | 2018-09-14 18:25:17.967 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:17.969 | 2018-09-14 18:25:17.969 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:17.971 | 2018-09-14 18:25:17.971 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=OVS_PHYSICAL_BRIDGE
2018-09-14 18:25:17.972 | 2018-09-14 18:25:17.972 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=br0
2018-09-14 18:25:17.974 | 2018-09-14 18:25:17.974 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' OVS_PHYSICAL_BRIDGE br0
2018-09-14 18:25:17.976 | 2018-09-14 18:25:17.976 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.977 | 2018-09-14 18:25:17.977 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:17.979 | 2018-09-14 18:25:17.979 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:17.981 | 2018-09-14 18:25:17.981 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:17.982 | 2018-09-14 18:25:17.982 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:17.984 | 2018-09-14 18:25:17.984 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L51:   localrc_set /opt/stack/new/devstack/local.conf ENABLE_TENANT_TUNNELS False
2018-09-14 18:25:17.986 | 2018-09-14 18:25:17.985 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:17.987 | 2018-09-14 18:25:17.987 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:17.989 | 2018-09-14 18:25:17.989 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:17.991 | 2018-09-14 18:25:17.990 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:17.992 | 2018-09-14 18:25:17.992 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=ENABLE_TENANT_TUNNELS
2018-09-14 18:25:17.994 | 2018-09-14 18:25:17.994 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=False
2018-09-14 18:25:17.996 | 2018-09-14 18:25:17.995 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' ENABLE_TENANT_TUNNELS False
2018-09-14 18:25:17.997 | 2018-09-14 18:25:17.997 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:17.999 | 2018-09-14 18:25:17.999 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:18.000 | 2018-09-14 18:25:18.000 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:18.002 | 2018-09-14 18:25:18.002 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:18.004 | 2018-09-14 18:25:18.004 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:18.005 | 2018-09-14 18:25:18.005 | +++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L52:   sed 's/.* dev \(.*\)/\1/g'
2018-09-14 18:25:18.007 | 2018-09-14 18:25:18.007 | +++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L52:   ip route
2018-09-14 18:25:18.009 | 2018-09-14 18:25:18.008 | +++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L52:   awk '{print $1}'
2018-09-14 18:25:18.010 | 2018-09-14 18:25:18.010 | +++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L52:   grep '^default'
2018-09-14 18:25:18.012 | 2018-09-14 18:25:18.012 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L52:   localrc_set /opt/stack/new/devstack/local.conf PUBLIC_INTERFACE enp0s1
2018-09-14 18:25:18.013 | 2018-09-14 18:25:18.013 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:18.015 | 2018-09-14 18:25:18.015 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:18.017 | 2018-09-14 18:25:18.017 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:18.018 | 2018-09-14 18:25:18.018 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:18.020 | 2018-09-14 18:25:18.020 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=PUBLIC_INTERFACE
2018-09-14 18:25:18.022 | 2018-09-14 18:25:18.021 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=enp0s1
2018-09-14 18:25:18.023 | 2018-09-14 18:25:18.023 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' PUBLIC_INTERFACE enp0s1
2018-09-14 18:25:18.025 | 2018-09-14 18:25:18.025 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:18.027 | 2018-09-14 18:25:18.026 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:18.028 | 2018-09-14 18:25:18.028 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:18.030 | 2018-09-14 18:25:18.030 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:18.031 | 2018-09-14 18:25:18.031 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:18.033 | 2018-09-14 18:25:18.033 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L53:   localrc_set /opt/stack/new/devstack/local.conf USE_SUBNETPOOL False
2018-09-14 18:25:18.035 | 2018-09-14 18:25:18.035 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:18.036 | 2018-09-14 18:25:18.036 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:18.038 | 2018-09-14 18:25:18.038 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:18.040 | 2018-09-14 18:25:18.039 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:18.041 | 2018-09-14 18:25:18.041 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=USE_SUBNETPOOL
2018-09-14 18:25:18.043 | 2018-09-14 18:25:18.043 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=False
2018-09-14 18:25:18.045 | 2018-09-14 18:25:18.044 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' USE_SUBNETPOOL False
2018-09-14 18:25:18.046 | 2018-09-14 18:25:18.046 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:18.048 | 2018-09-14 18:25:18.048 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:18.049 | 2018-09-14 18:25:18.049 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:18.051 | 2018-09-14 18:25:18.051 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:18.053 | 2018-09-14 18:25:18.053 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:18.055 | 2018-09-14 18:25:18.054 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L54:   /tmp/ansible/bin/dsconf setlc /opt/stack/new/devstack/local.conf ML2_L3_PLUGIN ''
2018-09-14 18:25:18.126 | 2018-09-14 18:25:18.125 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L56:   localrc_set /opt/stack/new/devstack/local.conf PRECACHE_IMAGES False
2018-09-14 18:25:18.128 | 2018-09-14 18:25:18.128 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:18.131 | 2018-09-14 18:25:18.130 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:18.133 | 2018-09-14 18:25:18.132 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:18.136 | 2018-09-14 18:25:18.135 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:18.138 | 2018-09-14 18:25:18.138 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=PRECACHE_IMAGES
2018-09-14 18:25:18.141 | 2018-09-14 18:25:18.140 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=False
2018-09-14 18:25:18.142 | 2018-09-14 18:25:18.142 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' PRECACHE_IMAGES False
2018-09-14 18:25:18.144 | 2018-09-14 18:25:18.144 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:18.146 | 2018-09-14 18:25:18.145 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:18.148 | 2018-09-14 18:25:18.147 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:18.150 | 2018-09-14 18:25:18.149 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:18.152 | 2018-09-14 18:25:18.151 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:18.153 | 2018-09-14 18:25:18.153 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L58:   localrc_set /opt/stack/new/devstack/local.conf IMAGE_URLS+ ,http://ironic-image-server.pok.stglabs.ibm.com/images/centos7-ppc64le.tgz
2018-09-14 18:25:18.157 | 2018-09-14 18:25:18.157 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:18.159 | 2018-09-14 18:25:18.158 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:18.161 | 2018-09-14 18:25:18.160 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:18.162 | 2018-09-14 18:25:18.162 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:18.165 | 2018-09-14 18:25:18.164 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=IMAGE_URLS+
2018-09-14 18:25:18.166 | 2018-09-14 18:25:18.166 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=,http://ironic-image-server.pok.stglabs.ibm.com/images/centos7-ppc64le.tgz
2018-09-14 18:25:18.168 | 2018-09-14 18:25:18.168 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' IMAGE_URLS+ ,http://ironic-image-server.pok.stglabs.ibm.com/images/centos7-ppc64le.tgz
2018-09-14 18:25:18.171 | 2018-09-14 18:25:18.171 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:18.173 | 2018-09-14 18:25:18.172 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:18.175 | 2018-09-14 18:25:18.175 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:18.178 | 2018-09-14 18:25:18.177 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:18.180 | 2018-09-14 18:25:18.179 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:18.181 | 2018-09-14 18:25:18.181 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L59:   localrc_set /opt/stack/new/devstack/local.conf IRONIC_IMAGE_NAME centos7-ppc64le
2018-09-14 18:25:18.183 | 2018-09-14 18:25:18.183 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:18.186 | 2018-09-14 18:25:18.185 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:18.188 | 2018-09-14 18:25:18.187 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:18.190 | 2018-09-14 18:25:18.190 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:18.193 | 2018-09-14 18:25:18.192 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=IRONIC_IMAGE_NAME
2018-09-14 18:25:18.196 | 2018-09-14 18:25:18.195 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=centos7-ppc64le
2018-09-14 18:25:18.197 | 2018-09-14 18:25:18.197 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' IRONIC_IMAGE_NAME centos7-ppc64le
2018-09-14 18:25:18.201 | 2018-09-14 18:25:18.201 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:18.203 | 2018-09-14 18:25:18.203 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:18.206 | 2018-09-14 18:25:18.205 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:18.208 | 2018-09-14 18:25:18.207 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:18.210 | 2018-09-14 18:25:18.210 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:18.213 | 2018-09-14 18:25:18.213 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L62:   localrc_set /opt/stack/new/devstack/local.conf IRONIC_IS_HARDWARE True
2018-09-14 18:25:18.216 | 2018-09-14 18:25:18.215 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:18.218 | 2018-09-14 18:25:18.217 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:18.221 | 2018-09-14 18:25:18.220 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:18.224 | 2018-09-14 18:25:18.223 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:18.226 | 2018-09-14 18:25:18.226 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=IRONIC_IS_HARDWARE
2018-09-14 18:25:18.229 | 2018-09-14 18:25:18.228 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=True
2018-09-14 18:25:18.231 | 2018-09-14 18:25:18.230 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' IRONIC_IS_HARDWARE True
2018-09-14 18:25:18.233 | 2018-09-14 18:25:18.232 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:18.235 | 2018-09-14 18:25:18.235 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:18.239 | 2018-09-14 18:25:18.239 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:18.247 | 2018-09-14 18:25:18.246 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:18.251 | 2018-09-14 18:25:18.250 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:18.253 | 2018-09-14 18:25:18.252 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L64:   localrc_set /opt/stack/new/devstack/local.conf IRONIC_IPXE_ENABLED False
2018-09-14 18:25:18.254 | 2018-09-14 18:25:18.254 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:18.259 | 2018-09-14 18:25:18.256 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:18.259 | 2018-09-14 18:25:18.258 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:18.260 | 2018-09-14 18:25:18.260 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:18.262 | 2018-09-14 18:25:18.262 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=IRONIC_IPXE_ENABLED
2018-09-14 18:25:18.264 | 2018-09-14 18:25:18.264 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=False
2018-09-14 18:25:18.266 | 2018-09-14 18:25:18.266 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' IRONIC_IPXE_ENABLED False
2018-09-14 18:25:18.268 | 2018-09-14 18:25:18.267 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:18.270 | 2018-09-14 18:25:18.269 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:18.272 | 2018-09-14 18:25:18.271 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:18.274 | 2018-09-14 18:25:18.273 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:18.276 | 2018-09-14 18:25:18.275 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:18.278 | 2018-09-14 18:25:18.277 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L68:   localrc_set /opt/stack/new/devstack/local.conf DEFAULT_INSTANCE_USER root
2018-09-14 18:25:18.280 | 2018-09-14 18:25:18.279 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:18.281 | 2018-09-14 18:25:18.281 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:18.283 | 2018-09-14 18:25:18.283 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:18.285 | 2018-09-14 18:25:18.285 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:18.287 | 2018-09-14 18:25:18.287 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=DEFAULT_INSTANCE_USER
2018-09-14 18:25:18.289 | 2018-09-14 18:25:18.288 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=root
2018-09-14 18:25:18.291 | 2018-09-14 18:25:18.290 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' DEFAULT_INSTANCE_USER root
2018-09-14 18:25:18.292 | 2018-09-14 18:25:18.292 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:18.294 | 2018-09-14 18:25:18.294 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:18.296 | 2018-09-14 18:25:18.296 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:18.299 | 2018-09-14 18:25:18.299 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:18.301 | 2018-09-14 18:25:18.300 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:18.302 | 2018-09-14 18:25:18.302 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L70:   localrc_set /opt/stack/new/devstack/local.conf SWIFT_TEMPURL_KEY secretkey
2018-09-14 18:25:18.304 | 2018-09-14 18:25:18.304 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:18.306 | 2018-09-14 18:25:18.306 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:18.308 | 2018-09-14 18:25:18.308 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:18.310 | 2018-09-14 18:25:18.309 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:18.311 | 2018-09-14 18:25:18.311 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=SWIFT_TEMPURL_KEY
2018-09-14 18:25:18.313 | 2018-09-14 18:25:18.313 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=secretkey
2018-09-14 18:25:18.315 | 2018-09-14 18:25:18.315 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' SWIFT_TEMPURL_KEY secretkey
2018-09-14 18:25:18.317 | 2018-09-14 18:25:18.317 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:18.319 | 2018-09-14 18:25:18.318 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:18.321 | 2018-09-14 18:25:18.320 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:18.322 | 2018-09-14 18:25:18.322 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:18.324 | 2018-09-14 18:25:18.324 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:18.326 | 2018-09-14 18:25:18.326 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L77:   localrc_set /opt/stack/new/devstack/local.conf IRONIC_HWINFO_FILE /opt/stack/new/devstack/files/hardware_info
2018-09-14 18:25:18.328 | 2018-09-14 18:25:18.328 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L279:   local file=/opt/stack/new/devstack/local.conf
2018-09-14 18:25:18.330 | 2018-09-14 18:25:18.329 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L280:   local group=local
2018-09-14 18:25:18.331 | 2018-09-14 18:25:18.331 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L281:   local conf=localrc
2018-09-14 18:25:18.333 | 2018-09-14 18:25:18.333 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L282:   local section=
2018-09-14 18:25:18.335 | 2018-09-14 18:25:18.335 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L283:   local option=IRONIC_HWINFO_FILE
2018-09-14 18:25:18.337 | 2018-09-14 18:25:18.337 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L284:   local value=/opt/stack/new/devstack/files/hardware_info
2018-09-14 18:25:18.339 | 2018-09-14 18:25:18.338 | ++ /opt/stack/new/devstack/inc/ini-config:localrc_set:L285:   localconf_set /opt/stack/new/devstack/local.conf local localrc '' IRONIC_HWINFO_FILE /opt/stack/new/devstack/files/hardware_info
2018-09-14 18:25:18.340 | 2018-09-14 18:25:18.340 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L389:   local xtrace
2018-09-14 18:25:18.342 | 2018-09-14 18:25:18.342 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   set +o
2018-09-14 18:25:18.344 | 2018-09-14 18:25:18.344 | +++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   grep xtrace
2018-09-14 18:25:18.346 | 2018-09-14 18:25:18.346 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L390:   xtrace='set -o xtrace'
2018-09-14 18:25:18.348 | 2018-09-14 18:25:18.347 | ++ /opt/stack/new/devstack/inc/ini-config:localconf_set:L391:   set +o xtrace
2018-09-14 18:25:18.349 | 2018-09-14 18:25:18.349 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L80:   sudo apt-get install -y python-yaml
2018-09-14 18:25:18.351 | 2018-09-14 18:25:18.351 | Reading package lists...
2018-09-14 18:25:18.442 | 2018-09-14 18:25:18.442 | Building dependency tree...
2018-09-14 18:25:18.444 | 2018-09-14 18:25:18.444 | Reading state information...
2018-09-14 18:25:18.568 | 2018-09-14 18:25:18.567 | The following NEW packages will be installed:
2018-09-14 18:25:18.570 | 2018-09-14 18:25:18.569 |   python-yaml
2018-09-14 18:25:18.639 | 2018-09-14 18:25:18.638 | 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded.
2018-09-14 18:25:18.641 | 2018-09-14 18:25:18.641 | Need to get 95.5 kB of archives.
2018-09-14 18:25:18.643 | 2018-09-14 18:25:18.643 | After this operation, 539 kB of additional disk space will be used.
2018-09-14 18:25:18.645 | 2018-09-14 18:25:18.645 | Get:1 http://ports.ubuntu.com/ubuntu-ports xenial/main ppc64el python-yaml ppc64el 3.11-3build1 [95.5 kB]
2018-09-14 18:25:18.761 | 2018-09-14 18:25:18.761 | debconf: unable to initialize frontend: Dialog
2018-09-14 18:25:18.764 | 2018-09-14 18:25:18.763 | debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.)
2018-09-14 18:25:18.768 | 2018-09-14 18:25:18.766 | debconf: falling back to frontend: Readline
2018-09-14 18:25:18.773 | 2018-09-14 18:25:18.772 | debconf: unable to initialize frontend: Readline
2018-09-14 18:25:18.775 | 2018-09-14 18:25:18.774 | debconf: (This frontend requires a controlling tty.)
2018-09-14 18:25:18.777 | 2018-09-14 18:25:18.777 | debconf: falling back to frontend: Teletype
2018-09-14 18:25:18.779 | 2018-09-14 18:25:18.778 | dpkg-preconfigure: unable to re-open stdin: 
2018-09-14 18:25:18.815 | 2018-09-14 18:25:18.815 | Fetched 95.5 kB in 0s (1743 kB/s)
2018-09-14 18:25:18.940 | 2018-09-14 18:25:18.939 | Selecting previously unselected package python-yaml.
2018-09-14 18:25:19.060 | 2018-09-14 18:25:19.056 | (Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 82027 files and directories currently installed.)
2018-09-14 18:25:19.061 | 2018-09-14 18:25:19.060 | Preparing to unpack .../python-yaml_3.11-3build1_ppc64el.deb ...
2018-09-14 18:25:19.108 | 2018-09-14 18:25:19.102 | Unpacking python-yaml (3.11-3build1) ...
2018-09-14 18:25:19.343 | 2018-09-14 18:25:19.343 | Setting up python-yaml (3.11-3build1) ...
2018-09-14 18:25:20.550 | 2018-09-14 18:25:20.549 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L81:   git clone https://git.openstack.org/openstack/molteniron
2018-09-14 18:25:20.552 | 2018-09-14 18:25:20.551 | Cloning into 'molteniron'...
2018-09-14 18:25:21.388 | 2018-09-14 18:25:21.387 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L82:   cd molteniron
2018-09-14 18:25:21.389 | 2018-09-14 18:25:21.389 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L83:   git checkout -q e6c7435
2018-09-14 18:25:21.396 | 2018-09-14 18:25:21.396 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L87:   [[ zuul == c2* ]]
2018-09-14 18:25:21.398 | 2018-09-14 18:25:21.398 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L90:   miserver=molteniron
2018-09-14 18:25:21.400 | 2018-09-14 18:25:21.400 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L93:   echo -e 'serverIP: molteniron\nmi_port: 5656'
2018-09-14 18:25:21.402 | 2018-09-14 18:25:21.402 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L95:   dsvm_uuid=36215a7a-2be1-43b2-87d8-5e446910491a
2018-09-14 18:25:21.404 | 2018-09-14 18:25:21.404 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L96:   MI_CMD='./molteniron/molteniron -c . allocate 36215a7a-2be1-43b2-87d8-5e446910491a 1'
2018-09-14 18:25:21.406 | 2018-09-14 18:25:21.405 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L98:   set +o xtrace
2018-09-14 18:25:21.930 | 2018-09-14 18:25:21.930 | ++ ibm-devstack-gate/pre_test_hook_ironic.sh:source:L108:   checkout_patch_series http://review.openstack.org/openstack-dev/devstack.git /opt/stack/new/devstack '
2018-09-14 18:25:21.932 | 2018-09-14 18:25:21.932 | refs/heads/master
2018-09-14 18:25:21.935 | 2018-09-14 18:25:21.935 | https://review.openstack.org/#/c/400950/  [WIP] Force OVS brige to have MAC of slave port
2018-09-14 18:25:21.939 | 2018-09-14 18:25:21.938 | '
2018-09-14 18:25:21.943 | 2018-09-14 18:25:21.943 | ++ /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/ibm-devstack-gate/functions:checkout_patch_series:L32:   set +x
2018-09-14 18:25:21.945 | 2018-09-14 18:25:21.945 | 
2018-09-14 18:25:21.947 | 2018-09-14 18:25:21.947 | ========================
2018-09-14 18:25:21.949 | 2018-09-14 18:25:21.949 | Applying patch series to /opt/stack/new/devstack
2018-09-14 18:25:21.951 | 2018-09-14 18:25:21.951 | -------> checkout refs/heads/master
2018-09-14 18:25:23.300 | 2018-09-14 18:25:23.299 | From http://review.openstack.org/openstack-dev/devstack
2018-09-14 18:25:23.301 | 2018-09-14 18:25:23.301 |  * branch            master     -> FETCH_HEAD
2018-09-14 18:25:23.323 | 2018-09-14 18:25:23.323 | -------> cherry-pick https://review.openstack.org/#/c/400950/  [WIP] Force OVS brige to have MAC of slave port
2018-09-14 18:25:26.780 | 2018-09-14 18:25:26.779 | From http://review.openstack.org/openstack-dev/devstack
2018-09-14 18:25:26.782 | 2018-09-14 18:25:26.782 |  * branch            refs/changes/50/400950/2 -> FETCH_HEAD
2018-09-14 18:25:26.887 | 2018-09-14 18:25:26.887 | [detached HEAD d6dc1ac] [WIP] Force OVS brige to have MAC of slave port
2018-09-14 18:25:26.889 | 2018-09-14 18:25:26.889 |  Author: Michael Turek <mjturek@us.ibm.com>
2018-09-14 18:25:26.891 | 2018-09-14 18:25:26.890 |  Date: Tue Nov 22 14:53:51 2016 -0500
2018-09-14 18:25:26.892 | 2018-09-14 18:25:26.892 |  1 file changed, 3 insertions(+), 1 deletion(-)
2018-09-14 18:25:26.894 | 2018-09-14 18:25:26.894 | --------------------------
2018-09-14 18:25:26.895 | 2018-09-14 18:25:26.895 | Resulting git tree:
2018-09-14 18:25:27.045 | 2018-09-14 18:25:27.044 | * d6dc1ac (HEAD) [WIP] Force OVS brige to have MAC of slave port
2018-09-14 18:25:27.047 | 2018-09-14 18:25:27.047 | *   66ca7f5 (origin/master, origin/HEAD, master) Merge "Remove master only job"
2018-09-14 18:25:27.049 | 2018-09-14 18:25:27.049 | |\  
2018-09-14 18:25:27.051 | 2018-09-14 18:25:27.051 | | * 6403b14 Remove master only job
2018-09-14 18:25:27.053 | 2018-09-14 18:25:27.053 | * | b4b6789 Follow job renames
2018-09-14 18:25:27.055 | 2018-09-14 18:25:27.055 | |/  
2018-09-14 18:25:27.057 | 2018-09-14 18:25:27.057 | * 53db72c switch documentation job to new PTI
2018-09-14 18:25:27.060 | 2018-09-14 18:25:27.058 | 
2018-09-14 18:25:27.060 | + /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/devstack-gate/functions.sh:tsfilter:L103:   return 0
2018-09-14 18:25:27.061 | + /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/devstack-gate/functions.sh:call_hook_if_defined:L83:   local ret_val=0
2018-09-14 18:25:27.062 | + /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/devstack-gate/functions.sh:call_hook_if_defined:L84:   sudo mv /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/devstack-gate-pre_test_hook.txt /opt/stack/logs/
2018-09-14 18:25:27.071 | + /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/devstack-gate/functions.sh:call_hook_if_defined:L85:   set +o pipefail
2018-09-14 18:25:27.071 | + /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/devstack-gate/functions.sh:call_hook_if_defined:L86:   set +o xtrace
2018-09-14 18:25:27.072 | Running gate_hook
2018-09-14 18:25:27.074 | Job timeout set to: 101 minutes
2018-09-14 18:25:27.085 | +++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L26:   dirname /opt/stack/new/devstack-gate/devstack-vm-gate.sh
2018-09-14 18:25:27.087 | ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L26:   cd /opt/stack/new/devstack-gate
2018-09-14 18:25:27.088 | ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L26:   pwd
2018-09-14 18:25:27.088 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L26:   TOP_DIR=/opt/stack/new/devstack-gate
2018-09-14 18:25:27.089 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L32:   source /opt/stack/new/devstack-gate/functions.sh
2018-09-14 18:25:27.090 | ++ /opt/stack/new/devstack-gate/functions.sh:source:L19:   SUDO=sudo
2018-09-14 18:25:27.094 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L37:   source /opt/stack/new/devstack/inc/ini-config
2018-09-14 18:25:27.095 | +++ /opt/stack/new/devstack/inc/ini-config:source:L10:   set +o
2018-09-14 18:25:27.096 | +++ /opt/stack/new/devstack/inc/ini-config:source:L10:   grep xtrace
2018-09-14 18:25:27.098 | ++ /opt/stack/new/devstack/inc/ini-config:source:L10:   INC_CONF_TRACE='set -o xtrace'
2018-09-14 18:25:27.098 | ++ /opt/stack/new/devstack/inc/ini-config:source:L11:   set +o xtrace
2018-09-14 18:25:27.099 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L47:   echo 13281
2018-09-14 18:25:27.100 | +++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L48:   readlink -f /opt/stack/new/devstack-gate/devstack-vm-gate.sh
2018-09-14 18:25:27.102 | ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L48:   dirname /opt/stack/new/devstack-gate/devstack-vm-gate.sh
2018-09-14 18:25:27.104 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L48:   source /opt/stack/new/devstack-gate/functions.sh
2018-09-14 18:25:27.104 | ++ /opt/stack/new/devstack-gate/functions.sh:source:L19:   SUDO=sudo
2018-09-14 18:25:27.110 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L51:   FIXED_RANGE=9.114.112.0/24
2018-09-14 18:25:27.111 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L52:   IPV4_ADDRS_SAFE_TO_USE=9.114.112.0/24
2018-09-14 18:25:27.112 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L53:   FLOATING_RANGE=172.24.5.0/24
2018-09-14 18:25:27.113 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L54:   PUBLIC_NETWORK_GATEWAY=172.24.5.1
2018-09-14 18:25:27.114 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L66:   FLOATING_HOST_PREFIX=172.24.4
2018-09-14 18:25:27.114 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L67:   FLOATING_HOST_MASK=23
2018-09-14 18:25:27.115 | ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L70:   ip link show
2018-09-14 18:25:27.116 | ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L70:   head -1
2018-09-14 18:25:27.117 | ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L70:   sort -n
2018-09-14 18:25:27.117 | ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L70:   sed -ne 's/.*mtu \([0-9]\+\).*/\1/p'
2018-09-14 18:25:27.118 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L70:   LOCAL_MTU=1500
2018-09-14 18:25:27.119 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L73:   EXTERNAL_BRIDGE_MTU=1450
2018-09-14 18:25:27.123 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L664:   [[ -n '' ]]
2018-09-14 18:25:27.123 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L729:   cd /opt/stack/new/devstack
2018-09-14 18:25:27.124 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L730:   setup_localrc new local.conf primary
2018-09-14 18:25:27.125 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L265:   local localrc_oldnew=new
2018-09-14 18:25:27.125 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L266:   local localrc_file=local.conf
2018-09-14 18:25:27.126 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L267:   local role=primary
2018-09-14 18:25:27.126 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L273:   local branch_for_matrix=master
2018-09-14 18:25:27.127 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L277:   [[ -z 1 ]]
2018-09-14 18:25:27.127 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L282:   [[ ! -z ceilometer-acentral,ceilometer-acompute,ceilometer-alarm-evaluator,ceilometer-alarm-notifier,ceilometer-anotification,ceilometer-api,ceilometer-collector,dstat,g-api,g-reg,ironic,ir-api,ir-cond,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,placement-api,q-agt,q-dhcp,q-meta,q-metering,q-svc,quantum,rabbit,s-account,s-container,s-object,s-proxy,tempest ]]
2018-09-14 18:25:27.128 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L283:   MY_ENABLED_SERVICES=ceilometer-acentral,ceilometer-acompute,ceilometer-alarm-evaluator,ceilometer-alarm-notifier,ceilometer-anotification,ceilometer-api,ceilometer-collector,dstat,g-api,g-reg,ironic,ir-api,ir-cond,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,placement-api,q-agt,q-dhcp,q-meta,q-metering,q-svc,quantum,rabbit,s-account,s-container,s-object,s-proxy,tempest
2018-09-14 18:25:27.128 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L328:   [[ ! -z '' ]]
2018-09-14 18:25:27.129 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L332:   [[ '' == \1 ]]
2018-09-14 18:25:27.129 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L338:   SKIP_EXERCISES=boot_from_volume,bundle,client-env,euca
2018-09-14 18:25:27.130 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L340:   [[ 1 -eq 1 ]]
2018-09-14 18:25:27.130 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L341:   localrc_set local.conf Q_USE_DEBUG_COMMAND True
2018-09-14 18:25:27.130 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:27.131 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=Q_USE_DEBUG_COMMAND
2018-09-14 18:25:27.132 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
2018-09-14 18:25:27.132 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf Q_USE_DEBUG_COMMAND True
2018-09-14 18:25:27.332 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L342:   localrc_set local.conf NETWORK_GATEWAY 10.1.0.1
2018-09-14 18:25:27.332 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:27.333 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=NETWORK_GATEWAY
2018-09-14 18:25:27.334 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=10.1.0.1
2018-09-14 18:25:27.335 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf NETWORK_GATEWAY 10.1.0.1
2018-09-14 18:25:27.538 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L345:   [[ 0 -eq 1 ]]
2018-09-14 18:25:27.539 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L355:   localrc_set local.conf USE_SCREEN False
2018-09-14 18:25:27.540 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:27.540 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=USE_SCREEN
2018-09-14 18:25:27.541 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
2018-09-14 18:25:27.541 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf USE_SCREEN False
2018-09-14 18:25:27.749 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L356:   localrc_set local.conf DEST /opt/stack/new
2018-09-14 18:25:27.749 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:27.750 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=DEST
2018-09-14 18:25:27.751 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=/opt/stack/new
2018-09-14 18:25:27.751 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf DEST /opt/stack/new
2018-09-14 18:25:27.956 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L358:   localrc_set local.conf DATA_DIR /opt/stack/data
2018-09-14 18:25:27.957 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:27.958 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=DATA_DIR
2018-09-14 18:25:27.958 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=/opt/stack/data
2018-09-14 18:25:27.959 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf DATA_DIR /opt/stack/data
2018-09-14 18:25:28.163 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L359:   localrc_set local.conf ACTIVE_TIMEOUT 90
2018-09-14 18:25:28.164 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:28.164 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=ACTIVE_TIMEOUT
2018-09-14 18:25:28.165 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=90
2018-09-14 18:25:28.166 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf ACTIVE_TIMEOUT 90
2018-09-14 18:25:28.369 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L360:   localrc_set local.conf BOOT_TIMEOUT 90
2018-09-14 18:25:28.370 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:28.370 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=BOOT_TIMEOUT
2018-09-14 18:25:28.370 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=90
2018-09-14 18:25:28.371 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf BOOT_TIMEOUT 90
2018-09-14 18:25:28.579 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L361:   localrc_set local.conf ASSOCIATE_TIMEOUT 60
2018-09-14 18:25:28.579 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:28.579 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=ASSOCIATE_TIMEOUT
2018-09-14 18:25:28.580 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=60
2018-09-14 18:25:28.580 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf ASSOCIATE_TIMEOUT 60
2018-09-14 18:25:28.786 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L362:   localrc_set local.conf TERMINATE_TIMEOUT 60
2018-09-14 18:25:28.787 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:28.787 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=TERMINATE_TIMEOUT
2018-09-14 18:25:28.788 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=60
2018-09-14 18:25:28.788 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf TERMINATE_TIMEOUT 60
2018-09-14 18:25:28.996 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L363:   localrc_set local.conf MYSQL_PASSWORD secretmysql
2018-09-14 18:25:28.997 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:28.997 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=MYSQL_PASSWORD
2018-09-14 18:25:28.997 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=secretmysql
2018-09-14 18:25:28.998 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf MYSQL_PASSWORD secretmysql
2018-09-14 18:25:29.202 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L364:   localrc_set local.conf DATABASE_PASSWORD secretdatabase
2018-09-14 18:25:29.203 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:29.203 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=DATABASE_PASSWORD
2018-09-14 18:25:29.203 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=secretdatabase
2018-09-14 18:25:29.204 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf DATABASE_PASSWORD secretdatabase
2018-09-14 18:25:29.412 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L365:   localrc_set local.conf RABBIT_PASSWORD secretrabbit
2018-09-14 18:25:29.412 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:29.413 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=RABBIT_PASSWORD
2018-09-14 18:25:29.413 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=secretrabbit
2018-09-14 18:25:29.413 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf RABBIT_PASSWORD secretrabbit
2018-09-14 18:25:29.618 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L366:   localrc_set local.conf ADMIN_PASSWORD secretadmin
2018-09-14 18:25:29.619 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:29.619 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=ADMIN_PASSWORD
2018-09-14 18:25:29.619 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=secretadmin
2018-09-14 18:25:29.620 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf ADMIN_PASSWORD secretadmin
2018-09-14 18:25:29.829 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L367:   localrc_set local.conf SERVICE_PASSWORD secretservice
2018-09-14 18:25:29.830 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:29.830 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SERVICE_PASSWORD
2018-09-14 18:25:29.831 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=secretservice
2018-09-14 18:25:29.832 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SERVICE_PASSWORD secretservice
2018-09-14 18:25:30.033 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L368:   localrc_set local.conf SERVICE_TOKEN 111222333444
2018-09-14 18:25:30.033 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:30.034 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SERVICE_TOKEN
2018-09-14 18:25:30.034 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=111222333444
2018-09-14 18:25:30.034 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SERVICE_TOKEN 111222333444
2018-09-14 18:25:30.240 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L369:   localrc_set local.conf SWIFT_HASH 1234123412341234
2018-09-14 18:25:30.241 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:30.242 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SWIFT_HASH
2018-09-14 18:25:30.243 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=1234123412341234
2018-09-14 18:25:30.243 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SWIFT_HASH 1234123412341234
2018-09-14 18:25:30.446 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L370:   localrc_set local.conf ROOTSLEEP 0
2018-09-14 18:25:30.447 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:30.447 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=ROOTSLEEP
2018-09-14 18:25:30.447 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=0
2018-09-14 18:25:30.448 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf ROOTSLEEP 0
2018-09-14 18:25:30.654 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L376:   localrc_set local.conf ERROR_ON_CLONE True
2018-09-14 18:25:30.654 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:30.654 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=ERROR_ON_CLONE
2018-09-14 18:25:30.655 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
2018-09-14 18:25:30.655 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf ERROR_ON_CLONE True
2018-09-14 18:25:30.863 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L383:   localrc_set local.conf INSTALL_TEMPEST False
2018-09-14 18:25:30.863 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:30.863 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=INSTALL_TEMPEST
2018-09-14 18:25:30.864 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
2018-09-14 18:25:30.864 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf INSTALL_TEMPEST False
2018-09-14 18:25:31.072 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L385:   localrc_set local.conf NOVNC_FROM_PACKAGE True
2018-09-14 18:25:31.073 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:31.073 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=NOVNC_FROM_PACKAGE
2018-09-14 18:25:31.073 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
2018-09-14 18:25:31.074 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf NOVNC_FROM_PACKAGE True
2018-09-14 18:25:31.280 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L386:   localrc_set local.conf ENABLED_SERVICES ceilometer-acentral,ceilometer-acompute,ceilometer-alarm-evaluator,ceilometer-alarm-notifier,ceilometer-anotification,ceilometer-api,ceilometer-collector,dstat,g-api,g-reg,ironic,ir-api,ir-cond,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,placement-api,q-agt,q-dhcp,q-meta,q-metering,q-svc,quantum,rabbit,s-account,s-container,s-object,s-proxy,tempest
2018-09-14 18:25:31.280 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:31.280 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=ENABLED_SERVICES
2018-09-14 18:25:31.280 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=ceilometer-acentral,ceilometer-acompute,ceilometer-alarm-evaluator,ceilometer-alarm-notifier,ceilometer-anotification,ceilometer-api,ceilometer-collector,dstat,g-api,g-reg,ironic,ir-api,ir-cond,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,placement-api,q-agt,q-dhcp,q-meta,q-metering,q-svc,quantum,rabbit,s-account,s-container,s-object,s-proxy,tempest
2018-09-14 18:25:31.281 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf ENABLED_SERVICES ceilometer-acentral,ceilometer-acompute,ceilometer-alarm-evaluator,ceilometer-alarm-notifier,ceilometer-anotification,ceilometer-api,ceilometer-collector,dstat,g-api,g-reg,ironic,ir-api,ir-cond,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,placement-api,q-agt,q-dhcp,q-meta,q-metering,q-svc,quantum,rabbit,s-account,s-container,s-object,s-proxy,tempest
2018-09-14 18:25:31.486 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L387:   localrc_set local.conf SKIP_EXERCISES boot_from_volume,bundle,client-env,euca
2018-09-14 18:25:31.489 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:31.489 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SKIP_EXERCISES
2018-09-14 18:25:31.489 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=boot_from_volume,bundle,client-env,euca
2018-09-14 18:25:31.489 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SKIP_EXERCISES boot_from_volume,bundle,client-env,euca
2018-09-14 18:25:31.742 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L389:   localrc_set local.conf SYSLOG False
2018-09-14 18:25:31.742 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:31.742 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SYSLOG
2018-09-14 18:25:31.743 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
2018-09-14 18:25:31.743 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SYSLOG False
2018-09-14 18:25:31.950 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L390:   localrc_set local.conf SCREEN_LOGDIR /opt/stack/new/screen-logs
2018-09-14 18:25:31.951 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:31.951 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SCREEN_LOGDIR
2018-09-14 18:25:31.951 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=/opt/stack/new/screen-logs
2018-09-14 18:25:31.951 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SCREEN_LOGDIR /opt/stack/new/screen-logs
2018-09-14 18:25:32.156 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L391:   localrc_set local.conf LOGFILE /opt/stack/new/devstacklog.txt
2018-09-14 18:25:32.157 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:32.157 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=LOGFILE
2018-09-14 18:25:32.157 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=/opt/stack/new/devstacklog.txt
2018-09-14 18:25:32.158 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf LOGFILE /opt/stack/new/devstacklog.txt
2018-09-14 18:25:32.363 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L392:   localrc_set local.conf VERBOSE True
2018-09-14 18:25:32.364 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:32.364 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=VERBOSE
2018-09-14 18:25:32.364 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
2018-09-14 18:25:32.365 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf VERBOSE True
2018-09-14 18:25:32.571 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L393:   localrc_set local.conf FIXED_RANGE 9.114.112.0/24
2018-09-14 18:25:32.571 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:32.571 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=FIXED_RANGE
2018-09-14 18:25:32.572 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=9.114.112.0/24
2018-09-14 18:25:32.572 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf FIXED_RANGE 9.114.112.0/24
2018-09-14 18:25:32.779 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L394:   localrc_set local.conf IPV4_ADDRS_SAFE_TO_USE 9.114.112.0/24
2018-09-14 18:25:32.779 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:32.780 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=IPV4_ADDRS_SAFE_TO_USE
2018-09-14 18:25:32.780 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=9.114.112.0/24
2018-09-14 18:25:32.780 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf IPV4_ADDRS_SAFE_TO_USE 9.114.112.0/24
2018-09-14 18:25:32.987 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L395:   localrc_set local.conf FLOATING_RANGE 172.24.5.0/24
2018-09-14 18:25:32.987 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:32.987 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=FLOATING_RANGE
2018-09-14 18:25:32.988 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=172.24.5.0/24
2018-09-14 18:25:32.988 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf FLOATING_RANGE 172.24.5.0/24
2018-09-14 18:25:33.195 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L396:   localrc_set local.conf PUBLIC_NETWORK_GATEWAY 172.24.5.1
2018-09-14 18:25:33.195 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:33.196 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=PUBLIC_NETWORK_GATEWAY
2018-09-14 18:25:33.196 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=172.24.5.1
2018-09-14 18:25:33.197 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf PUBLIC_NETWORK_GATEWAY 172.24.5.1
2018-09-14 18:25:33.402 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L397:   localrc_set local.conf FIXED_NETWORK_SIZE 4096
2018-09-14 18:25:33.403 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:33.403 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=FIXED_NETWORK_SIZE
2018-09-14 18:25:33.404 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=4096
2018-09-14 18:25:33.406 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf FIXED_NETWORK_SIZE 4096
2018-09-14 18:25:33.609 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L398:   localrc_set local.conf VIRT_DRIVER ironic
2018-09-14 18:25:33.610 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:33.611 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=VIRT_DRIVER
2018-09-14 18:25:33.612 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=ironic
2018-09-14 18:25:33.613 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf VIRT_DRIVER ironic
2018-09-14 18:25:33.819 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L399:   localrc_set local.conf SWIFT_REPLICAS 1
2018-09-14 18:25:33.820 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:33.821 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SWIFT_REPLICAS
2018-09-14 18:25:33.822 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=1
2018-09-14 18:25:33.823 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SWIFT_REPLICAS 1
2018-09-14 18:25:34.029 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L400:   localrc_set local.conf SWIFT_START_ALL_SERVICES False
2018-09-14 18:25:34.030 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:34.031 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SWIFT_START_ALL_SERVICES
2018-09-14 18:25:34.031 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
2018-09-14 18:25:34.032 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SWIFT_START_ALL_SERVICES False
2018-09-14 18:25:34.236 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L401:   localrc_set local.conf LOG_COLOR False
2018-09-14 18:25:34.237 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:34.237 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=LOG_COLOR
2018-09-14 18:25:34.238 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
2018-09-14 18:25:34.239 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf LOG_COLOR False
2018-09-14 18:25:34.443 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L403:   localrc_set local.conf UNDO_REQUIREMENTS False
2018-09-14 18:25:34.444 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:34.445 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=UNDO_REQUIREMENTS
2018-09-14 18:25:34.446 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
2018-09-14 18:25:34.447 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf UNDO_REQUIREMENTS False
2018-09-14 18:25:34.652 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L404:   localrc_set local.conf CINDER_PERIODIC_INTERVAL 10
2018-09-14 18:25:34.653 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:34.653 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=CINDER_PERIODIC_INTERVAL
2018-09-14 18:25:34.654 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=10
2018-09-14 18:25:34.654 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf CINDER_PERIODIC_INTERVAL 10
2018-09-14 18:25:34.862 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L406:   localrc_set local.conf 'export OS_NO_CACHE' True
2018-09-14 18:25:34.863 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:34.863 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local 'key=export OS_NO_CACHE'
2018-09-14 18:25:34.864 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
2018-09-14 18:25:34.864 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf 'export OS_NO_CACHE' True
2018-09-14 18:25:35.072 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L407:   localrc_set local.conf LIBS_FROM_GIT ''
2018-09-14 18:25:35.073 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:35.073 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=LIBS_FROM_GIT
2018-09-14 18:25:35.074 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=
2018-09-14 18:25:35.075 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf LIBS_FROM_GIT ''
2018-09-14 18:25:35.279 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L411:   localrc_set local.conf EBTABLES_RACE_FIX True
2018-09-14 18:25:35.280 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:35.281 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=EBTABLES_RACE_FIX
2018-09-14 18:25:35.282 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
2018-09-14 18:25:35.282 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf EBTABLES_RACE_FIX True
2018-09-14 18:25:35.486 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L414:   localrc_set local.conf DEBUG_LIBVIRT_COREDUMPS True
2018-09-14 18:25:35.487 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:35.487 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=DEBUG_LIBVIRT_COREDUMPS
2018-09-14 18:25:35.488 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
2018-09-14 18:25:35.488 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf DEBUG_LIBVIRT_COREDUMPS True
2018-09-14 18:25:35.696 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L416:   [[ aio == \m\u\l\t\i\n\o\d\e ]]
2018-09-14 18:25:35.697 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L421:   localrc_set local.conf CINDER_VOLUME_CLEAR none
2018-09-14 18:25:35.697 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:35.697 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=CINDER_VOLUME_CLEAR
2018-09-14 18:25:35.698 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=none
2018-09-14 18:25:35.698 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf CINDER_VOLUME_CLEAR none
2018-09-14 18:25:35.903 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L423:   [[ 0 -eq 1 ]]
2018-09-14 18:25:35.903 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L429:   [[ ironic == \l\i\b\v\i\r\t ]]
2018-09-14 18:25:35.904 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L435:   [[ ironic == \i\r\o\n\i\c ]]
2018-09-14 18:25:35.904 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L436:   export TEMPEST_OS_TEST_TIMEOUT=1800
2018-09-14 18:25:35.905 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L436:   TEMPEST_OS_TEST_TIMEOUT=1800
2018-09-14 18:25:35.905 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L437:   localrc_set local.conf IRONIC_DEPLOY_DRIVER ipmi
2018-09-14 18:25:35.906 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:35.907 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=IRONIC_DEPLOY_DRIVER
2018-09-14 18:25:35.908 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=ipmi
2018-09-14 18:25:35.908 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf IRONIC_DEPLOY_DRIVER ipmi
2018-09-14 18:25:36.115 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L438:   localrc_set local.conf IRONIC_BAREMETAL_BASIC_OPS True
2018-09-14 18:25:36.116 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:36.116 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=IRONIC_BAREMETAL_BASIC_OPS
2018-09-14 18:25:36.117 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
2018-09-14 18:25:36.118 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf IRONIC_BAREMETAL_BASIC_OPS True
2018-09-14 18:25:36.327 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L439:   localrc_set local.conf IRONIC_VM_LOG_DIR /opt/stack/new/ironic-bm-logs
2018-09-14 18:25:36.328 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:36.329 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=IRONIC_VM_LOG_DIR
2018-09-14 18:25:36.330 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=/opt/stack/new/ironic-bm-logs
2018-09-14 18:25:36.330 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf IRONIC_VM_LOG_DIR /opt/stack/new/ironic-bm-logs
2018-09-14 18:25:36.532 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L440:   localrc_set local.conf DEFAULT_INSTANCE_TYPE baremetal
2018-09-14 18:25:36.536 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:36.536 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=DEFAULT_INSTANCE_TYPE
2018-09-14 18:25:36.537 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=baremetal
2018-09-14 18:25:36.537 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf DEFAULT_INSTANCE_TYPE baremetal
2018-09-14 18:25:36.827 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L441:   localrc_set local.conf BUILD_TIMEOUT 600
2018-09-14 18:25:36.827 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:36.827 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=BUILD_TIMEOUT
2018-09-14 18:25:36.828 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=600
2018-09-14 18:25:36.828 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf BUILD_TIMEOUT 600
2018-09-14 18:25:37.037 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L442:   localrc_set local.conf IRONIC_CALLBACK_TIMEOUT 600
2018-09-14 18:25:37.038 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:37.038 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=IRONIC_CALLBACK_TIMEOUT
2018-09-14 18:25:37.039 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=600
2018-09-14 18:25:37.039 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf IRONIC_CALLBACK_TIMEOUT 600
2018-09-14 18:25:37.247 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L443:   localrc_set local.conf Q_AGENT openvswitch
2018-09-14 18:25:37.248 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:37.249 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=Q_AGENT
2018-09-14 18:25:37.249 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=openvswitch
2018-09-14 18:25:37.250 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf Q_AGENT openvswitch
2018-09-14 18:25:37.455 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L444:   localrc_set local.conf Q_ML2_TENANT_NETWORK_TYPE vxlan
2018-09-14 18:25:37.455 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:37.456 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=Q_ML2_TENANT_NETWORK_TYPE
2018-09-14 18:25:37.456 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=vxlan
2018-09-14 18:25:37.457 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf Q_ML2_TENANT_NETWORK_TYPE vxlan
2018-09-14 18:25:37.662 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L445:   [[ 0 -eq 0 ]]
2018-09-14 18:25:37.663 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L446:   localrc_set local.conf IRONIC_BUILD_DEPLOY_RAMDISK False
2018-09-14 18:25:37.663 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:37.664 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=IRONIC_BUILD_DEPLOY_RAMDISK
2018-09-14 18:25:37.665 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
2018-09-14 18:25:37.665 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf IRONIC_BUILD_DEPLOY_RAMDISK False
2018-09-14 18:25:37.871 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L450:   [[ -z ipmi ]]
2018-09-14 18:25:37.871 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L459:   localrc_set local.conf IRONIC_ENABLED_DRIVERS fake,pxe_ipmitool
2018-09-14 18:25:37.871 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:37.872 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=IRONIC_ENABLED_DRIVERS
2018-09-14 18:25:37.872 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=fake,pxe_ipmitool
2018-09-14 18:25:37.872 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf IRONIC_ENABLED_DRIVERS fake,pxe_ipmitool
2018-09-14 18:25:38.081 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L460:   localrc_set local.conf IRONIC_VM_EPHEMERAL_DISK 1
2018-09-14 18:25:38.081 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:38.081 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=IRONIC_VM_EPHEMERAL_DISK
2018-09-14 18:25:38.081 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=1
2018-09-14 18:25:38.081 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf IRONIC_VM_EPHEMERAL_DISK 1
2018-09-14 18:25:38.288 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L464:   [[ ironic == \x\e\n\a\p\i ]]
2018-09-14 18:25:38.289 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L507:   [[ 1 -eq 1 ]]
2018-09-14 18:25:38.289 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L514:   localrc_set local.conf VOLUME_BACKING_FILE_SIZE 24G
2018-09-14 18:25:38.290 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:38.290 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=VOLUME_BACKING_FILE_SIZE
2018-09-14 18:25:38.291 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=24G
2018-09-14 18:25:38.291 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf VOLUME_BACKING_FILE_SIZE 24G
2018-09-14 18:25:38.495 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L518:   localrc_set local.conf TEMPEST_HTTP_IMAGE http://git.openstack.org/static/openstack.png
2018-09-14 18:25:38.495 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:38.495 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=TEMPEST_HTTP_IMAGE
2018-09-14 18:25:38.496 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=http://git.openstack.org/static/openstack.png
2018-09-14 18:25:38.496 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf TEMPEST_HTTP_IMAGE http://git.openstack.org/static/openstack.png
2018-09-14 18:25:38.703 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L521:   [[ 0 -eq 1 ]]
2018-09-14 18:25:38.703 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L525:   [[ -n '' ]]
2018-09-14 18:25:38.703 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L534:   [[ 0 -eq 1 ]]
2018-09-14 18:25:38.703 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L538:   [[ 0 -gt 1 ]]
2018-09-14 18:25:38.704 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L547:   [[ 1 -eq 1 ]]
2018-09-14 18:25:38.704 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L548:   localrc_set local.conf FORCE_CONFIG_DRIVE True
2018-09-14 18:25:38.704 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
2018-09-14 18:25:38.704 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=FORCE_CONFIG_DRIVE
2018-09-14 18:25:38.704 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
2018-09-14 18:25:38.704 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf FORCE_CONFIG_DRIVE True
2018-09-14 18:25:38.911 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L553:   [[ -n '' ]]
2018-09-14 18:25:38.911 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L559:   [[ 0 -eq 1 ]]
2018-09-14 18:25:38.912 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L564:   [[ aio != \a\i\o ]]
2018-09-14 18:25:38.912 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L611:   [[ -e /tmp/dg-local.conf ]]
2018-09-14 18:25:38.912 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L622:   [[ primary = sub ]]
2018-09-14 18:25:38.913 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L633:   [[ -n 
2018-09-14 18:25:38.913 | IRONIC_DEPLOY_DRIVER_ISCSI_WITH_IPA=True
2018-09-14 18:25:38.914 | IRONIC_AGENT_RAMDISK_URL=http://ironic-image-server.pok.stglabs.ibm.com/images/ironic-agent-centos.initramfs
2018-09-14 18:25:38.914 | IRONIC_AGENT_KERNEL_URL=http://ironic-image-server.pok.stglabs.ibm.com/images/ironic-agent-centos.vmlinuz
2018-09-14 18:25:38.915 | DEFAULT_IMAGE_NAME=centos7-ppc64le
2018-09-14 18:25:38.915 | NETWORK_GATEWAY=9.114.112.254
2018-09-14 18:25:38.916 | IRONIC_VM_COUNT=0
2018-09-14 18:25:38.916 | Q_ML2_TENANT_NETWORK_TYPE=flat
2018-09-14 18:25:38.917 | IRONIC_CALLBACK_TIMEOUT=3600
2018-09-14 18:25:38.917 | BUILD_TIMEOUT=3600
2018-09-14 18:25:38.918 | enable_plugin ironic git://git.openstack.org/openstack/ironic
2018-09-14 18:25:38.918 | TEMPEST_SSH_CONNECT_METHOD=fixed
2018-09-14 18:25:38.919 | SWIFT_ENABLE_TEMPURLS=True
2018-09-14 18:25:38.919 | TEMPEST_PLUGINS=/opt/stack/new/ironic-tempest-plugin
2018-09-14 18:25:38.920 | grep -q -v primary /etc/nodepool/role || enable_plugin devstack-plugin-mariadb git://github.com/openstack/devstack-plugin-mariadb
2018-09-14 18:25:38.920 | ETCD_DOWNLOAD_URL=http://aptcache.novalocal/etcd
2018-09-14 18:25:38.921 | ETCD_VERSION=v3.2.0
2018-09-14 18:25:38.922 | ETCD_SHA256=2466ec19d41290ccb15616c3b021e2e4edc7efa669c6b65f41da1d8c0a2a0fbc ]]
2018-09-14 18:25:38.922 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L634:   /tmp/ansible/bin/dsconf setlc_raw local.conf '
2018-09-14 18:25:38.923 | IRONIC_DEPLOY_DRIVER_ISCSI_WITH_IPA=True
2018-09-14 18:25:38.923 | IRONIC_AGENT_RAMDISK_URL=http://ironic-image-server.pok.stglabs.ibm.com/images/ironic-agent-centos.initramfs
2018-09-14 18:25:38.924 | IRONIC_AGENT_KERNEL_URL=http://ironic-image-server.pok.stglabs.ibm.com/images/ironic-agent-centos.vmlinuz
2018-09-14 18:25:38.924 | DEFAULT_IMAGE_NAME=centos7-ppc64le
2018-09-14 18:25:38.925 | NETWORK_GATEWAY=9.114.112.254
2018-09-14 18:25:38.925 | IRONIC_VM_COUNT=0
2018-09-14 18:25:38.926 | Q_ML2_TENANT_NETWORK_TYPE=flat
2018-09-14 18:25:38.926 | IRONIC_CALLBACK_TIMEOUT=3600
2018-09-14 18:25:38.927 | BUILD_TIMEOUT=3600
2018-09-14 18:25:38.927 | enable_plugin ironic git://git.openstack.org/openstack/ironic
2018-09-14 18:25:38.927 | TEMPEST_SSH_CONNECT_METHOD=fixed
2018-09-14 18:25:38.927 | SWIFT_ENABLE_TEMPURLS=True
2018-09-14 18:25:38.928 | TEMPEST_PLUGINS=/opt/stack/new/ironic-tempest-plugin
2018-09-14 18:25:38.928 | grep -q -v primary /etc/nodepool/role || enable_plugin devstack-plugin-mariadb git://github.com/openstack/devstack-plugin-mariadb
2018-09-14 18:25:38.928 | ETCD_DOWNLOAD_URL=http://aptcache.novalocal/etcd
2018-09-14 18:25:38.928 | ETCD_VERSION=v3.2.0
2018-09-14 18:25:38.928 | ETCD_SHA256=2466ec19d41290ccb15616c3b021e2e4edc7efa669c6b65f41da1d8c0a2a0fbc'
2018-09-14 18:25:39.121 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L643:   [[ -n '' ]]
2018-09-14 18:25:39.122 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L731:   [[ aio == \m\u\l\t\i\n\o\d\e ]]
2018-09-14 18:25:39.122 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L738:   setup_networking
2018-09-14 18:25:39.122 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_networking:L232:   local mode=devstack
2018-09-14 18:25:39.122 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_networking:L235:   [[ aio != \m\u\l\t\i\n\o\d\e ]]
2018-09-14 18:25:39.122 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_networking:L236:   [[ 1 -ne 1 ]]
2018-09-14 18:25:39.122 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_networking:L243:   [[ aio == \m\u\l\t\i\n\o\d\e ]]
2018-09-14 18:25:39.123 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L740:   setup_access_for_stack_user
2018-09-14 18:25:39.124 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_access_for_stack_user:L657:   /tmp/ansible/bin/ansible all --sudo -f 5 -i /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/inventory -m shell -a 'chown -R stack:stack '\''/opt/stack'\'''
2018-09-14 18:25:39.653 | [DEPRECATION WARNING]: The sudo command line option has been deprecated in 
2018-09-14 18:25:39.654 | favor of the "become" command line arguments. This feature will be removed in 
2018-09-14 18:25:39.654 | version 2.6. Deprecation warnings can be disabled by setting 
2018-09-14 18:25:39.655 | deprecation_warnings=False in ansible.cfg.
2018-09-14 18:25:41.746 |  [WARNING]: Consider using the file module with owner rather than running
2018-09-14 18:25:41.754 | chown.  If you need to use command because file is insufficient you can add
2018-09-14 18:25:41.754 | warn=False to this command task or set command_warnings=False in ansible.cfg to
2018-09-14 18:25:41.755 | get rid of this message.
2018-09-14 18:25:41.755 | localhost | SUCCESS | rc=0 >>
2018-09-14 18:25:41.756 | 
2018-09-14 18:25:41.797 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_access_for_stack_user:L660:   /tmp/ansible/bin/ansible all --sudo -f 5 -i /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/inventory -m shell -a 'chmod 777 '\''/home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/logs'\'''
2018-09-14 18:25:42.332 | [DEPRECATION WARNING]: The sudo command line option has been deprecated in 
2018-09-14 18:25:42.332 | favor of the "become" command line arguments. This feature will be removed in 
2018-09-14 18:25:42.332 | version 2.6. Deprecation warnings can be disabled by setting 
2018-09-14 18:25:42.332 | deprecation_warnings=False in ansible.cfg.
2018-09-14 18:25:43.990 |  [WARNING]: Consider using the file module with mode rather than running chmod.
2018-09-14 18:25:43.990 | If you need to use command because file is insufficient you can add warn=False
2018-09-14 18:25:43.990 | to this command task or set command_warnings=False in ansible.cfg to get rid of
2018-09-14 18:25:43.991 | this message.
2018-09-14 18:25:43.991 | localhost | SUCCESS | rc=0 >>
2018-09-14 18:25:43.991 | 
2018-09-14 18:25:44.041 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L742:   echo 'Running devstack'
2018-09-14 18:25:44.041 | Running devstack
2018-09-14 18:25:44.041 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L743:   echo '... this takes 10 - 15 minutes (logs in logs/devstacklog.txt.gz)'
2018-09-14 18:25:44.042 | ... this takes 10 - 15 minutes (logs in logs/devstacklog.txt.gz)
2018-09-14 18:25:44.042 | ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L744:   date +%s
2018-09-14 18:25:44.042 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L744:   start=1536949544
2018-09-14 18:25:44.043 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L751:   /tmp/ansible/bin/ansible primary -f 5 -i /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/inventory -m shell -a 'cd '\''/opt/stack/new/devstack'\'' && sudo -H -u stack DSTOOLS_VERSION=0.4.0 stdbuf -oL -eL ./stack.sh 2>&1 executable=/bin/bash'
2018-09-14 19:02:55.830 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L754:   '[' -d /opt/stack/data/CA ']'
2018-09-14 19:02:55.830 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L771:   /tmp/ansible/bin/ansible subnodes -f 5 -i /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/inventory -m shell -a 'cd '\''/opt/stack/new/devstack'\'' && sudo -H -u stack DSTOOLS_VERSION=0.4.0 stdbuf -oL -eL ./stack.sh 2>&1 executable=/bin/bash'
2018-09-14 19:02:57.554 | ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L774:   date +%s
2018-09-14 19:02:57.556 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L774:   end=1536951777
2018-09-14 19:02:57.557 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L775:   took=37
2018-09-14 19:02:57.557 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L776:   [[ 37 -gt 20 ]]
2018-09-14 19:02:57.557 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L777:   echo 'WARNING: devstack run took > 20 minutes, this is a very slow node.'
2018-09-14 19:02:57.558 | WARNING: devstack run took > 20 minutes, this is a very slow node.
2018-09-14 19:02:57.558 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L781:   discover_hosts
2018-09-14 19:02:57.558 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:discover_hosts:L259:   /tmp/ansible/bin/ansible primary -f 5 -i /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/inventory -m shell -a 'cd /opt/stack/new/devstack/ && (test -f tools/discover_hosts.sh && sudo -H -u stack DSTOOLS_VERSION=0.4.0 stdbuf -oL -eL ./tools/discover_hosts.sh) || (! test -f tools/discover_hosts.sh)'
2018-09-14 19:03:06.143 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L785:   [[ -f /usr/bin/yum ]]
2018-09-14 19:03:06.144 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L789:   POSTGRES_LOG_PATH='-d /var/log/postgresql'
2018-09-14 19:03:06.145 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L790:   MYSQL_LOG_PATH='-d /var/log/mysql'
2018-09-14 19:03:06.145 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L792:   [[ 0 -eq 1 ]]
2018-09-14 19:03:06.146 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L798:   [[ ! -n -d /var/log/mysql ]]
2018-09-14 19:03:06.146 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L805:   [[ 0 -eq 1 ]]
2018-09-14 19:03:06.146 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L810:   [[ 1 -eq 1 ]]
2018-09-14 19:03:06.146 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L811:   echo 'Removing sudo privileges for devstack user'
2018-09-14 19:03:06.147 | Removing sudo privileges for devstack user
2018-09-14 19:03:06.147 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L812:   /tmp/ansible/bin/ansible all --sudo -f 5 -i /home/jenkins/workspace/tempest-dsvm-ironic-agent_ipmitool/inventory -m file -a 'path=/etc/sudoers.d/50_stack_sh state=absent'
2018-09-14 19:03:06.821 | [DEPRECATION WARNING]: The sudo command line option has been deprecated in 
2018-09-14 19:03:06.821 | favor of the "become" command line arguments. This feature will be removed in 
2018-09-14 19:03:06.822 | version 2.6. Deprecation warnings can be disabled by setting 
2018-09-14 19:03:06.822 | deprecation_warnings=False in ansible.cfg.
2018-09-14 19:03:08.209 | localhost | SUCCESS => {
2018-09-14 19:03:08.209 |     "changed": true, 
2018-09-14 19:03:08.210 |     "path": "/etc/sudoers.d/50_stack_sh", 
2018-09-14 19:03:08.210 |     "state": "absent"
2018-09-14 19:03:08.210 | }
2018-09-14 19:03:08.275 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L816:   [[ 1 -eq 1 ]]
2018-09-14 19:03:08.275 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L818:   [[ -d /opt/stack/new/tempest ]]
2018-09-14 19:03:08.276 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L819:   sudo chown -R tempest:stack /opt/stack/new/tempest
2018-09-14 19:03:08.683 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L823:   [[ -d /opt/stack/data/tempest ]]
2018-09-14 19:03:08.684 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L824:   sudo chown -R tempest:stack /opt/stack/data/tempest
2018-09-14 19:03:08.694 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L827:   [[ -d /opt/stack/new/devstack/files ]]
2018-09-14 19:03:08.694 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L828:   sudo chmod -R o+rx /opt/stack/new/devstack/files
2018-09-14 19:03:08.708 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L837:   [[ aio == \m\u\l\t\i\n\o\d\e ]]
2018-09-14 19:03:08.708 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L842:   [[ 0 -eq 1 ]]
2018-09-14 19:03:08.709 | ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L850:   iniget /opt/stack/new/tempest/etc/tempest.conf neutron-feature-enabled api_extensions
2018-09-14 19:03:08.710 | +++ /opt/stack/new/devstack-gate/functions.sh:iniget:L1026:   source /opt/stack/new/devstack/inc/ini-config
2018-09-14 19:03:08.712 | +++++ /opt/stack/new/devstack/inc/ini-config:source:L10:   grep xtrace
2018-09-14 19:03:08.713 | +++++ /opt/stack/new/devstack/inc/ini-config:source:L10:   set +o
2018-09-14 19:03:08.716 | ++++ /opt/stack/new/devstack/inc/ini-config:source:L10:   INC_CONF_TRACE='set -o xtrace'
2018-09-14 19:03:08.717 | ++++ /opt/stack/new/devstack/inc/ini-config:source:L11:   set +o xtrace
2018-09-14 19:03:08.720 | +++ /opt/stack/new/devstack-gate/functions.sh:iniget:L1026:   iniget /opt/stack/new/tempest/etc/tempest.conf neutron-feature-enabled api_extensions
2018-09-14 19:03:08.720 | +++ /opt/stack/new/devstack/inc/ini-config:iniget:L61:   local xtrace
2018-09-14 19:03:08.720 | ++++ /opt/stack/new/devstack/inc/ini-config:iniget:L62:   set +o
2018-09-14 19:03:08.721 | ++++ /opt/stack/new/devstack/inc/ini-config:iniget:L62:   grep xtrace
2018-09-14 19:03:08.723 | +++ /opt/stack/new/devstack/inc/ini-config:iniget:L62:   xtrace='set -o xtrace'
2018-09-14 19:03:08.724 | +++ /opt/stack/new/devstack/inc/ini-config:iniget:L63:   set +o xtrace
2018-09-14 19:03:08.729 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L850:   neutron_extensions=
2018-09-14 19:03:08.729 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L851:   [[ '' == \m\a\s\t\e\r ]]
2018-09-14 19:03:08.730 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L858:   set -o errexit
2018-09-14 19:03:08.730 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L860:   [[ 1800 != '' ]]
2018-09-14 19:03:08.730 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L861:   TEMPEST_COMMAND='sudo -H -u tempest OS_TEST_TIMEOUT=1800 tox'
2018-09-14 19:03:08.730 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L865:   cd /opt/stack/new/tempest
2018-09-14 19:03:08.730 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L866:   [[ (?!.*(baremetal.test.to.skip1|baremetal.test.to.skip2).*)(ironic|baremetal) != '' ]]
2018-09-14 19:03:08.730 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L867:   [[ 0 -eq 1 ]]
2018-09-14 19:03:08.730 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L872:   echo 'Running tempest with a custom regex filter'
2018-09-14 19:03:08.730 | Running tempest with a custom regex filter
2018-09-14 19:03:08.731 | + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L873:   sudo -H -u tempest OS_TEST_TIMEOUT=1800 tox -eall -- '(?!.*(baremetal.test.to.skip1|baremetal.test.to.skip2).*)(ironic|baremetal)' --concurrency=1
2018-09-14 19:03:10.286 | all develop-inst-noop: /opt/stack/new/tempest
2018-09-14 19:03:11.010 | all installed: asn1crypto==0.24.0,Babel==2.6.0,bcrypt==3.1.4,certifi==2018.8.24,cffi==1.11.5,chardet==3.0.4,cliff==2.13.0,cmd2==0.8.9,contextlib2==0.5.5,cryptography==2.3.1,debtcollector==1.20.0,enum34==1.1.6,extras==1.0.0,fasteners==0.14.1,fixtures==3.0.0,funcsigs==1.0.2,functools32==3.2.3.post2,future==0.16.0,idna==2.7,ipaddress==1.0.22,ironic-tempest-plugin==1.2.1.dev5,iso8601==0.1.12,jsonschema==2.6.0,linecache2==1.0.0,monotonic==1.5,msgpack==0.5.6,netaddr==0.7.19,netifaces==0.10.7,oslo.concurrency==3.27.0,oslo.config==6.4.0,oslo.context==2.21.0,oslo.i18n==3.21.0,oslo.log==3.39.0,oslo.serialization==2.27.0,oslo.utils==3.37.0,paramiko==2.4.1,pbr==4.2.0,prettytable==0.7.2,pyasn1==0.4.4,pycparser==2.18,pyinotify==0.9.6,PyNaCl==1.2.1,pyparsing==2.2.0,pyperclip==1.6.4,python-dateutil==2.7.3,python-mimeparse==1.6.0,python-subunit==1.3.0,pytz==2018.5,PyYAML==3.13,requests==2.19.1,rfc3986==1.1.0,six==1.11.0,stestr==2.1.1,stevedore==1.29.0,subprocess32==3.5.2,-e git+https://git.openstack.org/openstack/tempest@3674fb13825a60be9eae6341ec5f7d1fdc7472b4#egg=tempest,testtools==2.3.0,traceback2==1.4.0,unicodecsv==0.14.1,unittest2==1.1.0,urllib3==1.23,voluptuous==0.11.5,wcwidth==0.1.7,wrapt==1.10.11
2018-09-14 19:03:11.010 | all runtests: PYTHONHASHSEED='4144150132'
2018-09-14 19:03:11.010 | all runtests: commands[0] | find . -type f -name '*.pyc' -delete
2018-09-14 19:03:11.159 | all runtests: commands[1] | tempest run --regex '(?!.*(baremetal.test.to.skip1|baremetal.test.to.skip2).*)(ironic|baremetal)' --concurrency=1
2018-09-14 19:03:19.166 | {0} ironic_tempest_plugin.tests.api.admin.test_api_discovery.TestApiDiscovery.test_api_versions [0.051062s] ... ok
2018-09-14 19:03:19.168 | {0} ironic_tempest_plugin.tests.api.admin.test_api_discovery.TestApiDiscovery.test_default_version [0.009319s] ... ok
2018-09-14 19:03:19.175 | {0} ironic_tempest_plugin.tests.api.admin.test_api_discovery.TestApiDiscovery.test_version_1_resources [0.006747s] ... ok
2018-09-14 19:03:21.419 | {0} ironic_tempest_plugin.tests.api.admin.test_chassis.TestChassis.test_chassis_node_list [0.138480s] ... ok
2018-09-14 19:03:21.450 | {0} ironic_tempest_plugin.tests.api.admin.test_chassis.TestChassis.test_create_chassis [0.030510s] ... ok
2018-09-14 19:03:21.479 | {0} ironic_tempest_plugin.tests.api.admin.test_chassis.TestChassis.test_create_chassis_unicode_description [0.027351s] ... ok
2018-09-14 19:03:21.504 | {0} ironic_tempest_plugin.tests.api.admin.test_chassis.TestChassis.test_create_chassis_uuid [0.023392s] ... ok
2018-09-14 19:03:21.579 | {0} ironic_tempest_plugin.tests.api.admin.test_chassis.TestChassis.test_delete_chassis [0.073660s] ... ok
2018-09-14 19:03:21.598 | {0} ironic_tempest_plugin.tests.api.admin.test_chassis.TestChassis.test_list_chassis [0.018151s] ... ok
2018-09-14 19:03:21.612 | {0} ironic_tempest_plugin.tests.api.admin.test_chassis.TestChassis.test_show_chassis [0.012428s] ... ok
2018-09-14 19:03:21.688 | {0} ironic_tempest_plugin.tests.api.admin.test_chassis.TestChassis.test_update_chassis [0.074377s] ... ok
2018-09-14 19:03:24.220 | {0} ironic_tempest_plugin.tests.api.admin.test_drivers.TestDrivers.test_driver_logical_disk_properties [0.256556s] ... ok
2018-09-14 19:03:24.252 | {0} ironic_tempest_plugin.tests.api.admin.test_drivers.TestDrivers.test_driver_properties [0.031626s] ... ok
2018-09-14 19:03:24.279 | {0} ironic_tempest_plugin.tests.api.admin.test_drivers.TestDrivers.test_list_drivers [0.026027s] ... ok
2018-09-14 19:03:24.304 | {0} ironic_tempest_plugin.tests.api.admin.test_drivers.TestDrivers.test_show_driver [0.024410s] ... ok
2018-09-14 19:03:29.011 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestHardwareInterfaces.test_reset_interfaces [2.832211s] ... ok
2018-09-14 19:03:31.512 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestHardwareInterfaces.test_set_interfaces [2.498931s] ... ok
2018-09-14 19:03:34.207 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodeFault.test_fault_shown [0.360420s] ... ok
2018-09-14 19:03:34.439 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodeFault.test_list_nodes_fault [0.231452s] ... ok
2018-09-14 19:03:34.562 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodeFault.test_list_nodes_with_invalid_fault [0.122061s] ... ok
2018-09-14 19:03:37.536 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_create_node [0.455679s] ... ok
2018-09-14 19:03:37.914 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_delete_node [0.377974s] ... ok
2018-09-14 19:03:38.040 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_fault_hidden [0.124858s] ... ok
2018-09-14 19:03:38.257 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_get_console [0.215699s] ... ok
2018-09-14 19:03:38.518 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_get_node_boot_device [0.260566s] ... ok
2018-09-14 19:03:39.274 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_get_node_by_instance_uuid [0.753803s] ... ok
2018-09-14 19:03:39.481 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_get_node_supported_boot_devices [0.208295s] ... ok
2018-09-14 19:03:39.759 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_list_nodes [0.277751s] ... ok
2018-09-14 19:03:41.890 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_list_nodes_association [2.130058s] ... ok
2018-09-14 19:03:42.363 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_node_port_list [0.473659s] ... ok
2018-09-14 19:03:42.585 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_node_port_list_no_ports [0.221065s] ... ok
2018-09-14 19:03:42.922 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_set_console_mode [0.336569s] ... ok
2018-09-14 19:03:43.227 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_set_node_boot_device [0.304454s] ... ok
2018-09-14 19:03:43.401 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_show_node [0.174199s] ... ok
2018-09-14 19:03:43.870 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_update_node [0.466689s] ... ok
2018-09-14 19:03:44.476 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodes.test_validate_driver_interface [0.604827s] ... ok
2018-09-14 19:03:50.758 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesResourceClass.test_create_node_resource_class_long [0.439999s] ... ok
2018-09-14 19:03:50.881 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesResourceClass.test_create_node_resource_class_too_long [0.124889s] ... ok
2018-09-14 19:03:51.323 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesResourceClass.test_list_nodes_detail_resource_class [0.443366s] ... ok
2018-09-14 19:03:51.738 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesResourceClass.test_list_nodes_resource_class [0.415294s] ... ok
2018-09-14 19:03:51.875 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesResourceClass.test_show_node_resource_class [0.136667s] ... ok
2018-09-14 19:03:52.179 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesResourceClass.test_update_node_resource_class [0.302075s] ... ok
2018-09-14 19:03:52.333 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesResourceClass.test_update_node_resource_class_too_long [0.153211s] ... ok
2018-09-14 19:03:57.173 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesResourceClassOldApi.test_create_node_resource_class_old_api [0.337500s] ... ok
2018-09-14 19:03:57.308 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesResourceClassOldApi.test_list_nodes_by_resource_class_old_api [0.134025s] ... ok
2018-09-14 19:03:57.427 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesResourceClassOldApi.test_update_node_resource_class_old_api [0.116641s] ... ok
2018-09-14 19:04:00.755 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_add_node_trait_duplicate_trait [0.690753s] ... ok
2018-09-14 19:04:01.051 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_add_node_trait_long [0.293680s] ... ok
2018-09-14 19:04:01.180 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_add_node_trait_non_existent_node [0.126990s] ... ok
2018-09-14 19:04:01.393 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_add_node_trait_too_long [0.210286s] ... ok
2018-09-14 19:04:02.048 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_add_node_trait_too_many [0.653262s] ... ok
2018-09-14 19:04:02.398 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_list_node_traits [0.347606s] ... ok
2018-09-14 19:04:02.528 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_list_node_traits_non_existent_node [0.128113s] ... ok
2018-09-14 19:04:03.022 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_list_nodes_detail [0.492545s] ... ok
2018-09-14 19:04:03.466 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_list_nodes_traits_field [0.442762s] ... ok
2018-09-14 19:04:03.951 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_remove_node_trait [0.485420s] ... ok
2018-09-14 19:04:04.074 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_remove_node_trait_non_existent_node [0.122632s] ... ok
2018-09-14 19:04:04.354 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_remove_node_trait_non_existent_trait [0.279304s] ... ok
2018-09-14 19:04:04.833 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_remove_node_traits [0.479107s] ... ok
2018-09-14 19:04:05.131 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_remove_node_traits_no_traits [0.297530s] ... ok
2018-09-14 19:04:05.263 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_remove_node_traits_non_existent_node [0.130496s] ... ok
2018-09-14 19:04:05.793 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_set_node_traits [0.527087s] ... ok
2018-09-14 19:04:06.103 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_set_node_traits_duplicate_trait [0.308030s] ... ok
2018-09-14 19:04:06.499 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_set_node_traits_max_traits [0.393902s] ... ok
2018-09-14 19:04:06.634 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_set_node_traits_non_existent_node [0.134169s] ... ok
2018-09-14 19:04:06.974 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_set_node_traits_too_many [0.336791s] ... ok
2018-09-14 19:04:07.286 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_show_node [0.309927s] ... ok
2018-09-14 19:04:07.436 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraits.test_update_node_traits [0.149431s] ... ok
2018-09-14 19:04:14.782 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraitsOldApi.test_add_node_trait_old_api [0.327635s] ... ok
2018-09-14 19:04:14.894 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraitsOldApi.test_list_node_traits_old_api [0.111056s] ... ok
2018-09-14 19:04:15.087 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraitsOldApi.test_list_nodes_detail_old_api [0.193475s] ... ok
2018-09-14 19:04:15.210 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraitsOldApi.test_list_nodes_traits_field [0.121986s] ... ok
2018-09-14 19:04:15.338 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraitsOldApi.test_remove_node_trait_old_api [0.116483s] ... ok
2018-09-14 19:04:15.534 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraitsOldApi.test_remove_node_traits_old_api [0.206619s] ... ok
2018-09-14 19:04:15.645 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraitsOldApi.test_set_node_traits_old_api [0.110871s] ... ok
2018-09-14 19:04:15.797 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesTraitsOldApi.test_show_node_old_api [0.151288s] ... ok
2018-09-14 19:04:23.805 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesVif.test_vif_already_set_on_extra [4.181329s] ... ok
2018-09-14 19:04:28.140 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesVif.test_vif_on_port [4.335505s] ... ok
2018-09-14 19:04:32.927 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestNodesVif.test_vif_on_portgroup [4.782229s] ... ok
2018-09-14 19:04:37.101 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestResetInterfaces.test_no_reset_by_default [1.132244s] ... ok
2018-09-14 19:04:38.197 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestResetInterfaces.test_reset_all_interfaces [1.094944s] ... ok
2018-09-14 19:04:40.869 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestResetInterfaces.test_reset_interfaces [2.668852s] ... ok
2018-09-14 19:04:43.755 | {0} ironic_tempest_plugin.tests.api.admin.test_nodes.TestResetInterfaces.test_set_interfaces [2.879235s] ... ok
2018-09-14 19:04:47.639 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_1.test_list_nodestates [0.102189s] ... ok
2018-09-14 19:04:48.291 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_1.test_set_node_power_state [0.649387s] ... ok
2018-09-14 19:04:49.469 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_1.test_set_node_provision_state [1.174342s] ... ok
2018-09-14 19:04:52.266 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_11.test_list_nodestates [0.100120s] ... ok
2018-09-14 19:04:52.942 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_11.test_set_node_power_state [0.678057s] ... ok
2018-09-14 19:04:55.203 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_11.test_set_node_provision_state [2.252210s] ... ok
2018-09-14 19:04:57.934 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_12.test_list_nodestates [0.102718s] ... ok
2018-09-14 19:04:58.599 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_12.test_set_node_power_state [0.664058s] ... ok
2018-09-14 19:04:58.868 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_12.test_set_node_raid_config [0.270397s] ... ok
2018-09-14 19:05:01.498 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_2.test_list_nodestates [0.105862s] ... ok
2018-09-14 19:05:02.162 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_2.test_set_node_power_state [0.662691s] ... ok
2018-09-14 19:05:03.301 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_2.test_set_node_provision_state [1.136761s] ... ok
2018-09-14 19:05:06.144 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_4.test_list_nodestates [0.108942s] ... ok
2018-09-14 19:05:06.814 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_4.test_set_node_power_state [0.669876s] ... ok
2018-09-14 19:05:08.698 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_4.test_set_node_provision_state [1.875309s] ... ok
2018-09-14 19:05:11.447 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_6.test_list_nodestates [0.096939s] ... ok
2018-09-14 19:05:12.086 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_6.test_set_node_power_state [0.637720s] ... ok
2018-09-14 19:05:14.108 | {0} ironic_tempest_plugin.tests.api.admin.test_nodestates.TestNodeStatesV1_6.test_set_node_provision_state [2.013436s] ... ok
2018-09-14 19:05:17.037 | {0} ironic_tempest_plugin.tests.api.admin.test_portgroups.TestPortGroups.test_create_portgroup_no_address [0.507953s] ... ok
2018-09-14 19:05:17.229 | {0} ironic_tempest_plugin.tests.api.admin.test_portgroups.TestPortGroups.test_create_portgroup_with_address [0.191557s] ... ok
2018-09-14 19:05:17.586 | {0} ironic_tempest_plugin.tests.api.admin.test_portgroups.TestPortGroups.test_delete_portgroup [0.356098s] ... ok
2018-09-14 19:05:17.842 | {0} ironic_tempest_plugin.tests.api.admin.test_portgroups.TestPortGroups.test_list_portgroups [0.254349s] ... ok
2018-09-14 19:05:18.033 | {0} ironic_tempest_plugin.tests.api.admin.test_portgroups.TestPortGroups.test_show_portgroup [0.189897s] ... ok
2018-09-14 19:05:21.750 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_create_port [0.740086s] ... ok
2018-09-14 19:05:22.248 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_create_port_specifying_uuid [0.496213s] ... ok
2018-09-14 19:05:22.743 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_create_port_with_extra [0.493738s] ... ok
2018-09-14 19:05:23.416 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_delete_port [0.672494s] ... ok
2018-09-14 19:05:23.852 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_list_ports [0.435126s] ... ok
2018-09-14 19:05:25.294 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_list_ports_details [1.439241s] ... ok
2018-09-14 19:05:26.805 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_list_ports_details_with_address [1.507946s] ... ok
2018-09-14 19:05:27.215 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_list_with_limit [0.410316s] ... ok
2018-09-14 19:05:27.655 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_show_port [0.439163s] ... ok
2018-09-14 19:05:28.003 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_show_port_by_address [0.347222s] ... ok
2018-09-14 19:05:28.324 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_show_port_with_links [0.315843s] ... ok
2018-09-14 19:05:29.697 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_update_port_add [1.376092s] ... ok
2018-09-14 19:05:30.621 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_update_port_mixed_ops [0.921860s] ... ok
2018-09-14 19:05:31.988 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_update_port_remove [1.363947s] ... ok
2018-09-14 19:05:32.974 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPorts.test_update_port_replace [0.982784s] ... ok
2018-09-14 19:05:43.832 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPortsWithPhysicalNetwork.test_create_port_with_physical_network [0.555751s] ... ok
2018-09-14 19:05:44.469 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPortsWithPhysicalNetwork.test_create_ports_in_portgroup_with_physical_network [0.636977s] ... ok
2018-09-14 19:05:45.527 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPortsWithPhysicalNetwork.test_update_port_remove_physical_network [1.056816s] ... ok
2018-09-14 19:05:46.547 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPortsWithPhysicalNetwork.test_update_port_replace_physical_network [1.019276s] ... ok
2018-09-14 19:05:47.681 | {0} ironic_tempest_plugin.tests.api.admin.test_ports.TestPortsWithPhysicalNetwork.test_update_ports_in_portgroup_with_physical_network [1.132838s] ... ok
2018-09-14 19:05:52.618 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_create_port_duplicated_mac [0.697968s] ... ok
2018-09-14 19:05:53.114 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_create_port_duplicated_port_uuid [0.497174s] ... ok
2018-09-14 19:05:53.225 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_create_port_malformed_mac [0.109418s] ... ok
2018-09-14 19:05:53.353 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_create_port_malformed_node_id [0.127690s] ... ok
2018-09-14 19:05:53.470 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_create_port_malformed_port_uuid [0.114687s] ... ok
2018-09-14 19:05:53.583 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_create_port_no_mandatory_field_mac [0.113651s] ... ok
2018-09-14 19:05:53.707 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_create_port_no_mandatory_field_node_id [0.123339s] ... ok
2018-09-14 19:05:53.847 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_create_port_nonexsistent_node_id [0.137652s] ... ok
2018-09-14 19:05:54.159 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_delete_port_by_mac_not_allowed [0.312506s] ... ok
2018-09-14 19:05:54.284 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_show_port_by_mac_not_allowed [0.124688s] ... ok
2018-09-14 19:05:54.405 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_show_port_malformed_uuid [0.120655s] ... ok
2018-09-14 19:05:54.524 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_show_port_nonexistent_uuid [0.118765s] ... ok
2018-09-14 19:05:54.877 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_update_port_add_nonexistent_property [0.352576s] ... ok
2018-09-14 19:05:55.204 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_update_port_by_mac_not_allowed [0.326405s] ... ok
2018-09-14 19:05:55.509 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_update_port_malformed_port_uuid [0.303942s] ... ok
2018-09-14 19:05:55.854 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_update_port_mixed_ops_integrity [0.343810s] ... ok
2018-09-14 19:05:56.363 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_update_port_nonexistent [0.508523s] ... ok
2018-09-14 19:05:56.658 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_update_port_remove_mandatory_field_mac [0.295224s] ... ok
2018-09-14 19:05:56.973 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_update_port_remove_mandatory_field_port_uuid [0.314244s] ... ok
2018-09-14 19:05:57.284 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_update_port_remove_nonexistent_property [0.304413s] ... ok
2018-09-14 19:05:58.462 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_update_port_replace_mac_with_duplicated [1.177315s] ... ok
2018-09-14 19:05:58.779 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_update_port_replace_mac_with_malformed [0.316932s] ... ok
2018-09-14 19:05:59.080 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_update_port_replace_node_id_with_malformed [0.300332s] ... ok
2018-09-14 19:05:59.406 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_update_port_replace_node_id_with_nonexistent [0.325618s] ... ok
2018-09-14 19:05:59.697 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegative.test_update_port_replace_nonexistent_property [0.290410s] ... ok
2018-09-14 19:06:10.245 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegativeWithPhysicalNetwork.test_create_ports_in_portgroup_with_inconsistent_physical_network [0.771087s] ... ok
2018-09-14 19:06:11.023 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegativeWithPhysicalNetwork.test_update_ports_in_portgroup_with_inconsistent_physical_network [0.776597s] ... ok
2018-09-14 19:06:11.921 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsNegativeWithPhysicalNetwork.test_update_ports_in_portgroup_with_inconsistent_physical_network_2 [0.897755s] ... ok
2018-09-14 19:06:15.639 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsWithPhysicalNetworkOldAPI.test_create_port_with_physical_network_old_api [0.357763s] ... ok
2018-09-14 19:06:15.947 | {0} ironic_tempest_plugin.tests.api.admin.test_ports_negative.TestPortsWithPhysicalNetworkOldAPI.test_update_port_replace_physical_network_old_api [0.306874s] ... ok
2018-09-14 19:06:18.913 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_connector.TestVolumeConnector.test_create_volume_connector_error [0.486501s] ... ok
2018-09-14 19:06:19.397 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_connector.TestVolumeConnector.test_delete_volume_connector [0.485227s] ... ok
2018-09-14 19:06:19.892 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_connector.TestVolumeConnector.test_delete_volume_connector_error [0.492560s] ... ok
2018-09-14 19:06:20.123 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_connector.TestVolumeConnector.test_list_volume_connectors [0.230845s] ... ok
2018-09-14 19:06:20.340 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_connector.TestVolumeConnector.test_list_with_limit [0.217643s] ... ok
2018-09-14 19:06:20.632 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_connector.TestVolumeConnector.test_show_volume_connector [0.290248s] ... ok
2018-09-14 19:06:21.190 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_connector.TestVolumeConnector.test_update_volume_connector_add [0.557121s] ... ok
2018-09-14 19:06:21.739 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_connector.TestVolumeConnector.test_update_volume_connector_remove_collection [0.549437s] ... ok
2018-09-14 19:06:22.301 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_connector.TestVolumeConnector.test_update_volume_connector_remove_item [0.560600s] ... ok
2018-09-14 19:06:22.856 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_connector.TestVolumeConnector.test_update_volume_connector_replace [0.554969s] ... ok
2018-09-14 19:06:23.347 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_connector.TestVolumeConnector.test_update_volume_connector_replace_error [0.491347s] ... ok
2018-09-14 19:06:28.156 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_target.TestVolumeTarget.test_create_volume_target_error [0.438529s] ... ok
2018-09-14 19:06:28.870 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_target.TestVolumeTarget.test_delete_volume_target [0.712854s] ... ok
2018-09-14 19:06:29.385 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_target.TestVolumeTarget.test_delete_volume_target_error [0.513340s] ... ok
2018-09-14 19:06:29.592 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_target.TestVolumeTarget.test_list_volume_targets [0.207567s] ... ok
2018-09-14 19:06:29.800 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_target.TestVolumeTarget.test_list_with_limit [0.207220s] ... ok
2018-09-14 19:06:29.983 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_target.TestVolumeTarget.test_show_volume_target [0.181810s] ... ok
2018-09-14 19:06:30.519 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_target.TestVolumeTarget.test_update_volume_target_add [0.535019s] ... ok
2018-09-14 19:06:31.077 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_target.TestVolumeTarget.test_update_volume_target_remove_collection [0.556744s] ... ok
2018-09-14 19:06:31.643 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_target.TestVolumeTarget.test_update_volume_target_remove_item [0.563387s] ... ok
2018-09-14 19:06:32.189 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_target.TestVolumeTarget.test_update_volume_target_replace [0.548790s] ... ok
2018-09-14 19:06:32.682 | {0} ironic_tempest_plugin.tests.api.admin.test_volume_target.TestVolumeTarget.test_update_volume_target_replace_error [0.492689s] ... ok
2018-09-14 19:06:36.033 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalAgentIpmitoolPartitioned) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.035 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalAgentIpmitoolWholedisk) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.035 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalAgentIpmitoolWholediskHttpLink) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.036 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalIloDirectPartitioned) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.036 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalIloDirectWholediskHttpLink) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.037 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalIloIscsiPartitioned) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.038 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalIloIscsiWholediskHttpLink) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.039 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalIloPxePartitioned) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.040 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalIloPxeWholediskHttpLink) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.043 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalIpmiAnsibleWholedisk) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.044 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalIpmiDirectPartitioned) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.045 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalIpmiDirectWholedisk) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.046 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalIpmiIscsiPartitioned) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.048 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalIpmiIscsiWholedisk) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.050 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalIpmiRescuePartitioned) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.051 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalIpmiRescueWholedisk) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.052 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalPxeIpmitoolPartitioned) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.054 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalPxeIpmitoolWholedisk) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.054 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_basic_ops.BaremetalPxeIpmitoolWholediskHttpLink) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.055 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_bios.BaremetalFakeBios) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.056 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_cleaning.BaremetalCleaningAgentIpmitoolWholedisk) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.057 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_cleaning.BaremetalCleaningIpmiWholedisk) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:06:36.057 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.ironic_standalone.test_cleaning.BaremetalCleaningPxeIpmitoolWholedisk) ... SKIPPED: Nova is enabled. Stand-alone tests will be skipped.
2018-09-14 19:32:28.118 | {0} ironic_tempest_plugin.tests.scenario.test_baremetal_basic_ops.BaremetalBasicOps.test_baremetal_server_ops [1550.149711s] ... ok
2018-09-14 19:32:29.835 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.test_baremetal_basic_ops.BaremetalBasicOpsAndRescue) ... SKIPPED: Node rescue interface is not enabled.
2018-09-14 19:32:31.536 | {0} ironic_tempest_plugin.tests.scenario.test_baremetal_boot_from_volume.BaremetalBFV.test_baremetal_boot_from_volume ... SKIPPED: Skipped because the volume service is not available
2018-09-14 19:32:33.054 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.test_baremetal_multitenancy.BaremetalMultitenancy) ... SKIPPED: Ironic/Neutron tenant isolation is not configured.
2018-09-14 19:32:33.054 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.test_introspection_basic.InspectorBasicTest) ... SKIPPED: Ironic Inspector is not enabled.
2018-09-14 19:32:33.056 | {0} setUpClass (ironic_tempest_plugin.tests.scenario.test_introspection_discovery.InspectorDiscoveryTest) ... SKIPPED: Ironic Inspector is not enabled.
2018-09-14 19:32:33.528 | 
2018-09-14 19:32:33.529 | ======
2018-09-14 19:32:33.529 | Totals
2018-09-14 19:32:33.529 | ======
2018-09-14 19:32:33.529 | Ran: 207 tests in 1756.0000 sec.
2018-09-14 19:32:33.530 |  - Passed: 179
2018-09-14 19:32:33.530 |  - Skipped: 28
2018-09-14 19:32:33.530 |  - Expected Fail: 0
2018-09-14 19:32:33.530 |  - Unexpected Success: 0
2018-09-14 19:32:33.531 |  - Failed: 0
2018-09-14 19:32:33.531 | Sum of execute time for each test: 1648.6299 sec.
2018-09-14 19:32:33.531 | 
2018-09-14 19:32:33.531 | ==============
2018-09-14 19:32:33.532 | Worker Balance
2018-09-14 19:32:33.532 | ==============
2018-09-14 19:32:33.532 |  - Worker 0 (207 tests) => 0:29:13.949251
2018-09-14 19:32:33.620 | ___________________________________ summary ____________________________________
2018-09-14 19:32:33.628 |   all: commands succeeded
2018-09-14 19:32:33.629 |   congratulations :)
2018-09-14 19:32:33.681 | Job timeout set to: 34 minutes
2018-09-14 19:32:33.701 | Cleaning up host
2018-09-14 19:32:33.701 | ... this takes 3 - 4 minutes (logs at logs/devstack-gate-cleanup-host.txt.gz)
2018-09-14 19:33:13.415 |  [WARNING]: Could not match supplied host pattern, ignoring: subnodes
2018-09-14 19:33:13.416 |  [WARNING]: No hosts matched, nothing to do
2018-09-14 19:33:17.141 | Done.
2018-09-14 19:33:18.560 | SUCCESSFULLY FINISHED
2018-09-14 19:33:18.765 | [PostBuildScript] - Execution post build scripts.
2018-09-14 19:33:18.815 | [tempest-dsvm-ironic-agent_ipmitool] $ /bin/sh -xe /tmp/hudson3789452982181033222.sh
2018-09-14 19:33:18.830 | + ibm-devstack-gate/ironic_cleanup.sh
2018-09-14 19:33:18.833 | + set -e
2018-09-14 19:33:18.833 | + trap _cleanup EXIT
2018-09-14 19:33:18.834 | + set +x
2018-09-14 19:33:19.180 | + _cleanup
2018-09-14 19:33:19.181 | + set -x
2018-09-14 19:33:19.181 | + uuid=36215a7a-2be1-43b2-87d8-5e446910491a
2018-09-14 19:33:19.182 | + molteniron/molteniron/molteniron -c molteniron release 36215a7a-2be1-43b2-87d8-5e446910491a
2018-09-14 19:33:19.423 | {"status": 200}
2018-09-14 19:33:19.436 | [PostBuildScript] - Execution post build scripts.
2018-09-14 19:33:19.446 | [tempest-dsvm-ironic-agent_ipmitool] $ /bin/sh -xe /tmp/hudson657799830253805801.sh
2018-09-14 19:33:19.454 | + /usr/local/jenkins/slave_scripts/grab_console_log.sh
2018-09-14 19:33:19.455 | + RETRY_LIMIT=20
2018-09-14 19:33:19.457 | ++ cat /proc/sys/kernel/random/uuid
2018-09-14 19:33:19.458 | + END_UUID=b4a2d5a5-9aa9-49eb-95b9-e58adb8e925d
2018-09-14 19:33:19.459 | + echo 'Grabbing consoleLog (b4a2d5a5-9aa9-49eb-95b9-e58adb8e925d)'
2018-09-14 19:33:19.459 | Grabbing consoleLog (b4a2d5a5-9aa9-49eb-95b9-e58adb8e925d)
2018-09-14 19:33:19.459 | + rm -f /tmp/console.html
2018-09-14 19:33:19.460 | + TRIES=0
2018-09-14 19:33:19.460 | + console_log_path=logText/progressiveHtml
2018-09-14 19:33:19.461 | + grep -q b4a2d5a5-9aa9-49eb-95b9-e58adb8e925d /tmp/console.html
2018-09-14 19:33:19.462 | + TRIES=1
2018-09-14 19:33:19.463 | + '[' 1 -gt 20 ']'
2018-09-14 19:33:19.463 | + sleep 3
2018-09-14 19:33:22.466 | ++ stat -c %s /tmp/console.html
2018-09-14 19:33:22.468 | ++ echo 0
2018-09-14 19:33:22.469 | + curl -X POST --data start=0 --fail --insecure http://9.114.111.16:8080/job/tempest-dsvm-ironic-agent_ipmitool/9919/logText/progressiveHtml
2018-09-14 19:33:22.490 |   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
2018-09-14 19:33:22.491 |                                  Dload  Upload   Total   Spent    Left  Speed