See <https://jenkins01.openstack.org/job/periodic-tempest-devstack-vm-stable-grizzly/34/> ------------------------------------------ [...truncated 17146 lines...] + 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 --- 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 --- 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 --- 1 packets transmitted, 0 received, 100% packet loss, time 0ms 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=7.43 ms --- 10.1.0.2 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 7.431/7.431/7.431/0.000 ms ++ cinder list ++ grep ex-vol-98ee4912 ++ head -1 ++ get_field 2 ++ read data + [[ -n '' ]] ++ date +%s + start_time=1380866971 + cinder create --display_name ex-vol-98ee4912 --display_description 'test volume: ex-vol-98ee4912' 1 +---------------------+--------------------------------------+ | Property | Value | +---------------------+--------------------------------------+ | attachments | [] | | availability_zone | nova | | bootable | false | | created_at | 2013-10-04T06:09:31.747118 | | display_description | test volume: ex-vol-98ee4912 | | display_name | ex-vol-98ee4912 | | id | 40d50b2f-66e8-44ee-bd66-5c7aebb24d9d | | metadata | {} | | size | 1 | | snapshot_id | None | | source_volid | None | | status | creating | | volume_type | None | +---------------------+--------------------------------------+ + timeout 90 sh -c 'while ! cinder list | grep ex-vol-98ee4912 | grep available; do sleep 1; done' | 40d50b2f-66e8-44ee-bd66-5c7aebb24d9d | available | ex-vol-98ee4912 | 1 | None | false | | ++ date +%s + end_time=1380866973 + echo 'Completed cinder create in 2 seconds' Completed cinder create in 2 seconds ++ cinder list ++ get_field 1 ++ read data ++ head -1 ++ grep ex-vol-98ee4912 ++ '[' 1 -lt 0 ']' ++ field='$2' ++ echo '| 40d50b2f-66e8-44ee-bd66-5c7aebb24d9d | available | ex-vol-98ee4912 | 1 | None | false | |' ++ awk '-F[ \t]*\\|[ \t]*' '{print $2}' ++ read data + VOL_ID=40d50b2f-66e8-44ee-bd66-5c7aebb24d9d + die_if_not_set 158 VOL_ID 'Failure retrieving volume ID for ex-vol-98ee4912' + local exitcode=0 ++ set +o ++ grep xtrace + FXTRACE='set -o xtrace' + set +o xtrace + DEVICE=/dev/vdb ++ date +%s + start_time=1380866974 + nova volume-attach d58dc198-5faa-44f3-b263-4ebbcace9dcb 40d50b2f-66e8-44ee-bd66-5c7aebb24d9d /dev/vdb +----------+--------------------------------------+ | Property | Value | +----------+--------------------------------------+ | device | /dev/vdb | | serverId | d58dc198-5faa-44f3-b263-4ebbcace9dcb | | id | 40d50b2f-66e8-44ee-bd66-5c7aebb24d9d | | volumeId | 40d50b2f-66e8-44ee-bd66-5c7aebb24d9d | +----------+--------------------------------------+ + timeout 90 sh -c 'while ! cinder list | grep ex-vol-98ee4912 | grep in-use; do sleep 1; done' | 40d50b2f-66e8-44ee-bd66-5c7aebb24d9d | in-use | ex-vol-98ee4912 | 1 | None | false | d58dc198-5faa-44f3-b263-4ebbcace9dcb | ++ date +%s + end_time=1380866979 + echo 'Completed volume-attach in 5 seconds' Completed volume-attach in 5 seconds ++ cinder list ++ head -1 ++ get_field -1 ++ read data ++ grep ex-vol-98ee4912 ++ '[' -1 -lt 0 ']' ++ field='($(NF-1))' ++ echo '| 40d50b2f-66e8-44ee-bd66-5c7aebb24d9d | in-use | ex-vol-98ee4912 | 1 | None | false | d58dc198-5faa-44f3-b263-4ebbcace9dcb |' ++ awk '-F[ \t]*\\|[ \t]*' '{print ($(NF-1))}' ++ read data + VOL_ATTACH=d58dc198-5faa-44f3-b263-4ebbcace9dcb + die_if_not_set 172 VOL_ATTACH 'Failure retrieving ex-vol-98ee4912 status' + local exitcode=0 ++ set +o ++ grep xtrace + FXTRACE='set -o xtrace' + set +o xtrace + [[ d58dc198-5faa-44f3-b263-4ebbcace9dcb != d58dc198-5faa-44f3-b263-4ebbcace9dcb ]] ++ date +%s + start_time=1380866979 + nova volume-detach d58dc198-5faa-44f3-b263-4ebbcace9dcb 40d50b2f-66e8-44ee-bd66-5c7aebb24d9d + timeout 90 sh -c 'while ! cinder list | grep ex-vol-98ee4912 | grep available; do sleep 1; done' | 40d50b2f-66e8-44ee-bd66-5c7aebb24d9d | available | ex-vol-98ee4912 | 1 | None | false | | ++ date +%s + end_time=1380866984 + echo 'Completed volume-detach in 5 seconds' Completed volume-detach in 5 seconds ++ date +%s + start_time=1380866984 + cinder delete 40d50b2f-66e8-44ee-bd66-5c7aebb24d9d + timeout 90 sh -c 'while cinder list | grep ex-vol-98ee4912; do sleep 1; done' | 40d50b2f-66e8-44ee-bd66-5c7aebb24d9d | deleting | ex-vol-98ee4912 | 1 | None | false | | ++ date +%s + end_time=1380866986 + echo 'Completed cinder delete in 2 seconds' Completed cinder delete in 2 seconds + nova delete d58dc198-5faa-44f3-b263-4ebbcace9dcb + timeout 60 sh -c 'while nova list | grep -q d58dc198-5faa-44f3-b263-4ebbcace9dcb; 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-447455 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/devstack-gate-cleanup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/devstack-gate-setup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/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-447455/34/logs/devstacklog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/iptables.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/kern_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/localrc.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/mysql [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/mysql/error.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/mysql/mysql-slow.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/pip-freeze.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/rabbitmq [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az3-447455-sasl.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az3-447455.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/rabbitmq/startup_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-c-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-c-sch.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-c-vol.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-g-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-g-reg.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-horizon.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-key.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-n-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-n-cond.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-n-cpu.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-n-crt.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-n-net.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-n-obj.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-n-sch.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-s-account.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-s-container.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-s-object.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-s-proxy.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/screen-sysstat.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/sudoers.d [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/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-447455/34/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-447455/34/logs/sudoers.d/README.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/sudoers.d/cinder-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/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-447455/34/logs/sudoers.d/nova-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/sudoers.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/logs/syslog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stable-grizzly/devstack-precise-hpcloud-az3-447455/34/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.