See <https://jenkins01.openstack.org/job/periodic-tempest-devstack-vm-postgres-stable-grizzly/29/> ------------------------------------------ [...truncated 18290 lines...] + FXTRACE='set -o xtrace' + set +o xtrace + ping_check private 10.1.0.4 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,rabbit,sysstat,tempest,s-proxy,s-account,s-container,s-object,cinder,c-api,c-vol,c-sch,n-cond,n-net,postgresql, =~ ,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,rabbit,sysstat,tempest,s-proxy,s-account,s-container,s-object,cinder,c-api,c-vol,c-sch,n-cond,n-net,postgresql =~ q- ]] + [[ quantum == \s\w\i\f\t ]] + [[ quantum == s-* ]] + return 1 + _ping_check_novanet private 10.1.0.4 90 + local from_net=private + local ip=10.1.0.4 + 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.4; do sleep 1; done' + timeout 90 sh -c 'while ! ping -c1 -w1 10.1.0.4; do sleep 1; done' PING 10.1.0.4 (10.1.0.4) 56(84) bytes of data. --- 10.1.0.4 ping statistics --- 1 packets transmitted, 0 received, 100% packet loss, time 0ms PING 10.1.0.4 (10.1.0.4) 56(84) bytes of data.
From 10.1.0.1 icmp_seq=1 Destination Host Unreachable
--- 10.1.0.4 ping statistics --- 1 packets transmitted, 0 received, +1 errors, 100% packet loss, time 0ms PING 10.1.0.4 (10.1.0.4) 56(84) bytes of data. --- 10.1.0.4 ping statistics --- 1 packets transmitted, 0 received, 100% packet loss, time 0ms PING 10.1.0.4 (10.1.0.4) 56(84) bytes of data.
From 10.1.0.1 icmp_seq=1 Destination Host Unreachable
--- 10.1.0.4 ping statistics --- 1 packets transmitted, 0 received, +1 errors, 100% packet loss, time 0ms PING 10.1.0.4 (10.1.0.4) 56(84) bytes of data. --- 10.1.0.4 ping statistics --- 1 packets transmitted, 0 received, 100% packet loss, time 0ms PING 10.1.0.4 (10.1.0.4) 56(84) bytes of data. 64 bytes from 10.1.0.4: icmp_req=1 ttl=64 time=8.58 ms --- 10.1.0.4 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 8.583/8.583/8.583/0.000 ms ++ cinder list ++ grep ex-vol-8a3ec1e3 ++ get_field 2 ++ head -1 ++ read data + [[ -n '' ]] ++ date +%s + start_time=1380866983 + cinder create --display_name ex-vol-8a3ec1e3 --display_description 'test volume: ex-vol-8a3ec1e3' 1 +---------------------+--------------------------------------+ | Property | Value | +---------------------+--------------------------------------+ | attachments | [] | | availability_zone | nova | | bootable | false | | created_at | 2013-10-04T06:09:43.770469 | | display_description | test volume: ex-vol-8a3ec1e3 | | display_name | ex-vol-8a3ec1e3 | | id | a3334e65-ed45-4bb0-a689-0ca1347479fd | | metadata | {} | | size | 1 | | snapshot_id | None | | source_volid | None | | status | creating | | volume_type | None | +---------------------+--------------------------------------+ + timeout 90 sh -c 'while ! cinder list | grep ex-vol-8a3ec1e3 | grep available; do sleep 1; done' | a3334e65-ed45-4bb0-a689-0ca1347479fd | available | ex-vol-8a3ec1e3 | 1 | None | false | | ++ date +%s + end_time=1380866986 + echo 'Completed cinder create in 3 seconds' Completed cinder create in 3 seconds ++ cinder list ++ head -1 ++ get_field 1 ++ read data ++ grep ex-vol-8a3ec1e3 ++ '[' 1 -lt 0 ']' ++ field='$2' ++ awk '-F[ \t]*\\|[ \t]*' '{print $2}' ++ echo '| a3334e65-ed45-4bb0-a689-0ca1347479fd | available | ex-vol-8a3ec1e3 | 1 | None | false | |' ++ read data + VOL_ID=a3334e65-ed45-4bb0-a689-0ca1347479fd + die_if_not_set 158 VOL_ID 'Failure retrieving volume ID for ex-vol-8a3ec1e3' + local exitcode=0 ++ set +o ++ grep xtrace + FXTRACE='set -o xtrace' + set +o xtrace + DEVICE=/dev/vdb ++ date +%s + start_time=1380866986 + nova volume-attach 1484c84a-ce7e-41d2-a0e2-81432c7ebb2c a3334e65-ed45-4bb0-a689-0ca1347479fd /dev/vdb +----------+--------------------------------------+ | Property | Value | +----------+--------------------------------------+ | device | /dev/vdb | | serverId | 1484c84a-ce7e-41d2-a0e2-81432c7ebb2c | | id | a3334e65-ed45-4bb0-a689-0ca1347479fd | | volumeId | a3334e65-ed45-4bb0-a689-0ca1347479fd | +----------+--------------------------------------+ + timeout 90 sh -c 'while ! cinder list | grep ex-vol-8a3ec1e3 | grep in-use; do sleep 1; done' | a3334e65-ed45-4bb0-a689-0ca1347479fd | in-use | ex-vol-8a3ec1e3 | 1 | None | false | 1484c84a-ce7e-41d2-a0e2-81432c7ebb2c | ++ date +%s + end_time=1380866991 + echo 'Completed volume-attach in 5 seconds' Completed volume-attach in 5 seconds ++ cinder list ++ grep ex-vol-8a3ec1e3 ++ get_field -1 ++ head -1 ++ read data ++ '[' -1 -lt 0 ']' ++ field='($(NF-1))' ++ awk '-F[ \t]*\\|[ \t]*' '{print ($(NF-1))}' ++ echo '| a3334e65-ed45-4bb0-a689-0ca1347479fd | in-use | ex-vol-8a3ec1e3 | 1 | None | false | 1484c84a-ce7e-41d2-a0e2-81432c7ebb2c |' ++ read data + VOL_ATTACH=1484c84a-ce7e-41d2-a0e2-81432c7ebb2c + die_if_not_set 172 VOL_ATTACH 'Failure retrieving ex-vol-8a3ec1e3 status' + local exitcode=0 ++ set +o ++ grep xtrace + FXTRACE='set -o xtrace' + set +o xtrace + [[ 1484c84a-ce7e-41d2-a0e2-81432c7ebb2c != 1484c84a-ce7e-41d2-a0e2-81432c7ebb2c ]] ++ date +%s + start_time=1380866992 + nova volume-detach 1484c84a-ce7e-41d2-a0e2-81432c7ebb2c a3334e65-ed45-4bb0-a689-0ca1347479fd + timeout 90 sh -c 'while ! cinder list | grep ex-vol-8a3ec1e3 | grep available; do sleep 1; done' | a3334e65-ed45-4bb0-a689-0ca1347479fd | available | ex-vol-8a3ec1e3 | 1 | None | false | | ++ date +%s + end_time=1380866997 + echo 'Completed volume-detach in 5 seconds' Completed volume-detach in 5 seconds ++ date +%s + start_time=1380866997 + cinder delete a3334e65-ed45-4bb0-a689-0ca1347479fd + timeout 90 sh -c 'while cinder list | grep ex-vol-8a3ec1e3; do sleep 1; done' | a3334e65-ed45-4bb0-a689-0ca1347479fd | deleting | ex-vol-8a3ec1e3 | 1 | None | false | | ++ date +%s + end_time=1380867000 + echo 'Completed cinder delete in 3 seconds' Completed cinder delete in 3 seconds + nova delete 1484c84a-ce7e-41d2-a0e2-81432c7ebb2c + timeout 60 sh -c 'while nova list | grep -q 1484c84a-ce7e-41d2-a0e2-81432c7ebb2c; 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-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/devstack-gate-cleanup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/devstack-gate-setup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/devstack-gate-setup-workspace-new.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/devstacklog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/iptables.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/kern_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/localrc.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/pip-freeze.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/rabbitmq [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az3-447444-sasl.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az3-447444.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/rabbitmq/startup_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-c-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-c-sch.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-c-vol.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-g-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-g-reg.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-horizon.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-key.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-n-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-n-cond.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-n-cpu.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-n-crt.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-n-net.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-n-obj.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-n-sch.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-s-account.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-s-container.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-s-object.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-s-proxy.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/screen-sysstat.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/sudoers.d [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/sudoers.d/51_tempest_sh.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/sudoers.d/90-cloudimg-ubuntu.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/sudoers.d/README.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/sudoers.d/cinder-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/sudoers.d/jenkins-sudo-grep.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/sudoers.d/nova-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/sudoers.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/logs/syslog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-postgres-stable-grizzly/devstack-precise-hpcloud-az3-447444/29/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.