03:31:24 Started by user anonymous
03:31:24 [EnvInject] - Loading node environment variables.
03:31:24 Building remotely on scaleiocinder_101 (VxFlexOS) in workspace /home/jenkins/workspace/EMC_VxFlexOS
03:31:25 [EMC_VxFlexOS] $ /bin/bash /tmp/jenkins1533630704175701479.sh
03:31:25 MASTER clean journal
03:31:25 Vacuuming done, freed 0B of archived journals on disk.
03:31:25 Archived and active journals take up 504.0M on disk.
03:31:25 rm: cannot remove '/var/lib/apt/lists/partial/*': No such file or directory
03:31:27 Reading package lists...
03:31:27 Building dependency tree...
03:31:27 Reading state information...
03:31:27 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
03:31:35 Reading package lists...
03:31:35 Building dependency tree...
03:31:35 Reading state information...
03:31:35 Package 'mysql-client' is not installed, so not removed
03:31:35 The following packages were automatically installed and are no longer required:
03:31:35   libevent-core-2.0-5 mysql-client-core-5.7 mysql-server-core-5.7
03:31:35 Use 'sudo apt autoremove' to remove them.
03:31:36 The following packages will be REMOVED:
03:31:36   libmysqlclient-dev* libmysqlclient20* mysql-client-5.7* mysql-common*
03:31:36   mysql-server* mysql-server-5.7* python-mysqldb*
03:31:37 0 upgraded, 0 newly installed, 7 to remove and 0 not upgraded.
03:31:37 After this operation, 94.2 MB disk space will be freed.
03:31:44 (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 ... 117945 files and directories currently installed.)
03:31:44 Removing libmysqlclient-dev (5.7.25-0ubuntu0.16.04.2) ...
03:31:44 Removing python-mysqldb (1.3.7-1build2) ...
03:31:44 Removing libmysqlclient20:amd64 (5.7.25-0ubuntu0.16.04.2) ...
03:31:44 Removing mysql-server (5.7.25-0ubuntu0.16.04.2) ...
03:31:44 Removing mysql-server-5.7 (5.7.25-0ubuntu0.16.04.2) ...
03:31:44 debconf: unable to initialize frontend: Dialog
03:31:44 debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.)
03:31:44 debconf: falling back to frontend: Readline
03:31:45 debconf: unable to initialize frontend: Dialog
03:31:45 debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.)
03:31:45 debconf: falling back to frontend: Readline
03:31:45 update-alternatives: using /etc/mysql/my.cnf.fallback to provide /etc/mysql/my.cnf (my.cnf) in auto mode
03:31:45 update-alternatives: warning: not replacing /etc/mysql/my.cnf with a link
03:31:45 Purging configuration files for mysql-server-5.7 (5.7.25-0ubuntu0.16.04.2) ...
03:31:46 debconf: unable to initialize frontend: Dialog
03:31:46 debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.)
03:31:46 debconf: falling back to frontend: Readline
03:31:46 Removing mysql-client-5.7 (5.7.25-0ubuntu0.16.04.2) ...
03:31:46 Removing mysql-common (5.7.25-0ubuntu0.16.04.2) ...
03:31:47 Purging configuration files for mysql-common (5.7.25-0ubuntu0.16.04.2) ...
03:31:47 dpkg: warning: while removing mysql-common, directory '/etc/mysql' not empty so not removed
03:31:47 Processing triggers for man-db (2.7.5-1) ...
03:31:47 Processing triggers for libc-bin (2.23-0ubuntu10) ...
03:31:49 Reading package lists...
03:31:50 Building dependency tree...
03:31:50 Reading state information...
03:31:50 The following packages will be REMOVED:
03:31:50   libevent-core-2.0-5* mysql-client-core-5.7* mysql-server-core-5.7*
03:31:50 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded.
03:31:50 After this operation, 77.9 MB disk space will be freed.
03:31:50 (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 ... 117643 files and directories currently installed.)
03:31:50 Removing libevent-core-2.0-5:amd64 (2.0.21-stable-2ubuntu0.16.04.1) ...
03:31:50 Removing mysql-client-core-5.7 (5.7.25-0ubuntu0.16.04.2) ...
03:31:50 Removing mysql-server-core-5.7 (5.7.25-0ubuntu0.16.04.2) ...
03:31:50 Processing triggers for libc-bin (2.23-0ubuntu10) ...
03:31:50 Processing triggers for man-db (2.7.5-1) ...
03:32:10 [EMC_VxFlexOS] $ /bin/sh -xe /tmp/jenkins7730898407900998950.sh
03:32:10 + rm -rf devstack-gate
03:32:10 + git clone http://git.openstack.org/openstack-infra/devstack-gate
03:32:10 Cloning into 'devstack-gate'...
03:32:12 + cd devstack-gate
03:32:12 + git apply /opt/git/patches/journalctl.patch
03:32:12 + sudo rm -rf /home/jenkins/workspace/EMC_VxFlexOS/logs /opt/stack/logs
03:32:12 [EMC_VxFlexOS] $ /bin/bash -xe /tmp/jenkins281987388024838496.sh
03:32:12 + export TEMPEST_CONCURRENCY=1
03:32:12 + TEMPEST_CONCURRENCY=1
03:32:12 + export PYTHONUNBUFFERED=true
03:32:12 + PYTHONUNBUFFERED=true
03:32:12 ++ expr 3 '*' 60 '*' 60000
03:32:12 + export BUILD_TIMEOUT=10800000
03:32:12 + BUILD_TIMEOUT=10800000
03:32:12 + export GIT_BASE=http://git.openstack.org
03:32:12 + GIT_BASE=http://git.openstack.org
03:32:12 + export ZUUL_PROJECT=openstack/cinder
03:32:12 + ZUUL_PROJECT=openstack/cinder
03:32:12 + export ZUUL_BRANCH=master
03:32:12 + ZUUL_BRANCH=master
03:32:12 + export DEVSTACK_GATE_TEMPEST=1
03:32:12 + DEVSTACK_GATE_TEMPEST=1
03:32:12 + export 'DEVSTACK_GATE_TEMPEST_REGEX=^(?=.*volume).*'
03:32:12 + DEVSTACK_GATE_TEMPEST_REGEX='^(?=.*volume).*'
03:32:12 + export RE_EXEC=true
03:32:12 + RE_EXEC=true
03:32:12 + export DRV_CFG=/opt/emc/scaleio/sdc/bin/drv_cfg
03:32:12 + DRV_CFG=/opt/emc/scaleio/sdc/bin/drv_cfg
03:32:12 + export MDM_IP=10.228.225.147,10.228.225.148
03:32:12 + MDM_IP=10.228.225.147,10.228.225.148
03:32:12 + sudo /opt/emc/scaleio/sdc/bin/drv_cfg --query_mdm
03:32:12 + grep 'Retrieved 0 mdm'
03:32:12 ++ echo http://10.228.225.15:8080/
03:32:12 ++ cut -f1 -d:
03:32:12 ++ cut -f3 -d/
03:32:12 + MASTER=10.228.225.15
03:32:12 + export ZUUL_URL=git@10.228.225.15:/var/lib/zuul/git
03:32:12 + ZUUL_URL=git@10.228.225.15:/var/lib/zuul/git
03:32:12 + ssh -o 'StrictHostKeyChecking no' git@10.228.225.15 date
03:32:13 Tue Feb 12 03:32:13 EST 2019
03:32:13 + export BASE=/opt/stack
03:32:13 + BASE=/opt/stack
03:32:13 + export OVERRIDE_ENABLED_SERVICES=n-api-meta,c-api,c-bak,c-sch,c-vol,cinder,g-api,g-reg,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,neutron,placement-api,q-svc,q-agt,q-dhcp,q-l3,q-meta,rabbit,s-account,s-container,s-object,s-proxy,tempest
03:32:13 + OVERRIDE_ENABLED_SERVICES=n-api-meta,c-api,c-bak,c-sch,c-vol,cinder,g-api,g-reg,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,neutron,placement-api,q-svc,q-agt,q-dhcp,q-l3,q-meta,rabbit,s-account,s-container,s-object,s-proxy,tempest
03:32:13 + export DEVSTACK_GATE_NEUTRON=1
03:32:13 + DEVSTACK_GATE_NEUTRON=1
03:32:13 + export LOCAL_CONF=/openstack/emc_local.conf
03:32:13 + LOCAL_CONF=/openstack/emc_local.conf
03:32:13 ++ cat /openstack/emc_local.conf
03:32:13 + export 'DEVSTACK_LOCAL_CONFIG=#
03:32:13 [[local|localrc]]
03:32:13 USE_PYTHON3=True
03:32:13 
03:32:13 DATABASE_QUERY_LOGGING=False
03:32:13 TEMPEST_VOLUME_DRIVER=EMC
03:32:13 TEMPEST_VOLUME_VENDOR='\''Dell EMC'\''
03:32:13 TEMPEST_STORAGE_PROTOCOL=scaleio
03:32:13 CINDER_ENABLED_BACKENDS=scaleio
03:32:13 DATABASE_QUERY_LOGGING=False
03:32:13 ENABLE_VOLUME_MULTIATTACH=True
03:32:13 
03:32:13 #PIP_GET_PIP_URL=http://10.228.225.108/rally/get-pip.py
03:32:13 # Do not download cirros image from internet.
03:32:13 DOWNLOAD_DEFAULT_IMAGES=False
03:32:13 # Specify the image we will use.
03:32:13 IMAGE_URLS=file:///opt/git/files/cirros-0.3.5-x86_64-disk.img
03:32:13 
03:32:13 [[post-config|$CINDER_CONF]]
03:32:13 [DEFAULT]
03:32:13 rpc_response_timeout=1000
03:32:13 service_down_time=1000
03:32:13 volume_name_template = cdh2h7-%s
03:32:13 max_pool_size = 80
03:32:13 max_overflow = 100
03:32:13 rpc_cast_timeout=600
03:32:13 use_multipath_for_image_xfer=true
03:32:13 default_volume_type = scaleio
03:32:13 enabled_backends = scaleio
03:32:13 #max_over_subscription_ratio = 10.0
03:32:13 
03:32:13 [scaleio]
03:32:13 volume_driver = cinder.volume.drivers.dell_emc.scaleio.driver.ScaleIODriver
03:32:13 volume_backend_name = scaleio
03:32:13 san_ip=10.228.225.150
03:32:13 san_login=admin
03:32:13 san_password=Danger0us
03:32:13 san_thin_provision=true
03:32:13 sio_protection_domain_name=OpenstackPD
03:32:13 sio_storage_pool_name=OpenstackSP
03:32:13 sio_storage_pools=OpenstackPD:OpenstackSP
03:32:13 sio_round_volume_capacity=True
03:32:13 sio_force_delete=True
03:32:13 sio_unmap_volume_before_deletion=True
03:32:13 #sio_allow_non_padded_thick_volumes=True
03:32:13 sio_allow_non_padded_volumes=True
03:32:13 num_volume_device_scan_tries=180
03:32:13 #sio_max_over_subscription_ratio = 10.0
03:32:13 image_volume_cache_enabled = True
03:32:13 #[scaleio]
03:32:13 #volume_driver = cinder.volume.drivers.emc.scaleio.ScaleIODriver
03:32:13 #volume_backend_name = scaleio
03:32:13 #san_ip=10.108.225.160
03:32:13 #san_login=admin
03:32:13 #san_password=Danger0us
03:32:13 #sio_protection_domain_name=default
03:32:13 #sio_storage_pool_name=pool2
03:32:13 #sio_storage_pools=default:pool2
03:32:13 #sio_round_volume_capacity=True
03:32:13 #sio_force_delete=True
03:32:13 #sio_unmap_volume_before_deletion=True
03:32:13 #num_volume_device_scan_tries=180
03:32:13 ##sio_max_over_subscription_ratio = 10.0
03:32:13 #
03:32:13 
03:32:13 [database]
03:32:13 max_pool_size = 80
03:32:13 max_overflow = 100
03:32:13 
03:32:13 [[post-config|$NOVA_CONF]]
03:32:13 [DEFAULT]
03:32:13 #libvirt_iscsi_use_multipath = true
03:32:13 #compute_driver = nova.virt.libvirt.LibvirtDriver
03:32:13 
03:32:13 [[test-config|$TEMPEST_CONFIG]]
03:32:13 [compute-feature-enabled]
03:32:13 volume_multiattach = True
03:32:13 #attach_encrypted_volume = False
03:32:13 #[DEFAULT]
03:32:13 #active_timeout = 600
03:32:13 [volume]
03:32:13 volume_size = 8
03:32:13 build_timeout = 600
03:32:13 disk_format = qcow2
03:32:13 [compute]
03:32:13 build_timeout = 600
03:32:13 ssh_timeout = 600
03:32:13 ping_timeout = 600
03:32:13 [image]
03:32:13 build_timeout = 600
03:32:13 #build_interval = 30
03:32:13 
03:32:13 [scenario]
03:32:13 img_dir = /opt/git/files
03:32:13 img_file = cirros-0.3.5-x86_64-disk.img
03:32:13 aki_img_file = cirros-0.3.5-x86_64-vmlinuz
03:32:13 ari_img_file = cirros-0.3.5-x86_64-initrd
03:32:13 ami_img_file = cirros-0.3.5-x86_64-blank.img
03:32:13 img_container_format = bare
03:32:13 img_disk_format = qcow2
03:32:13 
03:32:13 [volume-feature-enabled]
03:32:13 extend_attached_volume = True'
03:32:13 + DEVSTACK_LOCAL_CONFIG='#
03:32:13 [[local|localrc]]
03:32:13 USE_PYTHON3=True
03:32:13 
03:32:13 DATABASE_QUERY_LOGGING=False
03:32:13 TEMPEST_VOLUME_DRIVER=EMC
03:32:13 TEMPEST_VOLUME_VENDOR='\''Dell EMC'\''
03:32:13 TEMPEST_STORAGE_PROTOCOL=scaleio
03:32:13 CINDER_ENABLED_BACKENDS=scaleio
03:32:13 DATABASE_QUERY_LOGGING=False
03:32:13 ENABLE_VOLUME_MULTIATTACH=True
03:32:13 
03:32:13 #PIP_GET_PIP_URL=http://10.228.225.108/rally/get-pip.py
03:32:13 # Do not download cirros image from internet.
03:32:13 DOWNLOAD_DEFAULT_IMAGES=False
03:32:13 # Specify the image we will use.
03:32:13 IMAGE_URLS=file:///opt/git/files/cirros-0.3.5-x86_64-disk.img
03:32:13 
03:32:13 [[post-config|$CINDER_CONF]]
03:32:13 [DEFAULT]
03:32:13 rpc_response_timeout=1000
03:32:13 service_down_time=1000
03:32:13 volume_name_template = cdh2h7-%s
03:32:13 max_pool_size = 80
03:32:13 max_overflow = 100
03:32:13 rpc_cast_timeout=600
03:32:13 use_multipath_for_image_xfer=true
03:32:13 default_volume_type = scaleio
03:32:13 enabled_backends = scaleio
03:32:13 #max_over_subscription_ratio = 10.0
03:32:13 
03:32:13 [scaleio]
03:32:13 volume_driver = cinder.volume.drivers.dell_emc.scaleio.driver.ScaleIODriver
03:32:13 volume_backend_name = scaleio
03:32:13 san_ip=10.228.225.150
03:32:13 san_login=admin
03:32:13 san_password=Danger0us
03:32:13 san_thin_provision=true
03:32:13 sio_protection_domain_name=OpenstackPD
03:32:13 sio_storage_pool_name=OpenstackSP
03:32:13 sio_storage_pools=OpenstackPD:OpenstackSP
03:32:13 sio_round_volume_capacity=True
03:32:13 sio_force_delete=True
03:32:13 sio_unmap_volume_before_deletion=True
03:32:13 #sio_allow_non_padded_thick_volumes=True
03:32:13 sio_allow_non_padded_volumes=True
03:32:13 num_volume_device_scan_tries=180
03:32:13 #sio_max_over_subscription_ratio = 10.0
03:32:13 image_volume_cache_enabled = True
03:32:13 #[scaleio]
03:32:13 #volume_driver = cinder.volume.drivers.emc.scaleio.ScaleIODriver
03:32:13 #volume_backend_name = scaleio
03:32:13 #san_ip=10.108.225.160
03:32:13 #san_login=admin
03:32:13 #san_password=Danger0us
03:32:13 #sio_protection_domain_name=default
03:32:13 #sio_storage_pool_name=pool2
03:32:13 #sio_storage_pools=default:pool2
03:32:13 #sio_round_volume_capacity=True
03:32:13 #sio_force_delete=True
03:32:13 #sio_unmap_volume_before_deletion=True
03:32:13 #num_volume_device_scan_tries=180
03:32:13 ##sio_max_over_subscription_ratio = 10.0
03:32:13 #
03:32:13 
03:32:13 [database]
03:32:13 max_pool_size = 80
03:32:13 max_overflow = 100
03:32:13 
03:32:13 [[post-config|$NOVA_CONF]]
03:32:13 [DEFAULT]
03:32:13 #libvirt_iscsi_use_multipath = true
03:32:13 #compute_driver = nova.virt.libvirt.LibvirtDriver
03:32:13 
03:32:13 [[test-config|$TEMPEST_CONFIG]]
03:32:13 [compute-feature-enabled]
03:32:13 volume_multiattach = True
03:32:13 #attach_encrypted_volume = False
03:32:13 #[DEFAULT]
03:32:13 #active_timeout = 600
03:32:13 [volume]
03:32:13 volume_size = 8
03:32:13 build_timeout = 600
03:32:13 disk_format = qcow2
03:32:13 [compute]
03:32:13 build_timeout = 600
03:32:13 ssh_timeout = 600
03:32:13 ping_timeout = 600
03:32:13 [image]
03:32:13 build_timeout = 600
03:32:13 #build_interval = 30
03:32:13 
03:32:13 [scenario]
03:32:13 img_dir = /opt/git/files
03:32:13 img_file = cirros-0.3.5-x86_64-disk.img
03:32:13 aki_img_file = cirros-0.3.5-x86_64-vmlinuz
03:32:13 ari_img_file = cirros-0.3.5-x86_64-initrd
03:32:13 ami_img_file = cirros-0.3.5-x86_64-blank.img
03:32:13 img_container_format = bare
03:32:13 img_disk_format = qcow2
03:32:13 
03:32:13 [volume-feature-enabled]
03:32:13 extend_attached_volume = True'
03:32:13 + export -f remove_pre_devstack
03:32:13 + export -f sam_pre_tempest_hook
03:32:13 + export -f checkout
03:32:13 + export -f hot_patch
03:32:13 + export -f pre_test_hook
03:32:13 + export -f post_test_hook
03:32:13 + cp devstack-gate/devstack-vm-gate-wrap.sh ./safe-devstack-vm-gate-wrap.sh
03:32:13 + sed -i /tripleo/d ./safe-devstack-vm-gate-wrap.sh
03:32:13 + remove_pre_devstack
03:32:13 + mkdir logs
03:32:13 + ps --no-header -fustack
03:32:13 + awk '{print $2}'
03:32:13 + xargs -i sudo -ustack kill '{}'
03:32:14 + true
03:32:14 + sleep 5
03:32:19 + [[ -f /opt/stack/new/devstack/unstack.sh ]]
03:32:19 + sudo -ustack /opt/stack/new/devstack/unstack.sh --all
03:33:59 + [[ -f /opt/stack/new/devstack/clean.sh ]]
03:33:59 + sudo -ustack /opt/stack/new/devstack/clean.sh
03:34:25 + sudo rm -rf /opt/stack/new
03:34:25 + sudo service apache2 stop
03:34:29 + sudo service mysql stop
03:34:29 Failed to stop mysql.service: Unit mysql.service not loaded.
03:34:29 + true
03:34:29 + sudo service libvirt-bin stop
03:34:30 + [[ -d /var/log/apache2 ]]
03:34:30 + sudo find /var/log/apache2 -type f -exec rm '{}' ';'
03:34:30 + [[ -d /var/log/mysql ]]
03:34:30 + sudo find /var/log/mysql/ -type f -exec rm '{}' ';'
03:34:30 + [[ -d /var/log/libvirt ]]
03:34:30 + sudo find /var/log/libvirt -type f -exec rm '{}' ';'
03:34:30 + sudo service libvirt-bin start
03:34:30 + sed -i 's,^\(\s*\)\(/tmp/ansible/bin/ara .*\)$,\1#(sam) remove ARA report because it is both time and space consuming\n\1#\2,g' safe-devstack-vm-gate-wrap.sh
03:34:30 + ./safe-devstack-vm-gate-wrap.sh
03:34:30 apt-get is /usr/bin/apt-get
03:34:37 12 Feb 03:34:37 ntpdate[22989]: adjust time server 10.106.16.22 offset 0.000095 sec
03:34:38 The PROJECTS list is:
03:34:38 openstack/ceilometer openstack/ceilometermiddleware openstack/cinder 
03:34:38 openstack-dev/devstack openstack/glance openstack/glance_store openstack/heat 
03:34:38 openstack/heat-cfntools openstack/heat-templates openstack-infra/devstack-gate 
03:34:38 openstack/keystone openstack/keystoneauth openstack/keystonemiddleware 
03:34:38 openstack/manila openstack/neutron openstack/neutron-fwaas 
03:34:38 openstack/neutron-lbaas openstack/neutron-vpnaas openstack/nova 
03:34:38 openstack/octavia openstack/os-apply-config openstack/os-brick 
03:34:38 openstack/osc-lib openstack/os-client-config openstack/os-collect-config 
03:34:38 openstack/os-net-config openstack/os-refresh-config openstack/placement 
03:34:38 openstack/requirements openstack/swift openstack/tempest openstack/zaqar
03:34:38 ---
03:34:38 Triggered by: https://review.openstack.org/636098 patchset 3
03:34:38 Pipeline: check
03:34:38 Timeout set to 170 minutes with 10 minutes reserved for cleanup.
03:34:38 Available disk space on this host:
03:34:38     Filesystem                   Size  Used Avail Use% Mounted on
03:34:38     udev                         3.9G     0  3.9G   0% /dev
03:34:38     tmpfs                        799M  964K  798M   1% /run
03:34:38     /dev/sda2                     36G   12G   23G  34% /
03:34:38     tmpfs                        3.9G   80K  3.9G   1% /dev/shm
03:34:38     tmpfs                        5.0M     0  5.0M   0% /run/lock
03:34:38     tmpfs                        3.9G     0  3.9G   0% /sys/fs/cgroup
03:34:38     10.228.237.165:/backup       2.0T  442G  1.5T  24% /backup
03:34:38     10.228.225.108:/mirrors/git   50G   26G   22G  55% /opt/git
03:34:38     tmpfs                        799M     0  799M   0% /run/user/1002
03:34:38     tmpfs                        799M     0  799M   0% /run/user/1000
03:34:38 Requirement already satisfied: virtualenv in /usr/local/lib/python3.5/dist-packages
03:34:39 You are using pip version 9.0.3, however version 19.0.1 is available.
03:34:39 You should consider upgrading via the 'pip install --upgrade pip' command.
03:34:39 Using base prefix '/usr'
03:34:39 New python executable in /tmp/ansible/bin/python3
03:34:39 Not overwriting existing python script /tmp/ansible/bin/python (you must use /tmp/ansible/bin/python3)
03:34:40 Installing setuptools, pip, wheel...
03:34:44 done.
03:34:45 Requirement already satisfied: pbr in /tmp/ansible/lib/python3.5/site-packages (5.1.1)
03:34:46 Requirement already satisfied: ansible==2.5.7 in /tmp/ansible/lib/python3.5/site-packages (2.5.7)
03:34:46 Requirement already satisfied: devstack-tools==0.4.0 in /tmp/ansible/lib/python3.5/site-packages (0.4.0)
03:34:46 Requirement already satisfied: ara in /tmp/ansible/lib/python3.5/site-packages (0.16.2)
03:34:46 Requirement already satisfied: cmd2<0.9.0 in /tmp/ansible/lib/python3.5/site-packages (0.8.9)
Requirement already satisfied: cmd2<0.9.0 in /tmp/ansible/lib/python3.5/site-packages (0.8.9)
03:34:46 Requirement already satisfied: setuptools in /tmp/ansible/lib/python3.5/site-packages (from ansible==2.5.7) (40.8.0)
03:34:46 Requirement already satisfied: paramiko in /tmp/ansible/lib/python3.5/site-packages (from ansible==2.5.7) (2.4.2)
03:34:46 Requirement already satisfied: PyYAML in /tmp/ansible/lib/python3.5/site-packages (from ansible==2.5.7) (3.13)
03:34:46 Requirement already satisfied: jinja2 in /tmp/ansible/lib/python3.5/site-packages (from ansible==2.5.7) (2.10)
03:34:46 Requirement already satisfied: cryptography in /tmp/ansible/lib/python3.5/site-packages (from ansible==2.5.7) (2.4.2)
03:34:46 Requirement already satisfied: pbr>=1.8 in /tmp/ansible/lib/python3.5/site-packages (from devstack-tools==0.4.0) (5.1.1)
Requirement already satisfied: pbr>=1.8 in /tmp/ansible/lib/python3.5/site-packages (from devstack-tools==0.4.0) (5.1.1)
03:34:46 Requirement already satisfied: python-subunit in /tmp/ansible/lib/python3.5/site-packages (from ara) (1.3.0)
03:34:46 Requirement already satisfied: XStatic>=1.0.0 in /tmp/ansible/lib/python3.5/site-packages (from ara) (1.0.2)
Requirement already satisfied: XStatic>=1.0.0 in /tmp/ansible/lib/python3.5/site-packages (from ara) (1.0.2)
03:34:46 Requirement already satisfied: cliff in /tmp/ansible/lib/python3.5/site-packages (from ara) (2.14.0)
03:34:46 Requirement already satisfied: junit-xml>=1.7 in /tmp/ansible/lib/python3.5/site-packages (from ara) (1.8)
Requirement already satisfied: junit-xml>=1.7 in /tmp/ansible/lib/python3.5/site-packages (from ara) (1.8)
03:34:46 Requirement already satisfied: XStatic-Patternfly-Bootstrap-Treeview>=2.1.3 in /tmp/ansible/lib/python3.5/site-packages (from ara) (2.1.3.2)
Requirement already satisfied: XStatic-Patternfly-Bootstrap-Treeview>=2.1.3 in /tmp/ansible/lib/python3.5/site-packages (from ara) (2.1.3.2)
03:34:46 Requirement already satisfied: Flask-SQLAlchemy in /tmp/ansible/lib/python3.5/site-packages (from ara) (2.3.2)
03:34:46 Requirement already satisfied: pygments>=1.6 in /tmp/ansible/lib/python3.5/site-packages (from ara) (2.3.1)
Requirement already satisfied: pygments>=1.6 in /tmp/ansible/lib/python3.5/site-packages (from ara) (2.3.1)
03:34:46 Requirement already satisfied: six in /tmp/ansible/lib/python3.5/site-packages (from ara) (1.12.0)
03:34:46 Requirement already satisfied: Flask-Migrate in /tmp/ansible/lib/python3.5/site-packages (from ara) (2.3.1)
03:34:46 Requirement already satisfied: pyfakefs in /tmp/ansible/lib/python3.5/site-packages (from ara) (3.5.6)
03:34:46 Requirement already satisfied: Flask-Script in /tmp/ansible/lib/python3.5/site-packages (from ara) (2.0.6)
03:34:46 Requirement already satisfied: Frozen-Flask in /tmp/ansible/lib/python3.5/site-packages (from ara) (0.15)
03:34:46 Requirement already satisfied: XStatic-Bootstrap-SCSS>=3.3.7.1 in /tmp/ansible/lib/python3.5/site-packages (from ara) (3.3.7.1)
Requirement already satisfied: XStatic-Bootstrap-SCSS>=3.3.7.1 in /tmp/ansible/lib/python3.5/site-packages (from ara) (3.3.7.1)
03:34:46 Requirement already satisfied: XStatic-Patternfly>=3.21.0 in /tmp/ansible/lib/python3.5/site-packages (from ara) (3.21.0.1)
Requirement already satisfied: XStatic-Patternfly>=3.21.0 in /tmp/ansible/lib/python3.5/site-packages (from ara) (3.21.0.1)
03:34:46 Requirement already satisfied: oslo.utils>=3.20.0 in /tmp/ansible/lib/python3.5/site-packages (from ara) (3.40.1)
Requirement already satisfied: oslo.utils>=3.20.0 in /tmp/ansible/lib/python3.5/site-packages (from ara) (3.40.1)
03:34:46 Requirement already satisfied: XStatic-jQuery>=1.8.2.1 in /tmp/ansible/lib/python3.5/site-packages (from ara) (3.3.1.1)
Requirement already satisfied: XStatic-jQuery>=1.8.2.1 in /tmp/ansible/lib/python3.5/site-packages (from ara) (3.3.1.1)
03:34:46 Requirement already satisfied: oslo.serialization>=1.10.0 in /tmp/ansible/lib/python3.5/site-packages (from ara) (2.28.1)
Requirement already satisfied: oslo.serialization>=1.10.0 in /tmp/ansible/lib/python3.5/site-packages (from ara) (2.28.1)
03:34:46 Requirement already satisfied: XStatic-DataTables>=1.10.15 in /tmp/ansible/lib/python3.5/site-packages (from ara) (1.10.15.1)
Requirement already satisfied: XStatic-DataTables>=1.10.15 in /tmp/ansible/lib/python3.5/site-packages (from ara) (1.10.15.1)
03:34:46 Requirement already satisfied: Flask!=0.12.3,>=0.11 in /tmp/ansible/lib/python3.5/site-packages (from ara) (1.0.2)
Requirement already satisfied: Flask!=0.12.3,>=0.11 in /tmp/ansible/lib/python3.5/site-packages (from ara) (1.0.2)
03:34:46 Requirement already satisfied: wcwidth; sys_platform != "win32" in /tmp/ansible/lib/python3.5/site-packages (from cmd2<0.9.0) (0.1.7)
Requirement already satisfied: wcwidth; sys_platform != "win32" in /tmp/ansible/lib/python3.5/site-packages (from cmd2<0.9.0) (0.1.7)
03:34:46 Requirement already satisfied: pyperclip in /tmp/ansible/lib/python3.5/site-packages (from cmd2<0.9.0) (1.7.0)
Requirement already satisfied: pyperclip in /tmp/ansible/lib/python3.5/site-packages (from cmd2<0.9.0) (1.7.0)
03:34:46 Requirement already satisfied: pyparsing>=2.0.1 in /tmp/ansible/lib/python3.5/site-packages (from cmd2<0.9.0) (2.3.1)
Requirement already satisfied: pyparsing>=2.0.1 in /tmp/ansible/lib/python3.5/site-packages (from cmd2<0.9.0) (2.3.1)
03:34:46 Requirement already satisfied: bcrypt>=3.1.3 in /tmp/ansible/lib/python3.5/site-packages (from paramiko->ansible==2.5.7) (3.1.6)
Requirement already satisfied: bcrypt>=3.1.3 in /tmp/ansible/lib/python3.5/site-packages (from paramiko->ansible==2.5.7) (3.1.6)
03:34:46 Requirement already satisfied: pynacl>=1.0.1 in /tmp/ansible/lib/python3.5/site-packages (from paramiko->ansible==2.5.7) (1.3.0)
Requirement already satisfied: pynacl>=1.0.1 in /tmp/ansible/lib/python3.5/site-packages (from paramiko->ansible==2.5.7) (1.3.0)
03:34:46 Requirement already satisfied: pyasn1>=0.1.7 in /tmp/ansible/lib/python3.5/site-packages (from paramiko->ansible==2.5.7) (0.4.5)
Requirement already satisfied: pyasn1>=0.1.7 in /tmp/ansible/lib/python3.5/site-packages (from paramiko->ansible==2.5.7) (0.4.5)
03:34:46 Requirement already satisfied: MarkupSafe>=0.23 in /tmp/ansible/lib/python3.5/site-packages (from jinja2->ansible==2.5.7) (1.1.0)
Requirement already satisfied: MarkupSafe>=0.23 in /tmp/ansible/lib/python3.5/site-packages (from jinja2->ansible==2.5.7) (1.1.0)
03:34:46 Requirement already satisfied: idna>=2.1 in /tmp/ansible/lib/python3.5/site-packages (from cryptography->ansible==2.5.7) (2.8)
Requirement already satisfied: idna>=2.1 in /tmp/ansible/lib/python3.5/site-packages (from cryptography->ansible==2.5.7) (2.8)
03:34:46 Requirement already satisfied: cffi!=1.11.3,>=1.7 in /tmp/ansible/lib/python3.5/site-packages (from cryptography->ansible==2.5.7) (1.11.5)
Requirement already satisfied: cffi!=1.11.3,>=1.7 in /tmp/ansible/lib/python3.5/site-packages (from cryptography->ansible==2.5.7) (1.11.5)
03:34:46 Requirement already satisfied: asn1crypto>=0.21.0 in /tmp/ansible/lib/python3.5/site-packages (from cryptography->ansible==2.5.7) (0.24.0)
Requirement already satisfied: asn1crypto>=0.21.0 in /tmp/ansible/lib/python3.5/site-packages (from cryptography->ansible==2.5.7) (0.24.0)
03:34:46 Requirement already satisfied: testtools>=0.9.34 in /tmp/ansible/lib/python3.5/site-packages (from python-subunit->ara) (2.3.0)
Requirement already satisfied: testtools>=0.9.34 in /tmp/ansible/lib/python3.5/site-packages (from python-subunit->ara) (2.3.0)
03:34:46 Requirement already satisfied: extras in /tmp/ansible/lib/python3.5/site-packages (from python-subunit->ara) (1.0.0)
Requirement already satisfied: extras in /tmp/ansible/lib/python3.5/site-packages (from python-subunit->ara) (1.0.0)
03:34:46 Requirement already satisfied: stevedore>=1.20.0 in /tmp/ansible/lib/python3.5/site-packages (from cliff->ara) (1.30.0)
Requirement already satisfied: stevedore>=1.20.0 in /tmp/ansible/lib/python3.5/site-packages (from cliff->ara) (1.30.0)
03:34:46 Requirement already satisfied: PrettyTable<0.8,>=0.7.2 in /tmp/ansible/lib/python3.5/site-packages (from cliff->ara) (0.7.2)
Requirement already satisfied: PrettyTable<0.8,>=0.7.2 in /tmp/ansible/lib/python3.5/site-packages (from cliff->ara) (0.7.2)
03:34:46 Requirement already satisfied: SQLAlchemy>=0.8.0 in /tmp/ansible/lib/python3.5/site-packages (from Flask-SQLAlchemy->ara) (1.2.16)
Requirement already satisfied: SQLAlchemy>=0.8.0 in /tmp/ansible/lib/python3.5/site-packages (from Flask-SQLAlchemy->ara) (1.2.16)
03:34:46 Requirement already satisfied: alembic>=0.7 in /tmp/ansible/lib/python3.5/site-packages (from Flask-Migrate->ara) (1.0.6)
Requirement already satisfied: alembic>=0.7 in /tmp/ansible/lib/python3.5/site-packages (from Flask-Migrate->ara) (1.0.6)
03:34:46 Requirement already satisfied: oslo.i18n>=3.15.3 in /tmp/ansible/lib/python3.5/site-packages (from oslo.utils>=3.20.0->ara) (3.23.0)
Requirement already satisfied: oslo.i18n>=3.15.3 in /tmp/ansible/lib/python3.5/site-packages (from oslo.utils>=3.20.0->ara) (3.23.0)
03:34:46 Requirement already satisfied: netaddr>=0.7.18 in /tmp/ansible/lib/python3.5/site-packages (from oslo.utils>=3.20.0->ara) (0.7.19)
Requirement already satisfied: netaddr>=0.7.18 in /tmp/ansible/lib/python3.5/site-packages (from oslo.utils>=3.20.0->ara) (0.7.19)
03:34:46 Requirement already satisfied: netifaces>=0.10.4 in /tmp/ansible/lib/python3.5/site-packages (from oslo.utils>=3.20.0->ara) (0.10.9)
Requirement already satisfied: netifaces>=0.10.4 in /tmp/ansible/lib/python3.5/site-packages (from oslo.utils>=3.20.0->ara) (0.10.9)
03:34:46 Requirement already satisfied: pytz>=2013.6 in /tmp/ansible/lib/python3.5/site-packages (from oslo.utils>=3.20.0->ara) (2018.9)
Requirement already satisfied: pytz>=2013.6 in /tmp/ansible/lib/python3.5/site-packages (from oslo.utils>=3.20.0->ara) (2018.9)
03:34:46 Requirement already satisfied: debtcollector>=1.2.0 in /tmp/ansible/lib/python3.5/site-packages (from oslo.utils>=3.20.0->ara) (1.20.0)
Requirement already satisfied: debtcollector>=1.2.0 in /tmp/ansible/lib/python3.5/site-packages (from oslo.utils>=3.20.0->ara) (1.20.0)
03:34:46 Requirement already satisfied: iso8601>=0.1.11 in /tmp/ansible/lib/python3.5/site-packages (from oslo.utils>=3.20.0->ara) (0.1.12)
Requirement already satisfied: iso8601>=0.1.11 in /tmp/ansible/lib/python3.5/site-packages (from oslo.utils>=3.20.0->ara) (0.1.12)
03:34:46 Requirement already satisfied: msgpack>=0.5.2 in /tmp/ansible/lib/python3.5/site-packages (from oslo.serialization>=1.10.0->ara) (0.6.0)
Requirement already satisfied: msgpack>=0.5.2 in /tmp/ansible/lib/python3.5/site-packages (from oslo.serialization>=1.10.0->ara) (0.6.0)
03:34:46 Requirement already satisfied: itsdangerous>=0.24 in /tmp/ansible/lib/python3.5/site-packages (from Flask!=0.12.3,>=0.11->ara) (1.1.0)
Requirement already satisfied: itsdangerous>=0.24 in /tmp/ansible/lib/python3.5/site-packages (from Flask!=0.12.3,>=0.11->ara) (1.1.0)
03:34:46 Requirement already satisfied: Werkzeug>=0.14 in /tmp/ansible/lib/python3.5/site-packages (from Flask!=0.12.3,>=0.11->ara) (0.14.1)
Requirement already satisfied: Werkzeug>=0.14 in /tmp/ansible/lib/python3.5/site-packages (from Flask!=0.12.3,>=0.11->ara) (0.14.1)
03:34:46 Requirement already satisfied: click>=5.1 in /tmp/ansible/lib/python3.5/site-packages (from Flask!=0.12.3,>=0.11->ara) (7.0)
Requirement already satisfied: click>=5.1 in /tmp/ansible/lib/python3.5/site-packages (from Flask!=0.12.3,>=0.11->ara) (7.0)
03:34:46 Requirement already satisfied: pycparser in /tmp/ansible/lib/python3.5/site-packages (from cffi!=1.11.3,>=1.7->cryptography->ansible==2.5.7) (2.19)
Requirement already satisfied: pycparser in /tmp/ansible/lib/python3.5/site-packages (from cffi!=1.11.3,>=1.7->cryptography->ansible==2.5.7) (2.19)
03:34:46 Requirement already satisfied: fixtures>=1.3.0 in /tmp/ansible/lib/python3.5/site-packages (from testtools>=0.9.34->python-subunit->ara) (3.0.0)
Requirement already satisfied: fixtures>=1.3.0 in /tmp/ansible/lib/python3.5/site-packages (from testtools>=0.9.34->python-subunit->ara) (3.0.0)
03:34:46 Requirement already satisfied: unittest2>=1.0.0 in /tmp/ansible/lib/python3.5/site-packages (from testtools>=0.9.34->python-subunit->ara) (1.1.0)
Requirement already satisfied: unittest2>=1.0.0 in /tmp/ansible/lib/python3.5/site-packages (from testtools>=0.9.34->python-subunit->ara) (1.1.0)
03:34:46 Requirement already satisfied: python-mimeparse in /tmp/ansible/lib/python3.5/site-packages (from testtools>=0.9.34->python-subunit->ara) (1.6.0)
Requirement already satisfied: python-mimeparse in /tmp/ansible/lib/python3.5/site-packages (from testtools>=0.9.34->python-subunit->ara) (1.6.0)
03:34:46 Requirement already satisfied: traceback2 in /tmp/ansible/lib/python3.5/site-packages (from testtools>=0.9.34->python-subunit->ara) (1.4.0)
Requirement already satisfied: traceback2 in /tmp/ansible/lib/python3.5/site-packages (from testtools>=0.9.34->python-subunit->ara) (1.4.0)
03:34:46 Requirement already satisfied: python-editor>=0.3 in /tmp/ansible/lib/python3.5/site-packages (from alembic>=0.7->Flask-Migrate->ara) (1.0.3)
Requirement already satisfied: python-editor>=0.3 in /tmp/ansible/lib/python3.5/site-packages (from alembic>=0.7->Flask-Migrate->ara) (1.0.3)
03:34:46 Requirement already satisfied: python-dateutil in /tmp/ansible/lib/python3.5/site-packages (from alembic>=0.7->Flask-Migrate->ara) (2.7.5)
Requirement already satisfied: python-dateutil in /tmp/ansible/lib/python3.5/site-packages (from alembic>=0.7->Flask-Migrate->ara) (2.7.5)
03:34:46 Requirement already satisfied: Mako in /tmp/ansible/lib/python3.5/site-packages (from alembic>=0.7->Flask-Migrate->ara) (1.0.7)
Requirement already satisfied: Mako in /tmp/ansible/lib/python3.5/site-packages (from alembic>=0.7->Flask-Migrate->ara) (1.0.7)
03:34:46 Requirement already satisfied: Babel!=2.4.0,>=2.3.4 in /tmp/ansible/lib/python3.5/site-packages (from oslo.i18n>=3.15.3->oslo.utils>=3.20.0->ara) (2.6.0)
Requirement already satisfied: Babel!=2.4.0,>=2.3.4 in /tmp/ansible/lib/python3.5/site-packages (from oslo.i18n>=3.15.3->oslo.utils>=3.20.0->ara) (2.6.0)
03:34:46 Requirement already satisfied: wrapt>=1.7.0 in /tmp/ansible/lib/python3.5/site-packages (from debtcollector>=1.2.0->oslo.utils>=3.20.0->ara) (1.11.1)
Requirement already satisfied: wrapt>=1.7.0 in /tmp/ansible/lib/python3.5/site-packages (from debtcollector>=1.2.0->oslo.utils>=3.20.0->ara) (1.11.1)
03:34:46 Requirement already satisfied: argparse in /tmp/ansible/lib/python3.5/site-packages (from unittest2>=1.0.0->testtools>=0.9.34->python-subunit->ara) (1.4.0)
Requirement already satisfied: argparse in /tmp/ansible/lib/python3.5/site-packages (from unittest2>=1.0.0->testtools>=0.9.34->python-subunit->ara) (1.4.0)
03:34:46 Requirement already satisfied: linecache2 in /tmp/ansible/lib/python3.5/site-packages (from traceback2->testtools>=0.9.34->python-subunit->ara) (1.0.0)
Requirement already satisfied: linecache2 in /tmp/ansible/lib/python3.5/site-packages (from traceback2->testtools>=0.9.34->python-subunit->ara) (1.0.0)
03:34:46 cat: /etc/nodepool/primary_node_private: No such file or directory
03:34:46 cat: /etc/nodepool/sub_nodes_private: No such file or directory
03:34:47  [WARNING]: Could not match supplied host pattern, ignoring: subnodes
03:34:47  [WARNING]: No hosts matched, nothing to do
03:34:47  [WARNING]: Could not match supplied host pattern, ignoring: subnodes
03:34:47  [WARNING]: No hosts matched, nothing to do
03:34:49 localhost | SUCCESS => {
localhost | SUCCESS => {
03:34:49     "changed": true,
    "changed": true,
03:34:49     "path": "/home/jenkins/workspace/EMC_VxFlexOS/logs",
    "path": "/home/jenkins/workspace/EMC_VxFlexOS/logs",
03:34:49     "state": "absent"
    "state": "absent"
03:34:49 }
03:34:50 localhost | SUCCESS => {
localhost | SUCCESS => {
03:34:50     "changed": true,
    "changed": true,
03:34:50     "gid": 1002,
    "gid": 1002,
03:34:50     "group": "jenkins",
    "group": "jenkins",
03:34:50     "mode": "0775",
    "mode": "0775",
03:34:50     "owner": "jenkins",
    "owner": "jenkins",
03:34:50     "path": "/home/jenkins/workspace/EMC_VxFlexOS/logs",
    "path": "/home/jenkins/workspace/EMC_VxFlexOS/logs",
03:34:50     "size": 4096,
    "size": 4096,
03:34:50     "state": "directory",
    "state": "directory",
03:34:50     "uid": 1002
    "uid": 1002
03:34:50 }
03:34:50 Setting up the hosts
03:34:50 ... this takes a few seconds (logs at logs/devstack-gate-setup-host.txt.gz)
03:35:14 Setting up the workspace
03:35:14 ... this takes 3 - 5 minutes (logs at logs/devstack-gate-setup-workspace-new.txt.gz)
03:42:49 localhost | SUCCESS | rc=0 >>
localhost | SUCCESS | rc=0 >>
03:42:49 
03:42:49 Job timeout set to: 157 minutes
03:42:49 Running pre_test_hook
03:42:49 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:call_hook_if_defined:L82:   tsfilter pre_test_hook
03:42:49 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:tsfilter:L93:   pre_test_hook
03:42:49 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:tsfilter:L101:   awk '
03:42:49     {
03:42:49         cmd ="date +\"%Y-%m-%d %H:%M:%S.%3N | \""
        cmd ="date +\"%Y-%m-%d %H:%M:%S.%3N | \""
03:42:49         cmd | getline now
03:42:49         close("date +\"%Y-%m-%d %H:%M:%S.%3N | \"")
        close("date +\"%Y-%m-%d %H:%M:%S.%3N | \"")
03:42:49         sub(/^/, now)
03:42:49         print
03:42:49         fflush()
03:42:49 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:call_hook_if_defined:L82:   tee /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate-pre_test_hook.txt
03:42:49     }'
03:42:49 2019-02-12 03:42:49.145 | + :pre_test_hook:L0:   echo pre_test_hook
03:42:49 2019-02-12 03:42:49.146 | pre_test_hook
03:42:49 2019-02-12 03:42:49.147 | + :pre_test_hook:L1:   '[' -f /opt/git/files/cirros-0.3.5-x86_64-uec.tar.gz ']'
03:42:49 2019-02-12 03:42:49.148 | + :pre_test_hook:L2:   mkdir -p /opt/stack/new/devstack/files
03:42:49 2019-02-12 03:42:49.150 | + :pre_test_hook:L3:   cp /opt/git/files/cirros-0.3.5-x86_64-uec.tar.gz /opt/stack/new/devstack/files
03:42:49 2019-02-12 03:42:49.248 | + :pre_test_hook:L5:   '[' -f /opt/git/files/get-pip.py ']'
03:42:49 2019-02-12 03:42:49.250 | + :pre_test_hook:L6:   mkdir -p /opt/stack/new/devstack/files
03:42:49 2019-02-12 03:42:49.252 | + :pre_test_hook:L7:   cp /opt/git/files/get-pip.py /opt/stack/new/devstack/files
03:42:49 2019-02-12 03:42:49.271 | + :pre_test_hook:L9:   sudo sed -i '/echo "Loading previous tempest runs subunit streams into testr"/i\
2019-02-12 03:42:49.271 | + :pre_test_hook:L9:   sudo sed -i '/echo "Loading previous tempest runs subunit streams into testr"/i\
03:42:49 2019-02-12 03:42:49.272 |         call_hook_if_defined "sam_pre_tempest_hook"
2019-02-12 03:42:49.272 |         call_hook_if_defined "sam_pre_tempest_hook"
03:42:49 2019-02-12 03:42:49.274 |     ' /opt/stack/new/devstack-gate/devstack-vm-gate.sh
03:42:49 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:tsfilter:L103:   return 0
03:42:49 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:call_hook_if_defined:L83:   local ret_val=0
03:42:49 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:call_hook_if_defined:L84:   sudo mv /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate-pre_test_hook.txt /opt/stack/logs/
03:42:49 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:call_hook_if_defined:L85:   set +o pipefail
03:42:49 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:call_hook_if_defined:L86:   set +o xtrace
03:42:49 Running gate_hook
03:42:49 Job timeout set to: 157 minutes
03:42:49 +++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L26:   dirname /opt/stack/new/devstack-gate/devstack-vm-gate.sh
03:42:49 ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L26:   cd /opt/stack/new/devstack-gate
03:42:49 ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L26:   pwd
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L26:   TOP_DIR=/opt/stack/new/devstack-gate
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L32:   source /opt/stack/new/devstack-gate/functions.sh
03:42:49 ++ /opt/stack/new/devstack-gate/functions.sh:source:L19:   SUDO=sudo
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L37:   source /opt/stack/new/devstack/inc/ini-config
03:42:49 +++ /opt/stack/new/devstack/inc/ini-config:source:L10:   set +o
03:42:49 +++ /opt/stack/new/devstack/inc/ini-config:source:L10:   grep xtrace
03:42:49 ++ /opt/stack/new/devstack/inc/ini-config:source:L10:   INC_CONF_TRACE='set -o xtrace'
03:42:49 ++ /opt/stack/new/devstack/inc/ini-config:source:L11:   set +o xtrace
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L47:   echo 32225
03:42:49 +++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L48:   readlink -f /opt/stack/new/devstack-gate/devstack-vm-gate.sh
03:42:49 ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L48:   dirname /opt/stack/new/devstack-gate/devstack-vm-gate.sh
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L48:   source /opt/stack/new/devstack-gate/functions.sh
03:42:49 ++ /opt/stack/new/devstack-gate/functions.sh:source:L19:   SUDO=sudo
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L51:   FIXED_RANGE=10.1.0.0/20
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L52:   IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L53:   FLOATING_RANGE=172.24.5.0/24
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L54:   PUBLIC_NETWORK_GATEWAY=172.24.5.1
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L66:   FLOATING_HOST_PREFIX=172.24.4
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L67:   FLOATING_HOST_MASK=23
03:42:49 ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L70:   ip link show
03:42:49 ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L70:   sed -ne 's/.*mtu \([0-9]\+\).*/\1/p'
03:42:49 ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L70:   head -1
03:42:49 ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L70:   sort -n
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L70:   LOCAL_MTU=1500
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L73:   EXTERNAL_BRIDGE_MTU=1450
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L664:   [[ -n '' ]]
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L729:   cd /opt/stack/new/devstack
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L730:   setup_localrc new local.conf primary
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L265:   local localrc_oldnew=new
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L266:   local localrc_file=local.conf
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L267:   local role=primary
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L273:   local branch_for_matrix=master
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L277:   [[ -z '' ]]
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L278:   rm -f local.conf
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L282:   [[ ! -z n-api-meta,c-api,c-bak,c-sch,c-vol,cinder,g-api,g-reg,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,neutron,placement-api,q-svc,q-agt,q-dhcp,q-l3,q-meta,rabbit,s-account,s-container,s-object,s-proxy,tempest ]]
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L283:   MY_ENABLED_SERVICES=n-api-meta,c-api,c-bak,c-sch,c-vol,cinder,g-api,g-reg,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,neutron,placement-api,q-svc,q-agt,q-dhcp,q-l3,q-meta,rabbit,s-account,s-container,s-object,s-proxy,tempest
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L328:   [[ ! -z '' ]]
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L332:   [[ '' == \1 ]]
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L338:   SKIP_EXERCISES=boot_from_volume,bundle,client-env,euca
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L340:   [[ 1 -eq 1 ]]
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L341:   localrc_set local.conf Q_USE_DEBUG_COMMAND True
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=Q_USE_DEBUG_COMMAND
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf Q_USE_DEBUG_COMMAND True
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L342:   localrc_set local.conf NETWORK_GATEWAY 10.1.0.1
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=NETWORK_GATEWAY
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=10.1.0.1
03:42:49 + /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
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L345:   [[ 0 -eq 1 ]]
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L355:   localrc_set local.conf USE_SCREEN False
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=USE_SCREEN
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf USE_SCREEN False
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L356:   localrc_set local.conf DEST /opt/stack/new
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=DEST
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=/opt/stack/new
03:42:49 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf DEST /opt/stack/new
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L358:   localrc_set local.conf DATA_DIR /opt/stack/data
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=DATA_DIR
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=/opt/stack/data
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf DATA_DIR /opt/stack/data
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L359:   localrc_set local.conf ACTIVE_TIMEOUT 90
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=ACTIVE_TIMEOUT
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=90
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf ACTIVE_TIMEOUT 90
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L360:   localrc_set local.conf BOOT_TIMEOUT 90
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=BOOT_TIMEOUT
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=90
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf BOOT_TIMEOUT 90
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L361:   localrc_set local.conf ASSOCIATE_TIMEOUT 60
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=ASSOCIATE_TIMEOUT
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=60
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf ASSOCIATE_TIMEOUT 60
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L362:   localrc_set local.conf TERMINATE_TIMEOUT 60
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=TERMINATE_TIMEOUT
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=60
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf TERMINATE_TIMEOUT 60
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L363:   localrc_set local.conf MYSQL_PASSWORD secretmysql
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=MYSQL_PASSWORD
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=secretmysql
03:42:50 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf MYSQL_PASSWORD secretmysql
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L364:   localrc_set local.conf DATABASE_PASSWORD secretdatabase
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=DATABASE_PASSWORD
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=secretdatabase
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf DATABASE_PASSWORD secretdatabase
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L365:   localrc_set local.conf RABBIT_PASSWORD secretrabbit
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=RABBIT_PASSWORD
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=secretrabbit
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf RABBIT_PASSWORD secretrabbit
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L366:   localrc_set local.conf ADMIN_PASSWORD secretadmin
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=ADMIN_PASSWORD
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=secretadmin
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf ADMIN_PASSWORD secretadmin
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L367:   localrc_set local.conf SERVICE_PASSWORD secretservice
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SERVICE_PASSWORD
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=secretservice
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SERVICE_PASSWORD secretservice
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L368:   localrc_set local.conf SERVICE_TOKEN 111222333444
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SERVICE_TOKEN
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=111222333444
03:42:51 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SERVICE_TOKEN 111222333444
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L369:   localrc_set local.conf SWIFT_HASH 1234123412341234
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SWIFT_HASH
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=1234123412341234
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SWIFT_HASH 1234123412341234
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L370:   localrc_set local.conf ROOTSLEEP 0
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=ROOTSLEEP
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=0
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf ROOTSLEEP 0
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L376:   localrc_set local.conf ERROR_ON_CLONE True
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=ERROR_ON_CLONE
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf ERROR_ON_CLONE True
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L383:   localrc_set local.conf INSTALL_TEMPEST False
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=INSTALL_TEMPEST
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf INSTALL_TEMPEST False
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L385:   localrc_set local.conf NOVNC_FROM_PACKAGE True
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=NOVNC_FROM_PACKAGE
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf NOVNC_FROM_PACKAGE True
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L386:   localrc_set local.conf ENABLED_SERVICES n-api-meta,c-api,c-bak,c-sch,c-vol,cinder,g-api,g-reg,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,neutron,placement-api,q-svc,q-agt,q-dhcp,q-l3,q-meta,rabbit,s-account,s-container,s-object,s-proxy,tempest
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=ENABLED_SERVICES
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=n-api-meta,c-api,c-bak,c-sch,c-vol,cinder,g-api,g-reg,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,neutron,placement-api,q-svc,q-agt,q-dhcp,q-l3,q-meta,rabbit,s-account,s-container,s-object,s-proxy,tempest
03:42:52 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf ENABLED_SERVICES n-api-meta,c-api,c-bak,c-sch,c-vol,cinder,g-api,g-reg,key,mysql,n-api,n-cond,n-cpu,n-crt,n-obj,n-sch,neutron,placement-api,q-svc,q-agt,q-dhcp,q-l3,q-meta,rabbit,s-account,s-container,s-object,s-proxy,tempest
03:42:53 + /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
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SKIP_EXERCISES
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=boot_from_volume,bundle,client-env,euca
03:42:53 + /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
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L389:   localrc_set local.conf SYSLOG False
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SYSLOG
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SYSLOG False
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L390:   localrc_set local.conf SCREEN_LOGDIR /opt/stack/new/screen-logs
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SCREEN_LOGDIR
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=/opt/stack/new/screen-logs
03:42:53 + /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
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L391:   localrc_set local.conf LOGFILE /opt/stack/new/devstacklog.txt
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=LOGFILE
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=/opt/stack/new/devstacklog.txt
03:42:53 + /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
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L392:   localrc_set local.conf VERBOSE True
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=VERBOSE
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
03:42:53 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf VERBOSE True
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L393:   localrc_set local.conf FIXED_RANGE 10.1.0.0/20
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=FIXED_RANGE
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=10.1.0.0/20
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf FIXED_RANGE 10.1.0.0/20
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L394:   localrc_set local.conf IPV4_ADDRS_SAFE_TO_USE 10.1.0.0/20
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=IPV4_ADDRS_SAFE_TO_USE
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=10.1.0.0/20
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf IPV4_ADDRS_SAFE_TO_USE 10.1.0.0/20
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L395:   localrc_set local.conf FLOATING_RANGE 172.24.5.0/24
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=FLOATING_RANGE
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=172.24.5.0/24
03:42:54 + /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
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L396:   localrc_set local.conf PUBLIC_NETWORK_GATEWAY 172.24.5.1
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=PUBLIC_NETWORK_GATEWAY
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=172.24.5.1
03:42:54 + /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
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L397:   localrc_set local.conf FIXED_NETWORK_SIZE 4096
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=FIXED_NETWORK_SIZE
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=4096
03:42:54 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf FIXED_NETWORK_SIZE 4096
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L398:   localrc_set local.conf VIRT_DRIVER libvirt
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=VIRT_DRIVER
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=libvirt
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf VIRT_DRIVER libvirt
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L399:   localrc_set local.conf SWIFT_REPLICAS 1
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SWIFT_REPLICAS
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=1
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SWIFT_REPLICAS 1
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L400:   localrc_set local.conf SWIFT_START_ALL_SERVICES False
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=SWIFT_START_ALL_SERVICES
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf SWIFT_START_ALL_SERVICES False
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L401:   localrc_set local.conf LOG_COLOR False
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=LOG_COLOR
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf LOG_COLOR False
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L403:   localrc_set local.conf UNDO_REQUIREMENTS False
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=UNDO_REQUIREMENTS
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
03:42:55 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf UNDO_REQUIREMENTS False
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L404:   localrc_set local.conf CINDER_PERIODIC_INTERVAL 10
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=CINDER_PERIODIC_INTERVAL
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=10
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf CINDER_PERIODIC_INTERVAL 10
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L406:   localrc_set local.conf 'export OS_NO_CACHE' True
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local 'key=export OS_NO_CACHE'
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf 'export OS_NO_CACHE' True
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L407:   localrc_set local.conf LIBS_FROM_GIT ''
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=LIBS_FROM_GIT
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf LIBS_FROM_GIT ''
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L411:   localrc_set local.conf EBTABLES_RACE_FIX True
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=EBTABLES_RACE_FIX
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf EBTABLES_RACE_FIX True
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L414:   localrc_set local.conf DEBUG_LIBVIRT_COREDUMPS True
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=DEBUG_LIBVIRT_COREDUMPS
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=True
03:42:56 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf DEBUG_LIBVIRT_COREDUMPS True
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L416:   [[ aio == \m\u\l\t\i\n\o\d\e ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L421:   localrc_set local.conf CINDER_VOLUME_CLEAR none
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=CINDER_VOLUME_CLEAR
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=none
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf CINDER_VOLUME_CLEAR none
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L423:   [[ 0 -eq 1 ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L429:   [[ libvirt == \l\i\b\v\i\r\t ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L430:   [[ -n qemu ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L431:   localrc_set local.conf LIBVIRT_TYPE qemu
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=LIBVIRT_TYPE
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=qemu
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf LIBVIRT_TYPE qemu
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L435:   [[ libvirt == \i\r\o\n\i\c ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L464:   [[ libvirt == \x\e\n\a\p\i ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L507:   [[ 1 -eq 1 ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L514:   localrc_set local.conf VOLUME_BACKING_FILE_SIZE 24G
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=VOLUME_BACKING_FILE_SIZE
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=24G
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf VOLUME_BACKING_FILE_SIZE 24G
03:42:57 + /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
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=TEMPEST_HTTP_IMAGE
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=http://git.openstack.org/static/openstack.png
03:42:57 + /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
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L521:   [[ 0 -eq 1 ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L525:   [[ -n '' ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L534:   [[ 0 -eq 1 ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L538:   [[ 0 -gt 1 ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L547:   [[ 0 -eq 1 ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L550:   localrc_set local.conf FORCE_CONFIG_DRIVE False
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L41:   local lcfile=local.conf
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L42:   local key=FORCE_CONFIG_DRIVE
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L43:   local value=False
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:localrc_set:L44:   /tmp/ansible/bin/dsconf setlc local.conf FORCE_CONFIG_DRIVE False
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L553:   [[ -n '' ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L559:   [[ 0 -eq 1 ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L564:   [[ aio != \a\i\o ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L611:   [[ -e /tmp/dg-local.conf ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L622:   [[ primary = sub ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L633:   [[ -n #
03:42:57 [[local|localrc]]
03:42:57 USE_PYTHON3=True
03:42:57 
03:42:57 DATABASE_QUERY_LOGGING=False
03:42:57 TEMPEST_VOLUME_DRIVER=EMC
03:42:57 TEMPEST_VOLUME_VENDOR='Dell EMC'
03:42:57 TEMPEST_STORAGE_PROTOCOL=scaleio
03:42:57 CINDER_ENABLED_BACKENDS=scaleio
03:42:57 DATABASE_QUERY_LOGGING=False
03:42:57 ENABLE_VOLUME_MULTIATTACH=True
03:42:57 
03:42:57 #PIP_GET_PIP_URL=http://10.228.225.108/rally/get-pip.py
03:42:57 # Do not download cirros image from internet.
03:42:57 DOWNLOAD_DEFAULT_IMAGES=False
03:42:57 # Specify the image we will use.
03:42:57 IMAGE_URLS=file:///opt/git/files/cirros-0.3.5-x86_64-disk.img
03:42:57 
03:42:57 [[post-config|$CINDER_CONF]]
03:42:57 [DEFAULT]
03:42:57 rpc_response_timeout=1000
03:42:57 service_down_time=1000
03:42:57 volume_name_template = cdh2h7-%s
03:42:57 max_pool_size = 80
03:42:57 max_overflow = 100
03:42:57 rpc_cast_timeout=600
03:42:57 use_multipath_for_image_xfer=true
03:42:57 default_volume_type = scaleio
03:42:57 enabled_backends = scaleio
03:42:57 #max_over_subscription_ratio = 10.0
03:42:57 
03:42:57 [scaleio]
03:42:57 volume_driver = cinder.volume.drivers.dell_emc.scaleio.driver.ScaleIODriver
03:42:57 volume_backend_name = scaleio
03:42:57 san_ip=10.228.225.150
03:42:57 san_login=admin
03:42:57 san_password=Danger0us
03:42:57 san_thin_provision=true
03:42:57 sio_protection_domain_name=OpenstackPD
03:42:57 sio_storage_pool_name=OpenstackSP
03:42:57 sio_storage_pools=OpenstackPD:OpenstackSP
03:42:57 sio_round_volume_capacity=True
03:42:57 sio_force_delete=True
03:42:57 sio_unmap_volume_before_deletion=True
03:42:57 #sio_allow_non_padded_thick_volumes=True
03:42:57 sio_allow_non_padded_volumes=True
03:42:57 num_volume_device_scan_tries=180
03:42:57 #sio_max_over_subscription_ratio = 10.0
03:42:57 image_volume_cache_enabled = True
03:42:57 #[scaleio]
03:42:57 #volume_driver = cinder.volume.drivers.emc.scaleio.ScaleIODriver
03:42:57 #volume_backend_name = scaleio
03:42:57 #san_ip=10.108.225.160
03:42:57 #san_login=admin
03:42:57 #san_password=Danger0us
03:42:57 #sio_protection_domain_name=default
03:42:57 #sio_storage_pool_name=pool2
03:42:57 #sio_storage_pools=default:pool2
03:42:57 #sio_round_volume_capacity=True
03:42:57 #sio_force_delete=True
03:42:57 #sio_unmap_volume_before_deletion=True
03:42:57 #num_volume_device_scan_tries=180
03:42:57 ##sio_max_over_subscription_ratio = 10.0
03:42:57 #
03:42:57 
03:42:57 [database]
03:42:57 max_pool_size = 80
03:42:57 max_overflow = 100
03:42:57 
03:42:57 [[post-config|$NOVA_CONF]]
03:42:57 [DEFAULT]
03:42:57 #libvirt_iscsi_use_multipath = true
03:42:57 #compute_driver = nova.virt.libvirt.LibvirtDriver
03:42:57 
03:42:57 [[test-config|$TEMPEST_CONFIG]]
03:42:57 [compute-feature-enabled]
03:42:57 volume_multiattach = True
03:42:57 #attach_encrypted_volume = False
03:42:57 #[DEFAULT]
03:42:57 #active_timeout = 600
03:42:57 [volume]
03:42:57 volume_size = 8
03:42:57 build_timeout = 600
03:42:57 disk_format = qcow2
03:42:57 [compute]
03:42:57 build_timeout = 600
03:42:57 ssh_timeout = 600
03:42:57 ping_timeout = 600
03:42:57 [image]
03:42:57 build_timeout = 600
03:42:57 #build_interval = 30
03:42:57 
03:42:57 [scenario]
03:42:57 img_dir = /opt/git/files
03:42:57 img_file = cirros-0.3.5-x86_64-disk.img
03:42:57 aki_img_file = cirros-0.3.5-x86_64-vmlinuz
03:42:57 ari_img_file = cirros-0.3.5-x86_64-initrd
03:42:57 ami_img_file = cirros-0.3.5-x86_64-blank.img
03:42:57 img_container_format = bare
03:42:57 img_disk_format = qcow2
03:42:57 
03:42:57 [volume-feature-enabled]
03:42:57 extend_attached_volume = True ]]
03:42:57 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L634:   /tmp/ansible/bin/dsconf setlc_raw local.conf '#
03:42:57 [[local|localrc]]
03:42:57 USE_PYTHON3=True
03:42:57 
03:42:57 DATABASE_QUERY_LOGGING=False
03:42:57 TEMPEST_VOLUME_DRIVER=EMC
03:42:57 TEMPEST_VOLUME_VENDOR='\''Dell EMC'\''
03:42:57 TEMPEST_STORAGE_PROTOCOL=scaleio
03:42:57 CINDER_ENABLED_BACKENDS=scaleio
03:42:57 DATABASE_QUERY_LOGGING=False
03:42:57 ENABLE_VOLUME_MULTIATTACH=True
03:42:57 
03:42:57 #PIP_GET_PIP_URL=http://10.228.225.108/rally/get-pip.py
03:42:57 # Do not download cirros image from internet.
03:42:57 DOWNLOAD_DEFAULT_IMAGES=False
03:42:57 # Specify the image we will use.
03:42:57 IMAGE_URLS=file:///opt/git/files/cirros-0.3.5-x86_64-disk.img
03:42:57 
03:42:57 [[post-config|$CINDER_CONF]]
03:42:57 [DEFAULT]
03:42:57 rpc_response_timeout=1000
03:42:57 service_down_time=1000
03:42:57 volume_name_template = cdh2h7-%s
03:42:57 max_pool_size = 80
03:42:57 max_overflow = 100
03:42:57 rpc_cast_timeout=600
03:42:57 use_multipath_for_image_xfer=true
03:42:57 default_volume_type = scaleio
03:42:57 enabled_backends = scaleio
03:42:57 #max_over_subscription_ratio = 10.0
03:42:57 
03:42:57 [scaleio]
03:42:57 volume_driver = cinder.volume.drivers.dell_emc.scaleio.driver.ScaleIODriver
03:42:57 volume_backend_name = scaleio
03:42:57 san_ip=10.228.225.150
03:42:57 san_login=admin
03:42:57 san_password=Danger0us
03:42:57 san_thin_provision=true
03:42:57 sio_protection_domain_name=OpenstackPD
03:42:57 sio_storage_pool_name=OpenstackSP
03:42:57 sio_storage_pools=OpenstackPD:OpenstackSP
03:42:57 sio_round_volume_capacity=True
03:42:57 sio_force_delete=True
03:42:57 sio_unmap_volume_before_deletion=True
03:42:57 #sio_allow_non_padded_thick_volumes=True
03:42:57 sio_allow_non_padded_volumes=True
03:42:57 num_volume_device_scan_tries=180
03:42:57 #sio_max_over_subscription_ratio = 10.0
03:42:57 image_volume_cache_enabled = True
03:42:57 #[scaleio]
03:42:57 #volume_driver = cinder.volume.drivers.emc.scaleio.ScaleIODriver
03:42:57 #volume_backend_name = scaleio
03:42:57 #san_ip=10.108.225.160
03:42:57 #san_login=admin
03:42:57 #san_password=Danger0us
03:42:57 #sio_protection_domain_name=default
03:42:57 #sio_storage_pool_name=pool2
03:42:57 #sio_storage_pools=default:pool2
03:42:57 #sio_round_volume_capacity=True
03:42:57 #sio_force_delete=True
03:42:57 #sio_unmap_volume_before_deletion=True
03:42:57 #num_volume_device_scan_tries=180
03:42:57 ##sio_max_over_subscription_ratio = 10.0
03:42:57 #
03:42:57 
03:42:57 [database]
03:42:57 max_pool_size = 80
03:42:57 max_overflow = 100
03:42:57 
03:42:57 [[post-config|$NOVA_CONF]]
03:42:57 [DEFAULT]
03:42:57 #libvirt_iscsi_use_multipath = true
03:42:57 #compute_driver = nova.virt.libvirt.LibvirtDriver
03:42:57 
03:42:57 [[test-config|$TEMPEST_CONFIG]]
03:42:57 [compute-feature-enabled]
03:42:57 volume_multiattach = True
03:42:57 #attach_encrypted_volume = False
03:42:57 #[DEFAULT]
03:42:57 #active_timeout = 600
03:42:57 [volume]
03:42:57 volume_size = 8
03:42:57 build_timeout = 600
03:42:57 disk_format = qcow2
03:42:57 [compute]
03:42:57 build_timeout = 600
03:42:57 ssh_timeout = 600
03:42:57 ping_timeout = 600
03:42:57 [image]
03:42:57 build_timeout = 600
03:42:57 #build_interval = 30
03:42:57 
03:42:57 [scenario]
03:42:57 img_dir = /opt/git/files
03:42:57 img_file = cirros-0.3.5-x86_64-disk.img
03:42:57 aki_img_file = cirros-0.3.5-x86_64-vmlinuz
03:42:57 ari_img_file = cirros-0.3.5-x86_64-initrd
03:42:57 ami_img_file = cirros-0.3.5-x86_64-blank.img
03:42:57 img_container_format = bare
03:42:57 img_disk_format = qcow2
03:42:57 
03:42:57 [volume-feature-enabled]
03:42:57 extend_attached_volume = True'
03:42:58 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_localrc:L643:   [[ -n '' ]]
03:42:58 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L731:   [[ aio == \m\u\l\t\i\n\o\d\e ]]
03:42:58 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L738:   setup_networking
03:42:58 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_networking:L232:   local mode=devstack
03:42:58 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_networking:L235:   [[ aio != \m\u\l\t\i\n\o\d\e ]]
03:42:58 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_networking:L236:   [[ 1 -ne 1 ]]
03:42:58 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_networking:L243:   [[ aio == \m\u\l\t\i\n\o\d\e ]]
03:42:58 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L740:   setup_access_for_stack_user
03:42:58 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_access_for_stack_user:L657:   /tmp/ansible/bin/ansible all --become -f 5 -i /home/jenkins/workspace/EMC_VxFlexOS/inventory -m shell -a 'chown -R stack:stack '\''/opt/stack'\'''
03:42:59  [WARNING]: Consider using the file module with owner rather than running
03:42:59 chown.  If you need to use command because file is insufficient you can add
03:42:59 warn=False to this command task or set command_warnings=False in ansible.cfg to
03:42:59 get rid of this message.
03:42:59 localhost | SUCCESS | rc=0 >>
localhost | SUCCESS | rc=0 >>
03:42:59 
03:42:59 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:setup_access_for_stack_user:L660:   /tmp/ansible/bin/ansible all --become -f 5 -i /home/jenkins/workspace/EMC_VxFlexOS/inventory -m shell -a 'chmod 777 '\''/home/jenkins/workspace/EMC_VxFlexOS/logs'\'''
03:43:01  [WARNING]: Consider using the file module with mode rather than running chmod.
03:43:01 If you need to use command because file is insufficient you can add warn=False
03:43:01 to this command task or set command_warnings=False in ansible.cfg to get rid of
03:43:01 this message.
03:43:01 localhost | SUCCESS | rc=0 >>
localhost | SUCCESS | rc=0 >>
03:43:01 
03:43:01 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L742:   echo 'Running devstack'
03:43:01 Running devstack
03:43:01 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L743:   echo '... this takes 10 - 15 minutes (logs in logs/devstacklog.txt.gz)'
03:43:01 ... this takes 10 - 15 minutes (logs in logs/devstacklog.txt.gz)
03:43:01 ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L744:   date +%s
03:43:01 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L744:   start=1549960981
03:43:01 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L751:   /tmp/ansible/bin/ansible primary -f 5 -i /home/jenkins/workspace/EMC_VxFlexOS/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'
+ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L751:   /tmp/ansible/bin/ansible primary -f 5 -i /home/jenkins/workspace/EMC_VxFlexOS/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'
04:02:01 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L754:   '[' -d /opt/stack/data/CA ']'
04:02:01 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L771:   /tmp/ansible/bin/ansible subnodes -f 5 -i /home/jenkins/workspace/EMC_VxFlexOS/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'
+ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L771:   /tmp/ansible/bin/ansible subnodes -f 5 -i /home/jenkins/workspace/EMC_VxFlexOS/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'
04:02:01 ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L774:   date +%s
04:02:01 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L774:   end=1549962121
04:02:01 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L775:   took=19
04:02:01 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L776:   [[ 19 -gt 20 ]]
04:02:01 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L781:   discover_hosts
04:02:01 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:discover_hosts:L259:   /tmp/ansible/bin/ansible primary -f 5 -i /home/jenkins/workspace/EMC_VxFlexOS/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)'
+ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:discover_hosts:L259:   /tmp/ansible/bin/ansible primary -f 5 -i /home/jenkins/workspace/EMC_VxFlexOS/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)'
04:02:06 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L785:   [[ -f /usr/bin/yum ]]
04:02:06 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L789:   POSTGRES_LOG_PATH='-d /var/log/postgresql'
04:02:06 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L790:   MYSQL_LOG_PATH='-d /var/log/mysql'
04:02:06 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L792:   [[ 0 -eq 1 ]]
04:02:06 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L798:   [[ ! -n -d /var/log/mysql ]]
04:02:06 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L805:   [[ 0 -eq 1 ]]
04:02:06 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L810:   [[ 1 -eq 1 ]]
04:02:06 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L811:   echo 'Removing sudo privileges for devstack user'
04:02:06 Removing sudo privileges for devstack user
04:02:06 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L812:   /tmp/ansible/bin/ansible all --sudo -f 5 -i /home/jenkins/workspace/EMC_VxFlexOS/inventory -m file -a 'path=/etc/sudoers.d/50_stack_sh state=absent'
04:02:07 [DEPRECATION WARNING]: The sudo command line option has been deprecated in 
04:02:07 favor of the "become" command line arguments. This feature will be removed in 
favor of the "become" command line arguments. This feature will be removed in 
04:02:07 version 2.6. Deprecation warnings can be disabled by setting 
04:02:07 deprecation_warnings=False in ansible.cfg.
04:02:07 localhost | SUCCESS => {
localhost | SUCCESS => {
04:02:07     "changed": true,
    "changed": true,
04:02:07     "path": "/etc/sudoers.d/50_stack_sh",
    "path": "/etc/sudoers.d/50_stack_sh",
04:02:07     "state": "absent"
    "state": "absent"
04:02:07 }
04:02:07 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L816:   [[ 1 -eq 1 ]]
04:02:07 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L818:   [[ -d /opt/stack/new/tempest ]]
04:02:07 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L819:   sudo chown -R tempest:stack /opt/stack/new/tempest
04:02:07 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L823:   [[ -d /opt/stack/data/tempest ]]
04:02:07 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L824:   sudo chown -R tempest:stack /opt/stack/data/tempest
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L827:   [[ -d /opt/stack/new/devstack/files ]]
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L828:   sudo chmod -R o+rx /opt/stack/new/devstack/files
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L837:   [[ aio == \m\u\l\t\i\n\o\d\e ]]
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L842:   [[ 0 -eq 1 ]]
04:02:08 ++ /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L850:   iniget /opt/stack/new/tempest/etc/tempest.conf neutron-feature-enabled api_extensions
04:02:08 +++ /opt/stack/new/devstack-gate/functions.sh:iniget:L1031:   source /opt/stack/new/devstack/inc/ini-config
04:02:08 +++++ /opt/stack/new/devstack/inc/ini-config:source:L10:   grep xtrace
04:02:08 +++++ /opt/stack/new/devstack/inc/ini-config:source:L10:   set +o
04:02:08 ++++ /opt/stack/new/devstack/inc/ini-config:source:L10:   INC_CONF_TRACE='set -o xtrace'
04:02:08 ++++ /opt/stack/new/devstack/inc/ini-config:source:L11:   set +o xtrace
04:02:08 +++ /opt/stack/new/devstack-gate/functions.sh:iniget:L1031:   iniget /opt/stack/new/tempest/etc/tempest.conf neutron-feature-enabled api_extensions
04:02:08 +++ /opt/stack/new/devstack/inc/ini-config:iniget:L61:   local xtrace
04:02:08 ++++ /opt/stack/new/devstack/inc/ini-config:iniget:L62:   set +o
04:02:08 ++++ /opt/stack/new/devstack/inc/ini-config:iniget:L62:   grep xtrace
04:02:08 +++ /opt/stack/new/devstack/inc/ini-config:iniget:L62:   xtrace='set -o xtrace'
04:02:08 +++ /opt/stack/new/devstack/inc/ini-config:iniget:L63:   set +o xtrace
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L850:   neutron_extensions=
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L851:   [[ '' == \m\a\s\t\e\r ]]
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L858:   set -o errexit
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L860:   [[ '' != '' ]]
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L863:   TEMPEST_COMMAND='sudo -H -u tempest tox'
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L865:   cd /opt/stack/new/tempest
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L866:   [[ ^(?=.*volume).* != '' ]]
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L867:   [[ 0 -eq 1 ]]
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L872:   echo 'Running tempest with a custom regex filter'
04:02:08 Running tempest with a custom regex filter
04:02:08 + /opt/stack/new/devstack-gate/devstack-vm-gate.sh:main:L873:   sudo -H -u tempest tox -eall -- '^(?=.*volume).*' --concurrency=1
04:02:09 all develop-inst-noop: /opt/stack/new/tempest
04:02:09 all installed: asn1crypto==0.24.0,Babel==2.6.0,bcrypt==3.1.6,certifi==2018.11.29,cffi==1.11.5,chardet==3.0.4,cliff==2.14.0,cmd2==0.8.9,cryptography==2.5,debtcollector==1.20.0,extras==1.0.0,fasteners==0.14.1,fixtures==3.0.0,future==0.17.1,idna==2.8,iso8601==0.1.12,jsonschema==2.6.0,linecache2==1.0.0,monotonic==1.5,msgpack==0.6.1,netaddr==0.7.19,netifaces==0.10.9,oslo.concurrency==3.29.0,oslo.config==6.8.0,oslo.context==2.22.0,oslo.i18n==3.23.0,oslo.log==3.42.2,oslo.serialization==2.28.1,oslo.utils==3.40.2,paramiko==2.4.2,pbr==5.1.2,prettytable==0.7.2,pyasn1==0.4.5,pycparser==2.19,pyinotify==0.9.6,PyNaCl==1.3.0,pyparsing==2.3.1,pyperclip==1.7.0,python-dateutil==2.8.0,python-mimeparse==1.6.0,python-subunit==1.3.0,pytz==2018.9,PyYAML==3.13,requests==2.21.0,rfc3986==1.2.0,six==1.12.0,stestr==2.2.0,stevedore==1.30.0,-e git+http://git.openstack.org/openstack/tempest@4008e4afef0d54ba50c26066897a518cea7f4180#egg=tempest,testtools==2.3.0,traceback2==1.4.0,unittest2==1.1.0,urllib3==1.24.1,voluptuous==0.11.5,wcwidth==0.1.7,wrapt==1.11.1
04:02:09 all run-test-pre: PYTHONHASHSEED='262273207'
04:02:09 all runtests: commands[0] | find . -type f -name '*.pyc' -delete
04:02:09 all runtests: commands[1] | tempest run --regex '^(?=.*volume).*' --concurrency=1
04:02:13 {0} setUpClass (tempest.api.compute.admin.test_live_migration.LiveAutoBlockMigrationV225Test) ... SKIPPED: LiveAutoBlockMigrationV225Test skipped as live-migration is not available
04:02:13 {0} setUpClass (tempest.api.compute.admin.test_live_migration.LiveMigrationTest) ... SKIPPED: LiveMigrationTest skipped as live-migration is not available
04:03:52 {0} tempest.api.compute.admin.test_volume_swap.TestMultiAttachVolumeSwap.test_volume_swap_with_multiattach [97.853217s] ... ok
04:07:01 {0} tempest.api.compute.admin.test_volume_swap.TestVolumeSwap.test_volume_swap [181.490532s] ... ok
04:07:23 {0} tempest.api.compute.admin.test_volumes_negative.VolumesAdminNegativeTest.test_update_attached_volume_with_nonexistent_volume_in_body [13.127644s] ... ok
04:07:24 {0} tempest.api.compute.admin.test_volumes_negative.VolumesAdminNegativeTest.test_update_attached_volume_with_nonexistent_volume_in_uri [1.495739s] ... ok
04:07:40 {0} tempest.api.compute.servers.test_delete_server.DeleteServersTestJSON.test_delete_server_while_in_attached_volume [10.518224s] ... ok
04:08:44 {0} tempest.api.compute.servers.test_device_tagging.TaggedAttachmentsTest.test_tagged_attachment [55.911764s] ... ok
04:10:45 {0} tempest.api.compute.servers.test_device_tagging.TaggedBootDevicesTest.test_tagged_boot_devices [107.448032s] ... ok
04:12:36 {0} tempest.api.compute.servers.test_device_tagging.TaggedBootDevicesTest_v242.test_tagged_boot_devices [96.110555s] ... ok
04:13:56 {0} tempest.api.compute.servers.test_server_actions.ServerActionsTestJSON.test_rebuild_server_with_volume_attached [51.712048s] ... ok
04:14:30 {0} tempest.api.compute.servers.test_server_actions.ServerActionsTestJSON.test_resize_server_revert_with_volume_attached [34.834771s] ... ok
04:15:21 {0} tempest.api.compute.servers.test_server_actions.ServerActionsTestJSON.test_resize_volume_backed_server_confirm [51.030884s] ... ok
04:16:16 {0} tempest.api.compute.servers.test_server_rescue_negative.ServerRescueNegativeTestJSON.test_rescued_vm_attach_volume [10.722916s] ... ok
04:16:57 {0} tempest.api.compute.servers.test_server_rescue_negative.ServerRescueNegativeTestJSON.test_rescued_vm_detach_volume [41.509327s] ... ok
04:17:17 {0} tempest.api.compute.servers.test_servers_negative.ServersNegativeTestJSON.test_create_server_from_non_bootable_volume [1.935599s] ... ok
04:17:18 {0} tempest.api.compute.servers.test_servers_negative.ServersNegativeTestJSON.test_create_server_invalid_bdm_in_2nd_dict [1.682115s] ... ok
04:17:39 {0} tempest.api.compute.volumes.test_attach_volume.AttachVolumeMultiAttachTest.test_boot_from_multiattach_volume [10.603588s] ... ok
04:17:59 {0} tempest.api.compute.volumes.test_attach_volume.AttachVolumeMultiAttachTest.test_list_get_volume_attachments_multiattach [19.477415s] ... ok
04:18:54 {0} tempest.api.compute.volumes.test_attach_volume.AttachVolumeMultiAttachTest.test_resize_server_with_multiattached_volume [55.357446s] ... ok
04:19:11 {0} tempest.api.compute.volumes.test_attach_volume.AttachVolumeMultiAttachTest.test_snapshot_volume_backed_multiattach [16.504480s] ... ok
04:20:55 {0} tempest.api.compute.volumes.test_attach_volume.AttachVolumeShelveTestJSON.test_attach_volume_shelved_or_offload_server [73.567350s] ... ok
04:22:10 {0} tempest.api.compute.volumes.test_attach_volume.AttachVolumeShelveTestJSON.test_detach_volume_shelved_or_offload_server [75.468879s] ... ok
04:24:07 {0} tempest.api.compute.volumes.test_attach_volume.AttachVolumeTestJSON.test_attach_detach_volume [101.296510s] ... ok
04:24:37 {0} tempest.api.compute.volumes.test_attach_volume.AttachVolumeTestJSON.test_list_get_volume_attachments [30.080386s] ... ok
04:25:02 {0} tempest.api.compute.volumes.test_attach_volume_negative.AttachVolumeNegativeTest.test_attach_attached_volume_to_different_server [13.605576s] ... ok
04:25:18 {0} tempest.api.compute.volumes.test_attach_volume_negative.AttachVolumeNegativeTest.test_attach_attached_volume_to_same_server [15.772296s] ... ok
04:25:35 {0} tempest.api.compute.volumes.test_attach_volume_negative.AttachVolumeNegativeTest.test_delete_attached_volume [17.137337s] ... ok
04:25:51 {0} tempest.api.compute.volumes.test_volume_snapshots.VolumesSnapshotsTestJSON.test_volume_snapshot_create_get_list_delete [5.475741s] ... ok
04:25:54 {0} tempest.api.compute.volumes.test_volumes_get.VolumesGetTestJSON.test_volume_create_get_delete [1.847252s] ... ok
04:26:01 {0} tempest.api.compute.volumes.test_volumes_list.VolumesTestJSON.test_volume_list [0.102150s] ... ok
04:26:01 {0} tempest.api.compute.volumes.test_volumes_list.VolumesTestJSON.test_volume_list_param_limit [0.084672s] ... ok
04:26:01 {0} tempest.api.compute.volumes.test_volumes_list.VolumesTestJSON.test_volume_list_param_offset_and_limit [0.141870s] ... ok
04:26:01 {0} tempest.api.compute.volumes.test_volumes_list.VolumesTestJSON.test_volume_list_with_detail_param_limit [0.076646s] ... ok
04:26:02 {0} tempest.api.compute.volumes.test_volumes_list.VolumesTestJSON.test_volume_list_with_detail_param_offset_and_limit [0.157172s] ... ok
04:26:02 {0} tempest.api.compute.volumes.test_volumes_list.VolumesTestJSON.test_volume_list_with_details [0.077758s] ... ok
04:26:07 {0} tempest.api.compute.volumes.test_volumes_negative.VolumesNegativeTest.test_create_volume_with_invalid_size [0.130286s] ... ok
04:26:07 {0} tempest.api.compute.volumes.test_volumes_negative.VolumesNegativeTest.test_create_volume_with_size_zero [0.011639s] ... ok
04:26:07 {0} tempest.api.compute.volumes.test_volumes_negative.VolumesNegativeTest.test_create_volume_without_passing_size [0.009758s] ... ok
04:26:07 {0} tempest.api.compute.volumes.test_volumes_negative.VolumesNegativeTest.test_delete_invalid_volume_id [0.061442s] ... ok
04:26:07 {0} tempest.api.compute.volumes.test_volumes_negative.VolumesNegativeTest.test_delete_volume_without_passing_volume_id [0.010315s] ... ok
04:26:07 {0} tempest.api.compute.volumes.test_volumes_negative.VolumesNegativeTest.test_get_volume_without_passing_volume_id [0.014830s] ... ok
04:26:07 {0} tempest.api.compute.volumes.test_volumes_negative.VolumesNegativeTest.test_volume_delete_nonexistent_volume_id [0.061996s] ... ok
04:26:07 {0} tempest.api.compute.volumes.test_volumes_negative.VolumesNegativeTest.test_volume_get_nonexistent_volume_id [0.061848s] ... ok
04:26:09 {0} tempest.api.volume.admin.test_backends_capabilities.BackendsCapabilitiesAdminTestsJSON.test_compare_volume_stats_values [0.058751s] ... ok
04:26:09 {0} tempest.api.volume.admin.test_backends_capabilities.BackendsCapabilitiesAdminTestsJSON.test_get_capabilities_backend [0.027599s] ... ok
04:26:20 {0} tempest.api.volume.admin.test_group_snapshots.GroupSnapshotsTest.test_create_group_from_group_snapshot [8.967451s] ... ok
04:26:34 {0} tempest.api.volume.admin.test_group_snapshots.GroupSnapshotsTest.test_delete_group_snapshots_following_updated_volumes [13.290512s] ... ok
04:26:41 {0} tempest.api.volume.admin.test_group_snapshots.GroupSnapshotsTest.test_group_snapshot_create_show_list_delete [7.250389s] ... ok
04:26:44 {0} tempest.api.volume.admin.test_group_snapshots.GroupSnapshotsV319Test.test_reset_group_snapshot_status ... SKIPPED: Skipped until bug: https://launchpad.net/bugs/1770179 is resolved.
04:26:47 {0} tempest.api.volume.admin.test_group_type_specs.GroupTypeSpecsTest.test_group_type_specs_create_show_update_list_delete [0.408735s] ... ok
04:26:49 {0} tempest.api.volume.admin.test_group_types.GroupTypesTest.test_group_type_create_list_update_show [0.235350s] ... ok
04:26:58 {0} tempest.api.volume.admin.test_groups.GroupsTest.test_group_create_show_list_delete [6.774005s] ... ok
04:27:07 {0} tempest.api.volume.admin.test_groups.GroupsTest.test_group_update [8.274273s] ... ok
04:27:16 {0} tempest.api.volume.admin.test_groups.GroupsV314Test.test_create_group_from_group [6.419020s] ... ok
04:27:21 {0} tempest.api.volume.admin.test_groups.GroupsV320Test.test_reset_group_status [2.984150s] ... ok
04:27:23 {0} setUpClass (tempest.api.volume.admin.test_multi_backend.VolumeMultiBackendTest) ... SKIPPED: Cinder multi-backend feature disabled
04:27:24 {0} tempest.api.volume.admin.test_qos.QosSpecsTestJSON.test_associate_disassociate_qos [0.411410s] ... ok
04:27:25 {0} tempest.api.volume.admin.test_qos.QosSpecsTestJSON.test_create_delete_qos_with_back_end_consumer [0.106652s] ... ok
04:27:25 {0} tempest.api.volume.admin.test_qos.QosSpecsTestJSON.test_create_delete_qos_with_both_consumer [0.102396s] ... ok
04:27:25 {0} tempest.api.volume.admin.test_qos.QosSpecsTestJSON.test_create_delete_qos_with_front_end_consumer [0.116921s] ... ok
04:27:25 {0} tempest.api.volume.admin.test_qos.QosSpecsTestJSON.test_get_qos [0.026176s] ... ok
04:27:25 {0} tempest.api.volume.admin.test_qos.QosSpecsTestJSON.test_list_qos [0.023063s] ... ok
04:27:25 {0} tempest.api.volume.admin.test_qos.QosSpecsTestJSON.test_set_unset_qos_key [0.129865s] ... ok
04:27:27 {0} setUpClass (tempest.api.volume.admin.test_snapshot_manage.SnapshotManageAdminTest) ... SKIPPED: Manage snapshot tests are disabled
04:27:31 {0} tempest.api.volume.admin.test_snapshots_actions.SnapshotsActionsTest.test_reset_snapshot_status [0.210810s] ... ok
04:27:33 {0} tempest.api.volume.admin.test_snapshots_actions.SnapshotsActionsTest.test_snapshot_force_delete_when_snapshot_is_creating [2.496292s] ... ok
04:27:36 {0} tempest.api.volume.admin.test_snapshots_actions.SnapshotsActionsTest.test_snapshot_force_delete_when_snapshot_is_deleting [2.575974s] ... ok
04:27:38 {0} tempest.api.volume.admin.test_snapshots_actions.SnapshotsActionsTest.test_snapshot_force_delete_when_snapshot_is_error [2.541943s] ... ok
04:27:41 {0} tempest.api.volume.admin.test_snapshots_actions.SnapshotsActionsTest.test_snapshot_force_delete_when_snapshot_is_error_deleting [2.517540s] ... ok
04:27:41 {0} tempest.api.volume.admin.test_snapshots_actions.SnapshotsActionsTest.test_update_snapshot_status [0.195681s] ... ok
04:27:47 {0} tempest.api.volume.admin.test_user_messages.UserMessagesTest.test_delete_message [0.577894s] ... ok
04:27:47 {0} tempest.api.volume.admin.test_user_messages.UserMessagesTest.test_list_show_messages [0.420340s] ... ok
04:27:50 {0} tempest.api.volume.admin.test_volume_hosts.VolumeHostsAdminTestsJSON.test_list_hosts [0.107000s] ... ok
04:27:50 {0} tempest.api.volume.admin.test_volume_hosts.VolumeHostsAdminTestsJSON.test_show_host [0.067213s] ... ok
04:27:51 {0} setUpClass (tempest.api.volume.admin.test_volume_manage.VolumeManageAdminTest) ... SKIPPED: Manage volume tests are disabled
04:27:52 {0} tempest.api.volume.admin.test_volume_pools.VolumePoolsAdminTestsJSON.test_get_pools_with_details [0.124162s] ... ok
04:27:52 {0} tempest.api.volume.admin.test_volume_pools.VolumePoolsAdminTestsJSON.test_get_pools_without_details [0.032709s] ... ok
04:27:54 {0} tempest.api.volume.admin.test_volume_quota_classes.VolumeQuotaClassesTest.test_show_default_quota [0.113404s] ... ok
04:27:55 {0} tempest.api.volume.admin.test_volume_quota_classes.VolumeQuotaClassesTest.test_update_default_quota [0.560401s] ... ok
04:27:58 {0} tempest.api.volume.admin.test_volume_quotas.VolumeQuotasAdminTestJSON.test_delete_quota [0.252110s] ... ok
04:27:58 {0} tempest.api.volume.admin.test_volume_quotas.VolumeQuotasAdminTestJSON.test_list_default_quotas [0.028091s] ... ok
04:27:58 {0} tempest.api.volume.admin.test_volume_quotas.VolumeQuotasAdminTestJSON.test_list_quotas [0.035939s] ... ok
04:28:01 {0} tempest.api.volume.admin.test_volume_quotas.VolumeQuotasAdminTestJSON.test_quota_usage [2.735051s] ... ok
04:28:04 {0} tempest.api.volume.admin.test_volume_quotas.VolumeQuotasAdminTestJSON.test_quota_usage_after_volume_transfer [3.332679s] ... ok
04:28:04 {0} tempest.api.volume.admin.test_volume_quotas.VolumeQuotasAdminTestJSON.test_show_quota_usage [0.044271s] ... ok
04:28:05 {0} tempest.api.volume.admin.test_volume_quotas.VolumeQuotasAdminTestJSON.test_update_all_quota_resources_for_tenant [0.249716s] ... ok
04:28:09 {0} tempest.api.volume.admin.test_volume_quotas_negative.VolumeQuotasNegativeTestJSON.test_quota_volume_gigabytes [0.326199s] ... ok
04:28:10 {0} tempest.api.volume.admin.test_volume_quotas_negative.VolumeQuotasNegativeTestJSON.test_quota_volumes [0.201798s] ... ok
04:28:10 {0} tempest.api.volume.admin.test_volume_quotas_negative.VolumeQuotasNegativeTestJSON.test_volume_extend_gigabytes_quota_deviation [0.159873s] ... ok
04:28:12 {0} setUpClass (tempest.api.volume.admin.test_volume_retype.VolumeRetypeWithMigrationTest) ... SKIPPED: Cinder multi-backend feature disabled.
04:28:17 {0} tempest.api.volume.admin.test_volume_retype.VolumeRetypeWithoutMigrationTest.test_available_volume_retype [3.141729s] ... ok
04:28:21 {0} tempest.api.volume.admin.test_volume_services.VolumesServicesTestJSON.test_get_service_by_host_name [0.016432s] ... ok
04:28:21 {0} tempest.api.volume.admin.test_volume_services.VolumesServicesTestJSON.test_get_service_by_service_and_host_name [0.021357s] ... ok
04:28:21 {0} tempest.api.volume.admin.test_volume_services.VolumesServicesTestJSON.test_get_service_by_service_binary_name [0.018649s] ... ok
04:28:22 {0} tempest.api.volume.admin.test_volume_services.VolumesServicesTestJSON.test_get_service_by_volume_host_name [1.731992s] ... ok
04:28:23 {0} tempest.api.volume.admin.test_volume_services.VolumesServicesTestJSON.test_list_services [0.020304s] ... ok
04:28:26 {0} tempest.api.volume.admin.test_volume_services_negative.VolumeServicesNegativeTest.test_disable_log_reason_with_no_reason [0.009889s] ... ok
04:28:26 {0} tempest.api.volume.admin.test_volume_services_negative.VolumeServicesNegativeTest.test_disable_service_with_invalid_binary [0.017881s] ... ok
04:28:26 {0} tempest.api.volume.admin.test_volume_services_negative.VolumeServicesNegativeTest.test_enable_service_with_invalid_host [0.015860s] ... ok
04:28:26 {0} tempest.api.volume.admin.test_volume_services_negative.VolumeServicesNegativeTest.test_freeze_host_with_invalid_host [0.021614s] ... ok
04:28:26 {0} tempest.api.volume.admin.test_volume_services_negative.VolumeServicesNegativeTest.test_thaw_host_with_invalid_host [0.015050s] ... ok
04:28:32 {0} tempest.api.volume.admin.test_volume_snapshot_quotas_negative.VolumeSnapshotQuotasNegativeTestJSON.test_quota_volume_gigabytes_snapshots [0.257232s] ... ok
04:28:32 {0} tempest.api.volume.admin.test_volume_snapshot_quotas_negative.VolumeSnapshotQuotasNegativeTestJSON.test_quota_volume_snapshots [0.091567s] ... ok
04:28:39 {0} tempest.api.volume.admin.test_volume_type_access.VolumeTypesAccessTest.test_volume_type_access_add [1.713661s] ... ok
04:28:39 {0} tempest.api.volume.admin.test_volume_type_access.VolumeTypesAccessTest.test_volume_type_access_list [0.160503s] ... ok
04:28:46 {0} tempest.api.volume.admin.test_volume_types.VolumeTypesTest.test_volume_crud_with_volume_type_and_extra_specs [3.126484s] ... ok
04:28:47 {0} tempest.api.volume.admin.test_volume_types.VolumeTypesTest.test_volume_type_create_get_delete [0.078167s] ... ok
04:28:47 {0} tempest.api.volume.admin.test_volume_types.VolumeTypesTest.test_volume_type_encryption_create_get_update_delete [0.336009s] ... ok
04:28:47 {0} tempest.api.volume.admin.test_volume_types.VolumeTypesTest.test_volume_type_list [0.033245s] ... ok
04:28:47 {0} tempest.api.volume.admin.test_volume_types.VolumeTypesTest.test_volume_type_update [0.133440s] ... ok
04:28:51 {0} tempest.api.volume.admin.test_volume_types_extra_specs.VolumeTypesExtraSpecsTest.test_volume_type_extra_spec_create_get_delete [0.208439s] ... ok
04:28:52 {0} tempest.api.volume.admin.test_volume_types_extra_specs.VolumeTypesExtraSpecsTest.test_volume_type_extra_specs_list [0.092374s] ... ok
04:28:52 {0} tempest.api.volume.admin.test_volume_types_extra_specs.VolumeTypesExtraSpecsTest.test_volume_type_extra_specs_update [0.274624s] ... ok
04:28:54 {0} tempest.api.volume.admin.test_volume_types_extra_specs_negative.ExtraSpecsNegativeTest.test_create_invalid_body [0.011569s] ... ok
04:28:54 {0} tempest.api.volume.admin.test_volume_types_extra_specs_negative.ExtraSpecsNegativeTest.test_create_none_body [0.011806s] ... ok
04:28:54 {0} tempest.api.volume.admin.test_volume_types_extra_specs_negative.ExtraSpecsNegativeTest.test_create_nonexistent_type_id [0.045476s] ... ok
04:28:54 {0} tempest.api.volume.admin.test_volume_types_extra_specs_negative.ExtraSpecsNegativeTest.test_delete_nonexistent_volume_type_id [0.019537s] ... ok
04:28:54 {0} tempest.api.volume.admin.test_volume_types_extra_specs_negative.ExtraSpecsNegativeTest.test_get_nonexistent_extra_spec_name [0.026253s] ... ok
04:28:54 {0} tempest.api.volume.admin.test_volume_types_extra_specs_negative.ExtraSpecsNegativeTest.test_get_nonexistent_volume_type_id [0.018591s] ... ok
04:28:54 {0} tempest.api.volume.admin.test_volume_types_extra_specs_negative.ExtraSpecsNegativeTest.test_list_nonexistent_volume_type_id [0.018795s] ... ok
04:28:54 {0} tempest.api.volume.admin.test_volume_types_extra_specs_negative.ExtraSpecsNegativeTest.test_update_multiple_extra_spec [0.011336s] ... ok
04:28:54 {0} tempest.api.volume.admin.test_volume_types_extra_specs_negative.ExtraSpecsNegativeTest.test_update_no_body [0.010691s] ... ok
04:28:55 {0} tempest.api.volume.admin.test_volume_types_extra_specs_negative.ExtraSpecsNegativeTest.test_update_none_extra_spec_id [0.058779s] ... ok
04:28:55 {0} tempest.api.volume.admin.test_volume_types_extra_specs_negative.ExtraSpecsNegativeTest.test_update_nonexistent_extra_spec_id [0.046573s] ... ok
04:28:57 {0} tempest.api.volume.admin.test_volume_types_negative.VolumeTypesNegativeTest.test_create_volume_with_private_volume_type [0.212985s] ... ok
04:28:57 {0} tempest.api.volume.admin.test_volume_types_negative.VolumeTypesNegativeTest.test_create_with_empty_name [0.007643s] ... ok
04:28:57 {0} tempest.api.volume.admin.test_volume_types_negative.VolumeTypesNegativeTest.test_delete_nonexistent_type_id [0.016098s] ... ok
04:28:57 {0} tempest.api.volume.admin.test_volume_types_negative.VolumeTypesNegativeTest.test_get_nonexistent_type_id [0.014641s] ... ok
04:29:10 {0} tempest.api.volume.admin.test_volumes_actions.VolumesActionsTest.test_force_detach_volume [10.205070s] ... ok
04:29:12 {0} tempest.api.volume.admin.test_volumes_actions.VolumesActionsTest.test_volume_force_delete_when_volume_is_attaching [2.669423s] ... ok
04:29:15 {0} tempest.api.volume.admin.test_volumes_actions.VolumesActionsTest.test_volume_force_delete_when_volume_is_creating [2.712817s] ... ok
04:29:18 {0} tempest.api.volume.admin.test_volumes_actions.VolumesActionsTest.test_volume_force_delete_when_volume_is_error [2.688632s] ... ok
04:29:21 {0} tempest.api.volume.admin.test_volumes_actions.VolumesActionsTest.test_volume_force_delete_when_volume_is_maintenance [2.868729s] ... ok
04:29:23 {0} tempest.api.volume.admin.test_volumes_actions.VolumesActionsTest.test_volume_reset_status [2.079024s] ... ok
04:33:45 {0} tempest.api.volume.admin.test_volumes_backup.VolumesBackupsAdminTest.test_volume_backup_export_import [256.809539s] ... ok
04:36:03 {0} tempest.api.volume.admin.test_volumes_backup.VolumesBackupsAdminTest.test_volume_backup_reset_status [138.533193s] ... ok
04:36:15 {0} tempest.api.volume.admin.test_volumes_list.VolumesListAdminTestJSON.test_volume_list_param_tenant [1.566322s] ... ok
04:36:21 {0} tempest.api.volume.test_availability_zone.AvailabilityZoneTestJSON.test_get_availability_zone_list [0.127253s] ... ok
04:36:22 {0} tempest.api.volume.test_extensions.ExtensionsTestJSON.test_list_extensions [0.119263s] ... ok
04:36:25 {0} tempest.api.volume.test_image_metadata.VolumesImageMetadata.test_update_show_delete_image_metadata [0.370735s] ... ok
04:36:31 {0} tempest.api.volume.test_snapshot_metadata.SnapshotMetadataTestJSON.test_crud_snapshot_metadata [0.366433s] ... ok
04:36:31 {0} tempest.api.volume.test_snapshot_metadata.SnapshotMetadataTestJSON.test_update_show_snapshot_metadata_item [0.294819s] ... ok
04:36:35 {0} tempest.api.volume.test_versions.VersionsTest.test_list_versions [0.046447s] ... ok
04:36:39 {0} tempest.api.volume.test_volume_absolute_limits.AbsoluteLimitsTests.test_get_volume_absolute_limits [0.049358s] ... ok
04:36:47 {0} tempest.api.volume.test_volume_delete_cascade.VolumesDeleteCascade.test_volume_delete_cascade [5.311341s] ... ok
04:36:53 {0} tempest.api.volume.test_volume_delete_cascade.VolumesDeleteCascade.test_volume_from_snapshot_cascade_delete [5.574733s] ... ok
04:36:57 {0} tempest.api.volume.test_volume_metadata.VolumesMetadataTest.test_crud_volume_metadata [0.415203s] ... ok
04:36:58 {0} tempest.api.volume.test_volume_metadata.VolumesMetadataTest.test_update_show_volume_metadata_item [0.307645s] ... ok
04:37:05 {0} tempest.api.volume.test_volume_transfers.VolumesTransfersTest.test_create_get_list_accept_volume_transfer [3.676984s] ... ok
04:37:08 {0} tempest.api.volume.test_volume_transfers.VolumesTransfersTest.test_create_list_delete_volume_transfer [3.081417s] ... ok
04:37:23 {0} tempest.api.volume.test_volumes_actions.VolumesActionsTest.test_attach_detach_volume_to_instance [10.615599s] ... ok
04:37:28 {0} tempest.api.volume.test_volumes_actions.VolumesActionsTest.test_get_volume_attachment [5.532119s] ... ok
04:37:28 {0} tempest.api.volume.test_volumes_actions.VolumesActionsTest.test_reserve_unreserve_volume [0.233394s] ... ok
04:37:29 {0} tempest.api.volume.test_volumes_actions.VolumesActionsTest.test_volume_bootable [0.236604s] ... ok
04:37:29 {0} tempest.api.volume.test_volumes_actions.VolumesActionsTest.test_volume_readonly_update [0.223884s] ... ok
04:37:39 {0} tempest.api.volume.test_volumes_actions.VolumesActionsTest.test_volume_upload [9.730143s] ... ok
04:40:10 {0} tempest.api.volume.test_volumes_backup.VolumesBackupsTest.test_backup_create_attached_volume [148.862743s] ... ok
04:44:28 {0} tempest.api.volume.test_volumes_backup.VolumesBackupsTest.test_bootable_volume_backup_and_restore [258.476952s] ... ok
04:48:46 {0} tempest.api.volume.test_volumes_backup.VolumesBackupsTest.test_volume_backup_create_get_detailed_list_restore_delete [258.019603s] ... ok
04:51:02 {0} tempest.api.volume.test_volumes_backup.VolumesBackupsV39Test.test_update_backup [132.328223s] ... ok
04:51:08 {0} tempest.api.volume.test_volumes_clone.VolumesCloneTest.test_create_from_bootable_volume [3.201362s] ... ok
04:51:10 {0} tempest.api.volume.test_volumes_clone.VolumesCloneTest.test_create_from_volume [2.969617s] ... ok
04:51:18 {0} tempest.api.volume.test_volumes_clone_negative.VolumesCloneNegativeTest.test_create_from_volume_decreasing_size [1.657547s] ... ok
04:51:39 {0} tempest.api.volume.test_volumes_extend.VolumesExtendAttachedTest.test_extend_attached_volume [17.003637s] ... ok
04:51:44 {0} tempest.api.volume.test_volumes_extend.VolumesExtendTest.test_volume_extend [2.809604s] ... ok
04:51:48 {0} tempest.api.volume.test_volumes_extend.VolumesExtendTest.test_volume_extend_when_volume_has_snapshot [3.945887s] ... ok
04:52:02 {0} tempest.api.volume.test_volumes_get.VolumesGetTest.test_volume_create_get_update_delete [5.439642s] ... ok
04:52:09 {0} tempest.api.volume.test_volumes_get.VolumesGetTest.test_volume_create_get_update_delete_as_clone [6.997003s] ... ok
04:52:15 {0} tempest.api.volume.test_volumes_get.VolumesGetTest.test_volume_create_get_update_delete_from_image [5.616710s] ... ok
04:52:17 {0} tempest.api.volume.test_volumes_get.VolumesSummaryTest.test_show_volume_summary [0.096224s] ... ok
04:52:23 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volume_list [0.047008s] ... ok
04:52:24 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volume_list_by_name [0.080089s] ... ok
04:52:24 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volume_list_details_by_name [0.060057s] ... ok
04:52:24 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volume_list_details_pagination [0.475008s] ... ok
04:52:24 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volume_list_details_with_multiple_params [0.132255s] ... ok
04:52:24 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volume_list_pagination [0.297412s] ... ok
04:52:25 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volume_list_param_display_name_and_status [0.049264s] ... ok
04:52:25 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volume_list_with_detail_param_display_name_and_status [0.056622s] ... ok
04:52:25 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volume_list_with_detail_param_marker [0.083653s] ... ok
04:52:25 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volume_list_with_detail_param_metadata [0.057120s] ... ok
04:52:25 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volume_list_with_details [0.059286s] ... ok
04:52:25 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volume_list_with_param_metadata [0.055294s] ... ok
04:52:25 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volumes_list_by_availability_zone [0.099384s] ... ok
04:52:25 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volumes_list_by_bootable [0.101809s] ... ok
04:52:25 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volumes_list_by_status [0.105518s] ... ok
04:52:25 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volumes_list_details_by_availability_zone [0.065307s] ... ok
04:52:25 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volumes_list_details_by_bootable [0.056648s] ... ok
04:52:25 {0} tempest.api.volume.test_volumes_list.VolumesListTestJSON.test_volumes_list_details_by_status [0.076212s] ... ok
04:52:37 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_attach_volumes_with_nonexistent_volume_id [5.315728s] ... ok
04:52:38 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_create_volume_from_deactivated_image [0.899389s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_create_volume_from_image_with_decreasing_size [0.704632s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_create_volume_with_invalid_size [0.009765s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_create_volume_with_nonexistent_snapshot_id [0.032817s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_create_volume_with_nonexistent_source_volid [0.045361s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_create_volume_with_nonexistent_volume_type [0.031650s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_create_volume_with_size_negative [0.009153s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_create_volume_with_size_zero [0.010723s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_create_volume_without_passing_size [0.012662s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_delete_invalid_volume_id [0.042083s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_delete_volume_without_passing_volume_id [0.008733s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_detach_volumes_with_invalid_volume_id [0.042420s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_get_invalid_volume_id [0.039472s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_get_volume_without_passing_volume_id [0.006428s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_list_volumes_detail_with_invalid_status [0.046823s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_list_volumes_detail_with_nonexistent_name [0.056578s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_list_volumes_with_invalid_status [0.049283s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_list_volumes_with_nonexistent_name [0.053113s] ... ok
04:52:39 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_reserve_volume_with_negative_volume_status [0.184663s] ... ok
04:52:40 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_reserve_volume_with_nonexistent_volume_id [0.058554s] ... ok
04:52:40 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_unreserve_volume_with_nonexistent_volume_id [0.045064s] ... ok
04:52:40 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_update_volume_with_empty_volume_id [0.007082s] ... ok
04:52:40 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_update_volume_with_invalid_volume_id [0.041239s] ... ok
04:52:40 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_update_volume_with_nonexistent_volume_id [0.043417s] ... ok
04:52:40 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_volume_delete_nonexistent_volume_id [0.048167s] ... ok
04:52:40 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_volume_extend_with_None_size [0.012024s] ... ok
04:52:40 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_volume_extend_with_non_number_size [0.014785s] ... ok
04:52:40 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_volume_extend_with_nonexistent_volume_id [0.042442s] ... ok
04:52:40 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_volume_extend_with_size_smaller_than_original_size [0.014760s] ... ok
04:52:40 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_volume_extend_without_passing_volume_id [0.050200s] ... ok
04:52:40 {0} tempest.api.volume.test_volumes_negative.VolumesNegativeTest.test_volume_get_nonexistent_volume_id [0.044214s] ... ok
04:54:57 {0} tempest.api.volume.test_volumes_snapshots.VolumesSnapshotTestJSON.test_snapshot_backup [133.238949s] ... ok
04:55:16 {0} tempest.api.volume.test_volumes_snapshots.VolumesSnapshotTestJSON.test_snapshot_create_delete_with_volume_in_use [18.629639s] ... ok
04:55:18 {0} tempest.api.volume.test_volumes_snapshots.VolumesSnapshotTestJSON.test_snapshot_create_get_list_update_delete [2.694741s] ... ok
04:55:35 {0} tempest.api.volume.test_volumes_snapshots.VolumesSnapshotTestJSON.test_snapshot_create_offline_delete_online [16.237766s] ... ok
04:55:40 {0} tempest.api.volume.test_volumes_snapshots.VolumesSnapshotTestJSON.test_volume_from_snapshot [5.423941s] ... ok
04:55:46 {0} tempest.api.volume.test_volumes_snapshots.VolumesSnapshotTestJSON.test_volume_from_snapshot_no_size [5.622048s] ... ok
04:56:01 {0} tempest.api.volume.test_volumes_snapshots_list.VolumesSnapshotListTestJSON.test_snapshot_list_param_limit [0.026448s] ... ok
04:56:01 {0} tempest.api.volume.test_volumes_snapshots_list.VolumesSnapshotListTestJSON.test_snapshot_list_param_limit_equals_infinite [0.051042s] ... ok
04:56:01 {0} tempest.api.volume.test_volumes_snapshots_list.VolumesSnapshotListTestJSON.test_snapshot_list_param_limit_equals_zero [0.022094s] ... ok
04:56:01 {0} tempest.api.volume.test_volumes_snapshots_list.VolumesSnapshotListTestJSON.test_snapshot_list_param_marker [0.063978s] ... ok
04:56:01 {0} tempest.api.volume.test_volumes_snapshots_list.VolumesSnapshotListTestJSON.test_snapshot_list_param_offset [0.033248s] ... ok
04:56:01 {0} tempest.api.volume.test_volumes_snapshots_list.VolumesSnapshotListTestJSON.test_snapshot_list_param_sort_created_at_asc [0.027330s] ... ok
04:56:01 {0} tempest.api.volume.test_volumes_snapshots_list.VolumesSnapshotListTestJSON.test_snapshot_list_param_sort_created_at_desc [0.028487s] ... ok
04:56:01 {0} tempest.api.volume.test_volumes_snapshots_list.VolumesSnapshotListTestJSON.test_snapshot_list_param_sort_id_asc [0.024955s] ... ok
04:56:01 {0} tempest.api.volume.test_volumes_snapshots_list.VolumesSnapshotListTestJSON.test_snapshot_list_param_sort_id_desc [0.030391s] ... ok
04:56:01 {0} tempest.api.volume.test_volumes_snapshots_list.VolumesSnapshotListTestJSON.test_snapshot_list_param_sort_name_asc [0.026282s] ... ok
04:56:01 {0} tempest.api.volume.test_volumes_snapshots_list.VolumesSnapshotListTestJSON.test_snapshot_list_param_sort_name_desc [0.027996s] ... ok
04:56:01 {0} tempest.api.volume.test_volumes_snapshots_list.VolumesSnapshotListTestJSON.test_snapshots_list_details_with_params [0.085405s] ... ok
04:56:01 {0} tempest.api.volume.test_volumes_snapshots_list.VolumesSnapshotListTestJSON.test_snapshots_list_with_params [0.096230s] ... ok
04:56:07 {0} tempest.api.volume.test_volumes_snapshots_negative.VolumesSnapshotNegativeTestJSON.test_create_snapshot_with_nonexistent_volume_id [0.136034s] ... ok
04:56:07 {0} tempest.api.volume.test_volumes_snapshots_negative.VolumesSnapshotNegativeTestJSON.test_create_snapshot_without_passing_volume_id [0.040542s] ... ok
04:56:07 {0} tempest.api.volume.test_volumes_snapshots_negative.VolumesSnapshotNegativeTestJSON.test_list_snapshot_invalid_param_limit [0.009245s] ... ok
04:56:08 {0} tempest.api.volume.test_volumes_snapshots_negative.VolumesSnapshotNegativeTestJSON.test_list_snapshots_invalid_param_marker [0.162224s] ... ok
04:56:08 {0} tempest.api.volume.test_volumes_snapshots_negative.VolumesSnapshotNegativeTestJSON.test_list_snapshots_invalid_param_sort [0.010787s] ... ok
04:56:10 {0} tempest.api.volume.test_volumes_snapshots_negative.VolumesSnapshotNegativeTestJSON.test_volume_from_snapshot_decreasing_size [2.635365s] ... ok
04:56:56 {0} tempest.scenario.test_encrypted_cinder_volumes.TestEncryptedCinderVolumes.test_encrypted_cinder_volumes_cryptsetup [30.995650s] ... ok
04:57:19 {0} tempest.scenario.test_encrypted_cinder_volumes.TestEncryptedCinderVolumes.test_encrypted_cinder_volumes_luks [23.259726s] ... ok
04:58:45 {0} tempest.scenario.test_minimum_basic.TestMinimumBasicScenario.test_minimum_basic_scenario [67.067674s] ... ok
05:00:17 {0} tempest.scenario.test_shelve_instance.TestShelveInstance.test_shelve_volume_backed_instance [80.155698s] ... ok
05:00:30 {0} tempest.scenario.test_stamp_pattern.TestStampPattern.test_stamp_pattern ... SKIPPED: Skipped until bug: https://launchpad.net/bugs/1664793 is resolved.
05:05:36 {0} tempest.scenario.test_volume_backup_restore.TestVolumeBackupRestore.test_volume_backup_restore [293.013894s] ... ok
05:06:14 {0} tempest.scenario.test_volume_boot_pattern.TestVolumeBootPattern.test_boot_server_from_encrypted_volume_luks [20.241179s] ... ok
05:06:34 {0} tempest.scenario.test_volume_boot_pattern.TestVolumeBootPattern.test_create_server_from_volume_snapshot [19.302428s] ... ok
05:07:05 {0} tempest.scenario.test_volume_boot_pattern.TestVolumeBootPattern.test_image_defined_boot_from_volume [31.080946s] ... ok
05:09:10 {0} tempest.scenario.test_volume_boot_pattern.TestVolumeBootPattern.test_volume_boot_pattern [124.882356s] ... ok
05:09:23 {0} setUpClass (tempest.scenario.test_volume_migrate_attached.TestVolumeMigrateRetypeAttached) ... SKIPPED: Cinder multi-backend feature disabled
05:09:27 
05:09:27 ======
05:09:27 Totals
05:09:27 ======
05:09:27 Ran: 257 tests in 4029.6337 sec.
05:09:27  - Passed: 248
05:09:27  - Skipped: 9
05:09:27  - Expected Fail: 0
05:09:27  - Unexpected Success: 0
05:09:27  - Failed: 0
05:09:27 Sum of execute time for each test: 3481.4002 sec.
05:09:27 
05:09:27 ==============
05:09:27 Worker Balance
05:09:27 ==============
05:09:27  - Worker 0 (257 tests) => 1:07:09.633739
 - Worker 0 (257 tests) => 1:07:09.633739
05:09:28 ___________________________________ summary ____________________________________
05:09:28   all: commands succeeded
05:09:28   congratulations :)
05:09:28 Job timeout set to: 71 minutes
05:09:28 Running post_test_hook
05:09:28 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:call_hook_if_defined:L82:   tsfilter post_test_hook
05:09:28 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:call_hook_if_defined:L82:   tee /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate-post_test_hook.txt
05:09:28 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:tsfilter:L93:   post_test_hook
05:09:28 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:tsfilter:L101:   awk '
05:09:28     {
05:09:28         cmd ="date +\"%Y-%m-%d %H:%M:%S.%3N | \""
        cmd ="date +\"%Y-%m-%d %H:%M:%S.%3N | \""
05:09:28         cmd | getline now
05:09:28         close("date +\"%Y-%m-%d %H:%M:%S.%3N | \"")
        close("date +\"%Y-%m-%d %H:%M:%S.%3N | \"")
05:09:28         sub(/^/, now)
05:09:28         print
05:09:28         fflush()
05:09:28     }'
05:09:29 2019-02-12 05:09:29.071 | + :post_test_hook:L0:   echo 'nothing here'
05:09:29 2019-02-12 05:09:29.072 | nothing here
05:09:29 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:tsfilter:L103:   return 0
05:09:29 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:call_hook_if_defined:L83:   local ret_val=0
05:09:29 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:call_hook_if_defined:L84:   sudo mv /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate-post_test_hook.txt /opt/stack/logs/
05:09:29 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:call_hook_if_defined:L85:   set +o pipefail
05:09:29 + /home/jenkins/workspace/EMC_VxFlexOS/devstack-gate/functions.sh:call_hook_if_defined:L86:   set +o xtrace
05:09:29 Cleaning up host
05:09:29 ... this takes 3 - 4 minutes (logs at logs/devstack-gate-cleanup-host.txt.gz)
05:12:39  [WARNING]: Could not match supplied host pattern, ignoring: subnodes
05:12:39  [WARNING]: No hosts matched, nothing to do
05:12:39 gzip: /home/jenkins/workspace/EMC_VxFlexOS/logs/ara: No such file or directory
05:12:39 SUCCESSFULLY FINISHED
05:12:44 [SCP] Connecting to oiescpprd01.isus.emc.com
05:12:44 [SCP] Copying console log.
05:12:45 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS
05:12:45 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409
05:12:45 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS
05:12:45 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876
05:12:46 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs
05:12:46 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/apache
05:12:46 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/apache/error.txt.gz'
05:12:47 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/apache/other_vhosts_access.txt.gz'
05:12:48 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/apache_config
05:12:48 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/apache_config/cinder-wsgi.conf.txt.gz'
05:12:48 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/apache_config/glance-wsgi-api.conf.txt.gz'
05:12:49 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/apache_config/keystone-wsgi-admin.conf.txt.gz'
05:12:49 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/apache_config/keystone-wsgi-public.conf.txt.gz'
05:12:50 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/apache_config/nova-api-wsgi.conf.txt.gz'
05:12:51 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/apache_config/placement-api.conf.txt.gz'
05:12:51 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/deprecations.txt.gz'
05:12:52 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/devstack-early.txt.gz'
05:12:52 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/devstack-gate-cleanup-host.txt'
05:12:53 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/devstack-gate-discover-hosts.txt.gz'
05:12:54 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/devstack-gate-post_test_hook.txt.gz'
05:12:54 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/devstack-gate-pre_test_hook.txt.gz'
05:12:55 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/devstack-gate-setup-host.txt.gz'
05:12:55 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/devstack-gate-setup-workspace-new.txt.gz'
05:12:56 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/devstack-subnodes-early.txt.gz'
05:12:56 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/devstack.journal.xz'
05:13:00 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/devstacklog.summary.txt.gz'
05:13:00 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/devstacklog.txt.gz'
05:13:01 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/df.txt.gz'
05:13:02 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/dpkg-l.txt.gz'
05:13:02 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc
05:13:02 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/cinder
05:13:02 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/cinder/api-paste.ini.txt.gz'
05:13:03 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/cinder/cinder-api-uwsgi.ini.txt.gz'
05:13:04 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/cinder/cinder.conf.txt.gz'
05:13:04 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/cinder/resource_filters.json.txt.gz'
05:13:05 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/cinder/rootwrap.conf.txt.gz'
05:13:06 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/cinder/rootwrap.d
05:13:06 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/cinder/rootwrap.d/volume.filters.txt.gz'
05:13:06 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance
05:13:06 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/glance-api-paste.ini.txt.gz'
05:13:07 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/glance-api.conf.txt.gz'
05:13:08 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/glance-cache.conf.txt.gz'
05:13:08 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/glance-image-import.conf.txt.gz'
05:13:09 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/glance-registry-paste.ini.txt.gz'
05:13:09 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/glance-registry.conf.txt.gz'
05:13:10 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/glance-swift-store.conf.txt.gz'
05:13:11 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/glance-uwsgi.ini.txt.gz'
05:13:11 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs
05:13:11 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/cim-processor-allocation-setting-data.json.txt.gz'
05:13:12 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/cim-resource-allocation-setting-data.json.txt.gz'
05:13:13 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/cim-storage-allocation-setting-data.json.txt.gz'
05:13:13 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/cim-virtual-system-setting-data.json.txt.gz'
05:13:14 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-aggr-disk-filter.json.txt.gz'
05:13:15 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-aggr-iops-filter.json.txt.gz'
05:13:15 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-aggr-num-instances.json.txt.gz'
05:13:16 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-cpu-pinning.json.txt.gz'
05:13:17 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-guest-memory-backing.json.txt.gz'
05:13:17 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-guest-shutdown.json.txt.gz'
05:13:18 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-host-capabilities.json.txt.gz'
05:13:19 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-hypervisor.json.txt.gz'
05:13:19 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-instance-data.json.txt.gz'
05:13:20 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-libvirt-image.json.txt.gz'
05:13:21 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-libvirt.json.txt.gz'
05:13:21 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-quota.json.txt.gz'
05:13:22 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-randomgen.json.txt.gz'
05:13:22 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-trust.json.txt.gz'
05:13:23 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-vcputopology.json.txt.gz'
05:13:24 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-vmware-flavor.json.txt.gz'
05:13:24 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-vmware-quota-flavor.json.txt.gz'
05:13:25 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-vmware.json.txt.gz'
05:13:26 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-watchdog.json.txt.gz'
05:13:26 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/compute-xenapi.json.txt.gz'
05:13:27 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/glance-common-image-props.json.txt.gz'
05:13:28 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/image-signature-verification.json.txt.gz'
05:13:28 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/operating-system.json.txt.gz'
05:13:29 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/software-databases.json.txt.gz'
05:13:30 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/software-runtimes.json.txt.gz'
05:13:30 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/software-webservers.json.txt.gz'
05:13:31 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/metadefs/storage-volume-type.json.txt.gz'
05:13:32 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/policy.json.txt.gz'
05:13:32 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/glance/schema-image.json.txt.gz'
05:13:33 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/keystone
05:13:33 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/keystone/credential-keys
05:13:33 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/keystone/credential-keys/0.txt.gz'
05:13:34 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/keystone/credential-keys/1.txt.gz'
05:13:34 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/keystone/fernet-keys
05:13:34 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/keystone/fernet-keys/0.txt.gz'
05:13:35 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/keystone/fernet-keys/1.txt.gz'
05:13:36 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/keystone/keystone-uwsgi-admin.ini.txt.gz'
05:13:36 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/keystone/keystone-uwsgi-public.ini.txt.gz'
05:13:37 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/keystone/keystone.conf.txt.gz'
05:13:37 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron
05:13:37 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/api-paste.ini.txt.gz'
05:13:38 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/dhcp_agent.ini.txt.gz'
05:13:39 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/l3_agent.ini.txt.gz'
05:13:39 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/metadata_agent.ini.txt.gz'
05:13:40 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/neutron.conf.txt.gz'
05:13:41 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/plugins
05:13:41 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/plugins/ml2
05:13:41 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/plugins/ml2/ml2_conf.ini.txt.gz'
05:13:41 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/policy.json.txt.gz'
05:13:42 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/rootwrap.conf.txt.gz'
05:13:43 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/rootwrap.d
05:13:43 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/rootwrap.d/debug.filters.txt.gz'
05:13:43 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/rootwrap.d/dhcp.filters.txt.gz'
05:13:44 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/rootwrap.d/dibbler.filters.txt.gz'
05:13:45 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/rootwrap.d/ebtables.filters.txt.gz'
05:13:45 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/rootwrap.d/ipset-firewall.filters.txt.gz'
05:13:46 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/rootwrap.d/iptables-firewall.filters.txt.gz'
05:13:47 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/rootwrap.d/l3.filters.txt.gz'
05:13:47 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/rootwrap.d/linuxbridge-plugin.filters.txt.gz'
05:13:48 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/rootwrap.d/netns-cleanup.filters.txt.gz'
05:13:49 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/rootwrap.d/openvswitch-plugin.filters.txt.gz'
05:13:49 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/neutron/rootwrap.d/privsep.filters.txt.gz'
05:13:50 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/nova
05:13:50 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/nova/api-paste.ini.txt.gz'
05:13:51 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/nova/nova-api-uwsgi.ini.txt.gz'
05:13:51 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/nova/nova-cpu.conf.txt.gz'
05:13:52 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/nova/nova-dhcpbridge.conf.txt.gz'
05:13:52 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/nova/nova-metadata-uwsgi.ini.txt.gz'
05:13:53 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/nova/nova.conf.txt.gz'
05:13:54 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/nova/nova_cell1.conf.txt.gz'
05:13:54 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/nova/rootwrap.conf.txt.gz'
05:13:55 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/nova/rootwrap.d
05:13:55 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/nova/rootwrap.d/api-metadata.filters.txt.gz'
05:13:56 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/nova/rootwrap.d/compute.filters.txt.gz'
05:13:56 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/nova/rootwrap.d/network.filters.txt.gz'
05:13:57 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/openstack
05:13:57 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/openstack/clouds.yaml.txt.gz'
05:13:58 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/placement
05:13:58 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/placement/placement-uwsgi.ini.txt.gz'
05:13:58 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/placement/placement.conf.txt.gz'
05:13:59 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift
05:13:59 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/account-server
05:13:59 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/account-server/1.conf.txt.gz'
05:14:00 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/account.builder.txt.gz'
05:14:00 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/account.ring.gz.txt.gz'
05:14:01 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/backups
05:14:01 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/backups/1549961843.object.builder.txt.gz'
05:14:02 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/backups/1549961844.account.builder.txt.gz'
05:14:02 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/backups/1549961844.container.builder.txt.gz'
05:14:03 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/backups/1549961846.account.builder.txt.gz'
05:14:04 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/backups/1549961846.account.ring.gz.txt.gz'
05:14:04 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/backups/1549961846.container.builder.txt.gz'
05:14:05 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/backups/1549961846.container.ring.gz.txt.gz'
05:14:06 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/backups/1549961846.object.builder.txt.gz'
05:14:06 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/backups/1549961846.object.ring.gz.txt.gz'
05:14:07 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/container-server
05:14:07 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/container-server/1.conf.txt.gz'
05:14:07 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/container-sync-realms.conf.txt.gz'
05:14:08 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/container.builder.txt.gz'
05:14:09 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/container.ring.gz.txt.gz'
05:14:09 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/internal-client.conf.txt.gz'
05:14:10 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/object-server
05:14:10 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/object-server/1.conf.txt.gz'
05:14:11 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/object.builder.txt.gz'
05:14:11 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/object.ring.gz.txt.gz'
05:14:12 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/proxy-server.conf.txt.gz'
05:14:13 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/swift.conf.txt.gz'
05:14:13 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/etc/swift/test.conf.txt.gz'
05:14:14 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/iptables.txt.gz'
05:14:14 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt
05:14:14 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/libvirtd.txt.gz'
05:14:15 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu
05:14:15 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000001.txt.gz'
05:14:16 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000002.txt.gz'
05:14:16 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000003.txt.gz'
05:14:17 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000004.txt.gz'
05:14:18 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000005.txt.gz'
05:14:18 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000006.txt.gz'
05:14:19 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000007.txt.gz'
05:14:20 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000008.txt.gz'
05:14:20 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000009.txt.gz'
05:14:21 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000000a.txt.gz'
05:14:21 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000000b.txt.gz'
05:14:22 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000000c.txt.gz'
05:14:23 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000000d.txt.gz'
05:14:23 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000000e.txt.gz'
05:14:24 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000000f.txt.gz'
05:14:25 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000010.txt.gz'
05:14:25 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000011.txt.gz'
05:14:26 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000012.txt.gz'
05:14:26 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000013.txt.gz'
05:14:27 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000014.txt.gz'
05:14:28 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000015.txt.gz'
05:14:28 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000016.txt.gz'
05:14:29 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000017.txt.gz'
05:14:30 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000018.txt.gz'
05:14:30 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000019.txt.gz'
05:14:31 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000001a.txt.gz'
05:14:31 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000001b.txt.gz'
05:14:32 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000001c.txt.gz'
05:14:33 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000001d.txt.gz'
05:14:33 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000001e.txt.gz'
05:14:34 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000001f.txt.gz'
05:14:35 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000020.txt.gz'
05:14:35 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000021.txt.gz'
05:14:36 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000022.txt.gz'
05:14:36 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000023.txt.gz'
05:14:37 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000024.txt.gz'
05:14:38 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000025.txt.gz'
05:14:38 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000026.txt.gz'
05:14:39 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000027.txt.gz'
05:14:39 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000028.txt.gz'
05:14:40 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000029.txt.gz'
05:14:41 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000002a.txt.gz'
05:14:41 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000002b.txt.gz'
05:14:42 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000002c.txt.gz'
05:14:43 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000002d.txt.gz'
05:14:43 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000002e.txt.gz'
05:14:44 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-0000002f.txt.gz'
05:14:44 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/libvirt/qemu/instance-00000030.txt.gz'
05:14:45 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/listen53.txt.gz'
05:14:46 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/local.conf.txt.gz'
05:14:46 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch
05:14:46 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.1.gz'
05:14:47 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.10.gz'
05:14:47 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.11.gz'
05:14:48 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.12.gz'
05:14:49 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.13.gz'
05:14:49 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.14.gz'
05:14:50 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.15.gz'
05:14:50 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.16.gz'
05:14:51 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.17.gz'
05:14:52 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.18.gz'
05:14:52 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.19.gz'
05:14:53 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.2.gz'
05:14:53 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.20.gz'
05:14:54 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.21.gz'
05:14:55 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.22.gz'
05:14:55 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.23.gz'
05:14:56 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.24.gz'
05:14:56 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.25.gz'
05:14:57 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.26.gz'
05:14:58 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.27.gz'
05:14:58 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.28.gz'
05:14:59 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.29.gz'
05:14:59 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.3.gz'
05:15:00 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.30.gz'
05:15:01 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.4.gz'
05:15:01 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.5.gz'
05:15:02 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.6.gz'
05:15:02 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.7.gz'
05:15:03 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.8.gz'
05:15:04 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.log.9.gz'
05:15:04 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovs-vswitchd.txt.gz'
05:15:05 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.1.gz'
05:15:05 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.10.gz'
05:15:06 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.11.gz'
05:15:07 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.12.gz'
05:15:07 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.13.gz'
05:15:08 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.14.gz'
05:15:08 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.15.gz'
05:15:09 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.16.gz'
05:15:10 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.17.gz'
05:15:10 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.18.gz'
05:15:11 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.19.gz'
05:15:11 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.2.gz'
05:15:12 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.20.gz'
05:15:13 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.21.gz'
05:15:13 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.22.gz'
05:15:14 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.23.gz'
05:15:14 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.24.gz'
05:15:15 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.25.gz'
05:15:16 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.26.gz'
05:15:16 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.27.gz'
05:15:17 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.28.gz'
05:15:17 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.29.gz'
05:15:18 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.3.gz'
05:15:19 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.30.gz'
05:15:19 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.4.gz'
05:15:20 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.5.gz'
05:15:20 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.6.gz'
05:15:21 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.7.gz'
05:15:22 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.8.gz'
05:15:22 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.log.9.gz'
05:15:23 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/openvswitch/ovsdb-server.txt.gz'
05:15:23 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/pip2-freeze.txt.gz'
05:15:24 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/pip3-freeze.txt.gz'
05:15:25 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/ps.txt.gz'
05:15:25 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/rabbitmq
05:15:25 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/rabbitmq/rabbit@scaleioubuntu-sasl.txt.gz'
05:15:26 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/rabbitmq/rabbit@scaleioubuntu.txt.gz'
05:15:26 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/rabbitmq/startup_err'
05:15:27 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/rabbitmq/startup_log'
05:15:27 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/reproduce.sh'
05:15:28 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/resolv_conf.txt.gz'
05:15:29 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-c-api.txt.gz'
05:15:29 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-c-bak.txt.gz'
05:15:30 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-c-sch.txt.gz'
05:15:30 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-c-vol.txt.gz'
05:15:31 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-g-api.txt.gz'
05:15:32 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-g-reg.txt.gz'
05:15:32 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-keystone.txt.gz'
05:15:33 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-n-api-meta.txt.gz'
05:15:33 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-n-api.txt.gz'
05:15:34 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-n-cond-cell1.txt.gz'
05:15:35 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-n-cpu.txt.gz'
05:15:35 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-n-sch.txt.gz'
05:15:36 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-n-super-cond.txt.gz'
05:15:36 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-placement-api.txt.gz'
05:15:37 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-q-agt.txt.gz'
05:15:38 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-q-dhcp.txt.gz'
05:15:38 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-q-l3.txt.gz'
05:15:39 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-q-meta.txt.gz'
05:15:39 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-q-svc.txt.gz'
05:15:40 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-s-account.txt.gz'
05:15:41 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-s-container-sync.txt.gz'
05:15:41 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-s-container.txt.gz'
05:15:42 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-s-object.txt.gz'
05:15:42 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/screen-s-proxy.txt.gz'
05:15:43 [SCP] Trying to create /apps/ostk_logs//98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/sudoers.d
05:15:43 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/sudoers.d/51_tempest_sh.txt.gz'
05:15:44 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/sudoers.d/README.txt.gz'
05:15:44 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/sudoers.d/cinder-rootwrap.txt.gz'
05:15:45 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/sudoers.d/jenkins-sudo.txt.gz'
05:15:45 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/sudoers.d/neutron-rootwrap.txt.gz'
05:15:46 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/sudoers.d/nova-rootwrap.txt.gz'
05:15:46 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/sudoers.txt.gz'
05:15:47 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/syslog.txt.gz'
05:15:48 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/tempest.txt.gz'
05:15:48 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/tempest_conf.txt.gz'
05:15:49 [SCP] uploading file: '/apps/ostk_logs/98/636098/3/check/EMC_VxFlexOS/ab47409/EMC_VxFlexOS/876/logs/testrepository.subunit.gz'
05:15:49 Finished: SUCCESS