See <https://jenkins02.openstack.org/job/periodic-tempest-devstack-vm-stable-grizzly/27/> ------------------------------------------ [...truncated 17141 lines...] + die_if_not_set 133 IP 'Failure retrieving IP address' + local exitcode=0 ++ set +o ++ grep xtrace + FXTRACE='set -o xtrace' + set +o xtrace + ping_check private 10.1.0.2 90 + is_service_enabled quantum + services=quantum + for service in '${services}' + [[ ,g-api,g-reg,key,n-api,n-crt,n-obj,n-cpu,n-sch,horizon,mysql,rabbit,sysstat,tempest,s-proxy,s-account,s-container,s-object,cinder,c-api,c-vol,c-sch,n-cond,n-net, =~ ,quantum, ]] + [[ quantum == \n\o\v\a ]] + [[ quantum == \c\i\n\d\e\r ]] + [[ quantum == \c\e\i\l\o\m\e\t\e\r ]] + [[ quantum == \g\l\a\n\c\e ]] + [[ quantum == \q\u\a\n\t\u\m ]] + [[ g-api,g-reg,key,n-api,n-crt,n-obj,n-cpu,n-sch,horizon,mysql,rabbit,sysstat,tempest,s-proxy,s-account,s-container,s-object,cinder,c-api,c-vol,c-sch,n-cond,n-net =~ q- ]] + [[ quantum == \s\w\i\f\t ]] + [[ quantum == s-* ]] + return 1 + _ping_check_novanet private 10.1.0.2 90 + local from_net=private + local ip=10.1.0.2 + local boot_timeout=90 + local expected=True + local check_command= ++ trueorfalse False ++ local default=False ++ local testval= ++ [[ -z '' ]] ++ echo False ++ return + MULTI_HOST=False + [[ False = \T\r\u\e ]] + [[ True = \T\r\u\e ]] + check_command='while ! ping -c1 -w1 10.1.0.2; do sleep 1; done' + timeout 90 sh -c 'while ! ping -c1 -w1 10.1.0.2; do sleep 1; done' PING 10.1.0.2 (10.1.0.2) 56(84) bytes of data. --- 10.1.0.2 ping statistics --- 2 packets transmitted, 0 received, 100% packet loss, time 999ms PING 10.1.0.2 (10.1.0.2) 56(84) bytes of data. --- 10.1.0.2 ping statistics --- 1 packets transmitted, 0 received, 100% packet loss, time 0ms PING 10.1.0.2 (10.1.0.2) 56(84) bytes of data. --- 10.1.0.2 ping statistics --- 1 packets transmitted, 0 received, 100% packet loss, time 0ms PING 10.1.0.2 (10.1.0.2) 56(84) bytes of data. --- 10.1.0.2 ping statistics --- 2 packets transmitted, 0 received, 100% packet loss, time 999ms PING 10.1.0.2 (10.1.0.2) 56(84) bytes of data. 64 bytes from 10.1.0.2: icmp_req=1 ttl=64 time=5.39 ms --- 10.1.0.2 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 5.395/5.395/5.395/0.000 ms ++ cinder list ++ grep ex-vol-cb0ec19d ++ get_field 2 ++ read data ++ head -1 + [[ -n '' ]] ++ date +%s + start_time=1380953449 + cinder create --display_name ex-vol-cb0ec19d --display_description 'test volume: ex-vol-cb0ec19d' 1 +---------------------+--------------------------------------+ | Property | Value | +---------------------+--------------------------------------+ | attachments | [] | | availability_zone | nova | | bootable | false | | created_at | 2013-10-05T06:10:50.375444 | | display_description | test volume: ex-vol-cb0ec19d | | display_name | ex-vol-cb0ec19d | | id | 110c1c41-65e0-4fa6-8cbd-1436d2f82aea | | metadata | {} | | size | 1 | | snapshot_id | None | | source_volid | None | | status | creating | | volume_type | None | +---------------------+--------------------------------------+ + timeout 90 sh -c 'while ! cinder list | grep ex-vol-cb0ec19d | grep available; do sleep 1; done' | 110c1c41-65e0-4fa6-8cbd-1436d2f82aea | available | ex-vol-cb0ec19d | 1 | None | false | | ++ date +%s + end_time=1380953452 + echo 'Completed cinder create in 3 seconds' Completed cinder create in 3 seconds ++ cinder list ++ grep ex-vol-cb0ec19d ++ get_field 1 ++ read data ++ head -1 ++ '[' 1 -lt 0 ']' ++ field='$2' ++ echo '| 110c1c41-65e0-4fa6-8cbd-1436d2f82aea | available | ex-vol-cb0ec19d | 1 | None | false | |' ++ awk '-F[ \t]*\\|[ \t]*' '{print $2}' ++ read data + VOL_ID=110c1c41-65e0-4fa6-8cbd-1436d2f82aea + die_if_not_set 158 VOL_ID 'Failure retrieving volume ID for ex-vol-cb0ec19d' + local exitcode=0 ++ set +o ++ grep xtrace + FXTRACE='set -o xtrace' + set +o xtrace + DEVICE=/dev/vdb ++ date +%s + start_time=1380953453 + nova volume-attach 9c764441-0ca0-474b-a981-a31cf854e9ed 110c1c41-65e0-4fa6-8cbd-1436d2f82aea /dev/vdb +----------+--------------------------------------+ | Property | Value | +----------+--------------------------------------+ | device | /dev/vdb | | serverId | 9c764441-0ca0-474b-a981-a31cf854e9ed | | id | 110c1c41-65e0-4fa6-8cbd-1436d2f82aea | | volumeId | 110c1c41-65e0-4fa6-8cbd-1436d2f82aea | +----------+--------------------------------------+ + timeout 90 sh -c 'while ! cinder list | grep ex-vol-cb0ec19d | grep in-use; do sleep 1; done' | 110c1c41-65e0-4fa6-8cbd-1436d2f82aea | in-use | ex-vol-cb0ec19d | 1 | None | false | 9c764441-0ca0-474b-a981-a31cf854e9ed | ++ date +%s + end_time=1380953458 + echo 'Completed volume-attach in 5 seconds' Completed volume-attach in 5 seconds ++ cinder list ++ get_field -1 ++ read data ++ head -1 ++ grep ex-vol-cb0ec19d ++ '[' -1 -lt 0 ']' ++ field='($(NF-1))' ++ echo '| 110c1c41-65e0-4fa6-8cbd-1436d2f82aea | in-use | ex-vol-cb0ec19d | 1 | None | false | 9c764441-0ca0-474b-a981-a31cf854e9ed |' ++ awk '-F[ \t]*\\|[ \t]*' '{print ($(NF-1))}' ++ read data + VOL_ATTACH=9c764441-0ca0-474b-a981-a31cf854e9ed + die_if_not_set 172 VOL_ATTACH 'Failure retrieving ex-vol-cb0ec19d status' + local exitcode=0 ++ set +o ++ grep xtrace + FXTRACE='set -o xtrace' + set +o xtrace + [[ 9c764441-0ca0-474b-a981-a31cf854e9ed != 9c764441-0ca0-474b-a981-a31cf854e9ed ]] ++ date +%s + start_time=1380953459 + nova volume-detach 9c764441-0ca0-474b-a981-a31cf854e9ed 110c1c41-65e0-4fa6-8cbd-1436d2f82aea + timeout 90 sh -c 'while ! cinder list | grep ex-vol-cb0ec19d | grep available; do sleep 1; done' | 110c1c41-65e0-4fa6-8cbd-1436d2f82aea | available | ex-vol-cb0ec19d | 1 | None | false | | ++ date +%s + end_time=1380953462 + echo 'Completed volume-detach in 3 seconds' Completed volume-detach in 3 seconds ++ date +%s + start_time=1380953462 + cinder delete 110c1c41-65e0-4fa6-8cbd-1436d2f82aea + timeout 90 sh -c 'while cinder list | grep ex-vol-cb0ec19d; do sleep 1; done' | 110c1c41-65e0-4fa6-8cbd-1436d2f82aea | deleting | ex-vol-cb0ec19d | 1 | None | false | | ++ date +%s + end_time=1380953465 + echo 'Completed cinder delete in 3 seconds' Completed cinder delete in 3 seconds + nova delete 9c764441-0ca0-474b-a981-a31cf854e9ed + timeout 60 sh -c 'while nova list | grep -q 9c764441-0ca0-474b-a981-a31cf854e9ed; do sleep 1; done' + nova secgroup-delete vol_secgroup +----+--------------+--------------------------+ | Id | Name | Description | +----+--------------+--------------------------+ | 5 | vol_secgroup | vol_secgroup description | +----+--------------+--------------------------+ + set +o xtrace ********************************************************************* SUCCESS: End DevStack Exercise: /opt/stack/new/devstack/exercises/volumes.sh ********************************************************************* ===================================================================== SKIP boot_from_volume SKIP client-env SKIP quantum-adv-test PASS aggregates PASS bundle PASS client-args PASS euca PASS floating_ips PASS horizon PASS sec_groups PASS swift PASS volumes ===================================================================== chown: cannot access `/opt/stack/data/tempest': No such file or directory Process leaked file descriptors. See http://wiki.jenkins-ci.org/display/JENKINS/Spawning+processes+from+build for more information Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/devstack-gate-cleanup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/devstack-gate-setup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/devstack-gate-setup-workspace-new.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/devstacklog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/iptables.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/kern_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/localrc.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/mysql [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/mysql/error.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/mysql/mysql-slow.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/pip-freeze.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/rabbitmq [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az3-516589-sasl.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az3-516589.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/rabbitmq/startup_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-c-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-c-sch.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-c-vol.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-g-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-g-reg.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-horizon.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-key.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-n-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-n-cond.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-n-cpu.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-n-crt.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-n-net.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-n-obj.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-n-sch.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-s-account.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-s-container.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-s-object.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-s-proxy.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/screen-sysstat.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/sudoers.d [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/sudoers.d/51_tempest_sh.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/sudoers.d/90-cloudimg-ubuntu.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/sudoers.d/README.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/sudoers.d/cinder-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/sudoers.d/jenkins-sudo-grep.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/sudoers.d/nova-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/sudoers.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/syslog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-516589/27/logs/sysstat.dat.gz' [SCP] No file(s) found: **/testr_results.html.gz [SCP] '**/testr_results.html.gz' doesn't match anything: '**' exists but not '**/testr_results.html.gz' [SCP] No file(s) found: **/subunit_log.txt.gz [SCP] '**/subunit_log.txt.gz' doesn't match anything: '**' exists but not '**/subunit_log.txt.gz' [SCP] Connecting to static.openstack.org [SCP] Copying console log.