Build failed in Jenkins: periodic-tempest-devstack-vm-neutron-stable-grizzly #23
See <https://jenkins02.openstack.org/job/periodic-tempest-devstack-vm-neutron-stable-grizzly/23/> ------------------------------------------ [...truncated 7307 lines...] 2013-09-25 06:05:15 + sed -i -e '/^\[DEFAULT\]/ a\ 2013-09-25 06:05:15 secure_delete = False 2013-09-25 06:05:15 ' /etc/cinder/cinder.conf 2013-09-25 06:05:15 + iniset /etc/cinder/cinder.conf DEFAULT volume_clear none 2013-09-25 06:05:15 + local file=/etc/cinder/cinder.conf 2013-09-25 06:05:15 + local section=DEFAULT 2013-09-25 06:05:15 + local option=volume_clear 2013-09-25 06:05:15 + local value=none 2013-09-25 06:05:15 + grep -q '^\[DEFAULT\]' /etc/cinder/cinder.conf 2013-09-25 06:05:15 + ini_has_option /etc/cinder/cinder.conf DEFAULT volume_clear 2013-09-25 06:05:15 + local file=/etc/cinder/cinder.conf 2013-09-25 06:05:15 + local section=DEFAULT 2013-09-25 06:05:15 + local option=volume_clear 2013-09-25 06:05:15 + local line 2013-09-25 06:05:15 ++ sed -ne '/^\[DEFAULT\]/,/^\[.*\]/ { /^volume_clear[ \t]*=/ p; }' /etc/cinder/cinder.conf 2013-09-25 06:05:15 + line= 2013-09-25 06:05:15 + '[' -n '' ']' 2013-09-25 06:05:15 + sed -i -e '/^\[DEFAULT\]/ a\ 2013-09-25 06:05:15 volume_clear = none 2013-09-25 06:05:15 ' /etc/cinder/cinder.conf 2013-09-25 06:05:15 + '[' False == True ']' 2013-09-25 06:05:15 + '[' default == XenAPINFS ']' 2013-09-25 06:05:15 + '[' default == sheepdog ']' 2013-09-25 06:05:15 + is_service_enabled quantum 2013-09-25 06:05:15 + services=quantum 2013-09-25 06:05:15 + for service in '${services}' 2013-09-25 06:05:15 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,quantum, ]] 2013-09-25 06:05:15 + return 0 2013-09-25 06:05:15 + install_quantum 2013-09-25 06:05:15 + git_clone https://github.com/openstack/neutron.git /opt/stack/new/neutron stable/grizzly 2013-09-25 06:05:15 + [[ False = \T\r\u\e ]] 2013-09-25 06:05:15 + GIT_REMOTE=https://github.com/openstack/neutron.git 2013-09-25 06:05:15 + GIT_DEST=/opt/stack/new/neutron 2013-09-25 06:05:15 + GIT_REF=stable/grizzly 2013-09-25 06:05:15 + echo stable/grizzly 2013-09-25 06:05:15 + egrep -q '^refs' 2013-09-25 06:05:15 + [[ ! -d /opt/stack/new/neutron ]] 2013-09-25 06:05:16 + [[ '' == \y\e\s ]] 2013-09-25 06:05:16 + setup_develop /opt/stack/new/neutron 2013-09-25 06:05:16 + [[ False = True ]] 2013-09-25 06:05:16 + SUDO_CMD=sudo 2013-09-25 06:05:16 + cd /opt/stack/new/neutron 2013-09-25 06:05:16 + python setup.py egg_info 2013-09-25 06:05:16 running egg_info 2013-09-25 06:05:16 creating quantum.egg-info 2013-09-25 06:05:16 writing requirements to quantum.egg-info/requires.txt 2013-09-25 06:05:16 writing quantum.egg-info/PKG-INFO 2013-09-25 06:05:16 writing eager_resources to quantum.egg-info/eager_resources.txt 2013-09-25 06:05:16 writing top-level names to quantum.egg-info/top_level.txt 2013-09-25 06:05:16 writing dependency_links to quantum.egg-info/dependency_links.txt 2013-09-25 06:05:16 writing entry points to quantum.egg-info/entry_points.txt 2013-09-25 06:05:16 writing manifest file 'quantum.egg-info/SOURCES.txt' 2013-09-25 06:05:16 reading manifest template 'MANIFEST.in' 2013-09-25 06:05:16 warning: no files found matching 'AUTHORS' 2013-09-25 06:05:16 warning: no files found matching 'ChangeLog' 2013-09-25 06:05:16 warning: no files found matching 'quantum/db/migration/alembic/script.py.mako' 2013-09-25 06:05:16 warning: no files found matching 'quantum/db/migration/alembic/versions/README' 2013-09-25 06:05:16 warning: no previously-included files matching '*.pyc' found anywhere in distribution 2013-09-25 06:05:16 writing manifest file 'quantum.egg-info/SOURCES.txt' 2013-09-25 06:05:17 ++ awk '/^.+/ {print "-f " $1}' quantum.egg-info/dependency_links.txt 2013-09-25 06:05:17 + raw_links= 2013-09-25 06:05:17 ++ echo 2013-09-25 06:05:17 ++ xargs 2013-09-25 06:05:17 + depend_links= 2013-09-25 06:05:17 ++ '[' '!' -r quantum.egg-info/requires.txt ']' 2013-09-25 06:05:17 ++ echo '-r *-info/requires.txt' 2013-09-25 06:05:17 + require_file='-r *-info/requires.txt' 2013-09-25 06:05:17 + pip_install -r quantum.egg-info/requires.txt 2013-09-25 06:05:17 + [[ False = \T\r\u\e ]] 2013-09-25 06:05:17 + [[ -z -r quantum.egg-info/requires.txt ]] 2013-09-25 06:05:17 + [[ -z deb ]] 2013-09-25 06:05:17 + [[ False = True ]] 2013-09-25 06:05:17 + SUDO_PIP=sudo 2013-09-25 06:05:17 ++ get_pip_command 2013-09-25 06:05:17 ++ is_fedora 2013-09-25 06:05:17 ++ [[ -z Ubuntu ]] 2013-09-25 06:05:17 ++ '[' Ubuntu = Fedora ']' 2013-09-25 06:05:17 ++ '[' Ubuntu = 'Red Hat' ']' 2013-09-25 06:05:17 ++ '[' Ubuntu = CentOS ']' 2013-09-25 06:05:17 ++ which pip 2013-09-25 06:05:17 + CMD_PIP=/usr/local/bin/pip 2013-09-25 06:05:17 + [[ False != \F\a\l\s\e ]] 2013-09-25 06:05:17 + sudo PIP_DOWNLOAD_CACHE=/var/cache/pip HTTP_PROXY= HTTPS_PROXY= NO_PROXY= /usr/local/bin/pip install -r quantum.egg-info/requires.txt 2013-09-25 06:05:17 Requirement already satisfied (use --upgrade to upgrade): Paste in /usr/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 1)) 2013-09-25 06:05:17 Requirement already satisfied (use --upgrade to upgrade): PasteDeploy>=1.5.0 in /usr/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 2)) 2013-09-25 06:05:17 Requirement already satisfied (use --upgrade to upgrade): Routes>=1.12.3 in /usr/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 3)) 2013-09-25 06:05:17 Requirement already satisfied (use --upgrade to upgrade): amqplib>=0.6.1 in /usr/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 4)) 2013-09-25 06:05:17 Requirement already satisfied (use --upgrade to upgrade): anyjson>=0.2.4 in /usr/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 5)) 2013-09-25 06:05:17 Requirement already satisfied (use --upgrade to upgrade): eventlet>=0.9.17 in /usr/local/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 6)) 2013-09-25 06:05:17 Requirement already satisfied (use --upgrade to upgrade): greenlet>=0.3.1 in /usr/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 7)) 2013-09-25 06:05:17 Requirement already satisfied (use --upgrade to upgrade): httplib2 in /usr/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 8)) 2013-09-25 06:05:17 Requirement already satisfied (use --upgrade to upgrade): iso8601>=0.1.4 in /usr/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 9)) 2013-09-25 06:05:17 Requirement already satisfied (use --upgrade to upgrade): kombu>=1.0.4 in /usr/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 10)) 2013-09-25 06:05:17 Requirement already satisfied (use --upgrade to upgrade): netaddr in /usr/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 11)) 2013-09-25 06:05:17 Downloading/unpacking python-quantumclient>=2.2.0,<3.0.0 (from -r quantum.egg-info/requires.txt (line 12)) 2013-09-25 06:05:17 Storing download in cache at /var/cache/pip/http%3A%2F%2Fpypi.openstack.org%2Fopenstack%2Fpython-quantumclient%2Fpython-quantumclient-2.2.3.tar.gz 2013-09-25 06:05:17 Running setup.py egg_info for package python-quantumclient 2013-09-25 06:05:17 [pbr] Processing SOURCES.txt 2013-09-25 06:05:17 warning: LocalManifestMaker: standard file '-c' not found 2013-09-25 06:05:17 2013-09-25 06:05:17 [pbr] In git context, generating filelist from git 2013-09-25 06:05:17 warning: no previously-included files matching '*.pyc' found anywhere in distribution 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): pyudev in /usr/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 13)) 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): sqlalchemy>=0.7.8,<=0.7.99 in /usr/local/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 14)) 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): WebOb>=1.2 in /usr/local/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 15)) 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): python-keystoneclient>=0.2.0 in /opt/stack/new/python-keystoneclient (from -r quantum.egg-info/requires.txt (line 16)) 2013-09-25 06:05:20 Downloading/unpacking alembic>=0.4.1 (from -r quantum.egg-info/requires.txt (line 17)) 2013-09-25 06:05:20 Storing download in cache at /var/cache/pip/http%3A%2F%2Fpypi.openstack.org%2Fopenstack%2Falembic%2Falembic-0.6.0.tar.gz 2013-09-25 06:05:20 Running setup.py egg_info for package alembic 2013-09-25 06:05:20 2013-09-25 06:05:20 warning: no files found matching '*.jpg' under directory 'docs' 2013-09-25 06:05:20 warning: no files found matching '*.sty' under directory 'docs' 2013-09-25 06:05:20 warning: no files found matching '*.dat' under directory 'tests' 2013-09-25 06:05:20 no previously-included directories found matching 'docs/build/output' 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): oslo.config>=1.1.0 in /usr/local/lib/python2.7/dist-packages (from -r quantum.egg-info/requires.txt (line 18)) 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): python-novaclient in /opt/stack/new/python-novaclient (from -r quantum.egg-info/requires.txt (line 19)) 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): d2to1>=0.2.10,<0.3 in /usr/local/lib/python2.7/dist-packages (from python-quantumclient>=2.2.0,<3.0.0->-r quantum.egg-info/requires.txt (line 12)) 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): pbr>=0.5.16,<0.6 in /usr/local/lib/python2.7/dist-packages/pbr-0.5.21-py2.7.egg (from python-quantumclient>=2.2.0,<3.0.0->-r quantum.egg-info/requires.txt (line 12)) 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): cliff>=1.4 in /usr/local/lib/python2.7/dist-packages (from python-quantumclient>=2.2.0,<3.0.0->-r quantum.egg-info/requires.txt (line 12)) 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): prettytable>=0.6,<0.8 in /usr/local/lib/python2.7/dist-packages (from python-quantumclient>=2.2.0,<3.0.0->-r quantum.egg-info/requires.txt (line 12)) 2013-09-25 06:05:20 Downloading/unpacking pyparsing>=1.5.6,<2.0 (from python-quantumclient>=2.2.0,<3.0.0->-r quantum.egg-info/requires.txt (line 12)) 2013-09-25 06:05:20 Storing download in cache at /var/cache/pip/http%3A%2F%2Fpypi.openstack.org%2Fopenstack%2Fpyparsing%2Fpyparsing-1.5.7.tar.gz 2013-09-25 06:05:20 Running setup.py egg_info for package pyparsing 2013-09-25 06:05:20 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): simplejson in /usr/lib/python2.7/dist-packages (from python-quantumclient>=2.2.0,<3.0.0->-r quantum.egg-info/requires.txt (line 12)) 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): requests>=1.1 in /usr/local/lib/python2.7/dist-packages (from python-keystoneclient>=0.2.0->-r quantum.egg-info/requires.txt (line 16)) 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): six in /usr/local/lib/python2.7/dist-packages (from python-keystoneclient>=0.2.0->-r quantum.egg-info/requires.txt (line 16)) 2013-09-25 06:05:20 Requirement already satisfied (use --upgrade to upgrade): Babel>=0.9.6 in /usr/local/lib/python2.7/dist-packages (from python-keystoneclient>=0.2.0->-r quantum.egg-info/requires.txt (line 16)) 2013-09-25 06:05:20 Downloading/unpacking Mako (from alembic>=0.4.1->-r quantum.egg-info/requires.txt (line 17)) 2013-09-25 06:05:20 Storing download in cache at /var/cache/pip/http%3A%2F%2Fpypi.openstack.org%2Fopenstack%2FMako%2FMako-0.9.0.tar.gz 2013-09-25 06:05:20 Running setup.py egg_info for package Mako 2013-09-25 06:05:20 2013-09-25 06:05:20 warning: no files found matching '*.xml' under directory 'examples' 2013-09-25 06:05:20 warning: no files found matching '*.mako' under directory 'examples' 2013-09-25 06:05:20 warning: no files found matching 'ez_setup.py' 2013-09-25 06:05:20 no previously-included directories found matching 'doc/build/output' 2013-09-25 06:05:21 Requirement already satisfied (use --upgrade to upgrade): distribute in /usr/lib/python2.7/dist-packages (from d2to1>=0.2.10,<0.3->python-quantumclient>=2.2.0,<3.0.0->-r quantum.egg-info/requires.txt (line 12)) 2013-09-25 06:05:21 Requirement already satisfied (use --upgrade to upgrade): pip>=1.0 in /usr/lib/python2.7/dist-packages (from pbr>=0.5.16,<0.6->python-quantumclient>=2.2.0,<3.0.0->-r quantum.egg-info/requires.txt (line 12)) 2013-09-25 06:05:21 Requirement already satisfied (use --upgrade to upgrade): cmd2>=0.6.7 in /usr/local/lib/python2.7/dist-packages (from cliff>=1.4->python-quantumclient>=2.2.0,<3.0.0->-r quantum.egg-info/requires.txt (line 12)) 2013-09-25 06:05:21 Requirement already satisfied (use --upgrade to upgrade): pytz>=0a in /usr/local/lib/python2.7/dist-packages (from Babel>=0.9.6->python-keystoneclient>=0.2.0->-r quantum.egg-info/requires.txt (line 16)) 2013-09-25 06:05:21 Requirement already satisfied (use --upgrade to upgrade): MarkupSafe>=0.9.2 in /usr/lib/python2.7/dist-packages (from Mako->alembic>=0.4.1->-r quantum.egg-info/requires.txt (line 17)) 2013-09-25 06:05:21 Installing collected packages: python-quantumclient, alembic, pyparsing, Mako 2013-09-25 06:05:21 Running setup.py install for python-quantumclient 2013-09-25 06:05:21 [pbr] Reusing existing SOURCES.txt 2013-09-25 06:05:21 Installing quantum script to /usr/local/bin 2013-09-25 06:05:21 Running setup.py install for alembic 2013-09-25 06:05:21 2013-09-25 06:05:21 warning: no files found matching '*.jpg' under directory 'docs' 2013-09-25 06:05:21 warning: no files found matching '*.sty' under directory 'docs' 2013-09-25 06:05:21 warning: no files found matching '*.dat' under directory 'tests' 2013-09-25 06:05:21 no previously-included directories found matching 'docs/build/output' 2013-09-25 06:05:21 Installing alembic script to /usr/local/bin 2013-09-25 06:05:21 Found existing installation: pyparsing 2.0.1 2013-09-25 06:05:21 Uninstalling pyparsing: 2013-09-25 06:05:21 Successfully uninstalled pyparsing 2013-09-25 06:05:21 Running setup.py install for pyparsing 2013-09-25 06:05:21 2013-09-25 06:05:21 Running setup.py install for Mako 2013-09-25 06:05:21 changing mode of build/scripts-2.7/mako-render from 644 to 755 2013-09-25 06:05:21 2013-09-25 06:05:21 warning: no files found matching '*.xml' under directory 'examples' 2013-09-25 06:05:21 warning: no files found matching '*.mako' under directory 'examples' 2013-09-25 06:05:21 warning: no files found matching 'ez_setup.py' 2013-09-25 06:05:21 no previously-included directories found matching 'doc/build/output' 2013-09-25 06:05:21 changing mode of /usr/local/bin/mako-render to 755 2013-09-25 06:05:21 Successfully installed python-quantumclient alembic pyparsing Mako 2013-09-25 06:05:21 Cleaning up... 2013-09-25 06:05:21 + sudo HTTP_PROXY= HTTPS_PROXY= NO_PROXY= python setup.py develop 2013-09-25 06:05:21 running develop 2013-09-25 06:05:21 Checking .pth file support in /usr/local/lib/python2.7/dist-packages/ 2013-09-25 06:05:21 /usr/bin/python -E -c pass 2013-09-25 06:05:21 TEST PASSED: /usr/local/lib/python2.7/dist-packages/ appears to support .pth files 2013-09-25 06:05:21 running egg_info 2013-09-25 06:05:21 writing requirements to quantum.egg-info/requires.txt 2013-09-25 06:05:21 writing quantum.egg-info/PKG-INFO 2013-09-25 06:05:21 writing eager_resources to quantum.egg-info/eager_resources.txt 2013-09-25 06:05:21 writing top-level names to quantum.egg-info/top_level.txt 2013-09-25 06:05:21 writing dependency_links to quantum.egg-info/dependency_links.txt 2013-09-25 06:05:21 writing entry points to quantum.egg-info/entry_points.txt 2013-09-25 06:05:21 reading manifest template 'MANIFEST.in' 2013-09-25 06:05:21 warning: no files found matching 'AUTHORS' 2013-09-25 06:05:21 warning: no files found matching 'ChangeLog' 2013-09-25 06:05:21 warning: no files found matching 'quantum/db/migration/alembic/script.py.mako' 2013-09-25 06:05:21 warning: no files found matching 'quantum/db/migration/alembic/versions/README' 2013-09-25 06:05:21 warning: no previously-included files matching '*.pyc' found anywhere in distribution 2013-09-25 06:05:21 writing manifest file 'quantum.egg-info/SOURCES.txt' 2013-09-25 06:05:21 running build_ext 2013-09-25 06:05:21 Creating /usr/local/lib/python2.7/dist-packages/quantum.egg-link (link to .) 2013-09-25 06:05:21 Adding quantum 2013.1.4.a13.g4601b14 to easy-install.pth file 2013-09-25 06:05:21 Installing quantum-debug script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-nec-agent script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-dhcp-agent-dnsmasq-lease-update script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-dhcp-agent script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-lbaas-agent script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-ryu-agent script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-l3-agent script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-check-nvp-config script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-db-manage script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-metadata-agent script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-server script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-linuxbridge-agent script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-ns-metadata-proxy script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-openvswitch-agent script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-netns-cleanup script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-ovs-cleanup script to /usr/local/bin 2013-09-25 06:05:21 Installing quantum-rootwrap script to /usr/local/bin 2013-09-25 06:05:21 2013-09-25 06:05:21 Installed /opt/stack/new/neutron 2013-09-25 06:05:21 Processing dependencies for quantum==2013.1.4.a13.g4601b14 2013-09-25 06:05:21 error: Installed distribution pyparsing 1.5.7 conflicts with requirement pyparsing>=2.0.1 2013-09-25 06:05:21 ++ failed 2013-09-25 06:05:21 ++ local r=1 2013-09-25 06:05:21 +++ jobs -p 2013-09-25 06:05:21 ++ kill 2013-09-25 06:05:21 ++ set +o xtrace 2013-09-25 06:05:21 stack.sh failed: full log in /opt/stack/new/devstacklog.txt.2013-09-25-060135 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-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/devstack-gate-cleanup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/devstack-gate-setup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/devstack-gate-setup-workspace-new.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/devstacklog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/iptables.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/kern_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/localrc.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/mysql [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/mysql/error.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/pip-freeze.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/rabbitmq [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az3-384354-sasl.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az3-384354.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/rabbitmq/startup_log.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/sudoers.d [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/sudoers.d/50_stack_sh.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/sudoers.d/90-cloudimg-ubuntu.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/sudoers.d/README.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/sudoers.d/cinder-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/sudoers.d/jenkins-sudo-grep.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/sudoers.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-384354/23/logs/syslog.txt.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.
See <https://jenkins02.openstack.org/job/periodic-tempest-devstack-vm-neutron-stable-grizzly/24/> ------------------------------------------ [...truncated 12950 lines...] 2013-09-28 06:06:20 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,swift3, ]] 2013-09-28 06:06:20 + [[ swift3 == \n\o\v\a ]] 2013-09-28 06:06:20 + [[ swift3 == \c\i\n\d\e\r ]] 2013-09-28 06:06:20 + [[ swift3 == \c\e\i\l\o\m\e\t\e\r ]] 2013-09-28 06:06:20 + [[ swift3 == \g\l\a\n\c\e ]] 2013-09-28 06:06:20 + [[ swift3 == \q\u\a\n\t\u\m ]] 2013-09-28 06:06:20 + [[ swift3 == \s\w\i\f\t ]] 2013-09-28 06:06:20 + [[ swift3 == s-* ]] 2013-09-28 06:06:20 + return 1 2013-09-28 06:06:20 + is_service_enabled zeromq 2013-09-28 06:06:20 + services=zeromq 2013-09-28 06:06:20 + for service in '${services}' 2013-09-28 06:06:20 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,zeromq, ]] 2013-09-28 06:06:20 + [[ zeromq == \n\o\v\a ]] 2013-09-28 06:06:20 + [[ zeromq == \c\i\n\d\e\r ]] 2013-09-28 06:06:20 + [[ zeromq == \c\e\i\l\o\m\e\t\e\r ]] 2013-09-28 06:06:20 + [[ zeromq == \g\l\a\n\c\e ]] 2013-09-28 06:06:20 + [[ zeromq == \q\u\a\n\t\u\m ]] 2013-09-28 06:06:20 + [[ zeromq == \s\w\i\f\t ]] 2013-09-28 06:06:20 + [[ zeromq == s-* ]] 2013-09-28 06:06:20 + return 1 2013-09-28 06:06:20 + is_service_enabled n-api 2013-09-28 06:06:20 + services=n-api 2013-09-28 06:06:20 + for service in '${services}' 2013-09-28 06:06:22 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,n-api, ]] 2013-09-28 06:06:22 + return 0 + exec + exec + [[ -n /opt/stack/new/screen-logs ]] + exec 2013-09-28 06:06:22 + echo_summary 'Starting Nova API' 2013-09-28 06:06:22 + [[ -t 3 ]] 2013-09-28 06:06:22 + echo -e Starting Nova API 2013-09-28 06:06:22 + start_nova_api 2013-09-28 06:06:22 + local service_port=8774 2013-09-28 06:06:22 + is_service_enabled tls-proxy 2013-09-28 06:06:22 + services=tls-proxy 2013-09-28 06:06:22 + for service in '${services}' 2013-09-28 06:06:22 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,tls-proxy, ]] 2013-09-28 06:06:22 + [[ tls-proxy == \n\o\v\a ]] 2013-09-28 06:06:22 + [[ tls-proxy == \c\i\n\d\e\r ]] 2013-09-28 06:06:22 + [[ tls-proxy == \c\e\i\l\o\m\e\t\e\r ]] 2013-09-28 06:06:22 + [[ tls-proxy == \g\l\a\n\c\e ]] 2013-09-28 06:06:22 + [[ tls-proxy == \q\u\a\n\t\u\m ]] 2013-09-28 06:06:22 + [[ tls-proxy == \s\w\i\f\t ]] 2013-09-28 06:06:22 + [[ tls-proxy == s-* ]] 2013-09-28 06:06:22 + return 1 2013-09-28 06:06:22 + screen_it n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-09-28 06:06:22 + SCREEN_NAME=stack 2013-09-28 06:06:22 + SERVICE_DIR=/opt/stack/new/status 2013-09-28 06:06:22 Starting Nova API 2013-09-28 06:06:22 ++ trueorfalse True False 2013-09-28 06:06:22 ++ local default=True 2013-09-28 06:06:22 ++ local testval=False 2013-09-28 06:06:22 ++ [[ -z False ]] 2013-09-28 06:06:22 ++ [[ 0 no false False FALSE =~ False ]] 2013-09-28 06:06:22 ++ echo False 2013-09-28 06:06:22 ++ return 2013-09-28 06:06:22 + USE_SCREEN=False 2013-09-28 06:06:22 + is_service_enabled n-api 2013-09-28 06:06:22 + services=n-api 2013-09-28 06:06:22 + for service in '${services}' 2013-09-28 06:06:22 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,n-api, ]] 2013-09-28 06:06:22 + return 0 2013-09-28 06:06:22 + screen_rc n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-09-28 06:06:22 + SCREEN_NAME=stack 2013-09-28 06:06:22 + SCREENRC=/opt/stack/new/devstack/stack-screenrc 2013-09-28 06:06:22 + [[ ! -e /opt/stack/new/devstack/stack-screenrc ]] 2013-09-28 06:06:22 + grep n-api /opt/stack/new/devstack/stack-screenrc 2013-09-28 06:06:22 ++ echo -ne '\015' 2013-09-28 06:06:22 + NL=$'\r' 2013-09-28 06:06:22 + echo 'screen -t n-api bash' 2013-09-28 06:06:22 + echo 'stuff "cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api "' 2013-09-28 06:06:22 + [[ False = \T\r\u\e ]] 2013-09-28 06:06:22 + run_process n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-09-28 06:06:22 + local service=n-api 2013-09-28 06:06:22 + local 'command=cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-09-28 06:06:22 + echo 20563 2013-09-28 06:06:22 + _run_process n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-09-28 06:06:22 + echo 'Waiting for nova-api to start...' 2013-09-28 06:06:22 Waiting for nova-api to start... 2013-09-28 06:06:22 + wait_for_service 60 http://127.0.0.1:8774 2013-09-28 06:06:22 + local timeout=60 2013-09-28 06:06:22 + local url=http://127.0.0.1:8774 2013-09-28 06:06:22 + timeout 60 sh -c 'while ! http_proxy= https_proxy= curl -s http://127.0.0.1:8774 >/dev/null; do sleep 1; done' 2013-09-28 06:06:22 + local service=n-api 2013-09-28 06:06:22 + local 'command=cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-09-28 06:06:22 + exec 2013-09-28 06:06:22 + exec 2013-09-28 06:06:22 + is_service_enabled tls-proxy 2013-09-28 06:06:22 + services=tls-proxy 2013-09-28 06:06:22 + for service in '${services}' 2013-09-28 06:06:22 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,tls-proxy, ]] 2013-09-28 06:06:22 + [[ tls-proxy == \n\o\v\a ]] 2013-09-28 06:06:22 + [[ tls-proxy == \c\i\n\d\e\r ]] 2013-09-28 06:06:22 + [[ tls-proxy == \c\e\i\l\o\m\e\t\e\r ]] 2013-09-28 06:06:22 + [[ tls-proxy == \g\l\a\n\c\e ]] 2013-09-28 06:06:22 + [[ tls-proxy == \q\u\a\n\t\u\m ]] 2013-09-28 06:06:22 + [[ tls-proxy == \s\w\i\f\t ]] 2013-09-28 06:06:22 + [[ tls-proxy == s-* ]] 2013-09-28 06:06:22 + return 1 2013-09-28 06:06:22 + is_service_enabled q-svc 2013-09-28 06:06:22 + services=q-svc 2013-09-28 06:06:22 + for service in '${services}' 2013-09-28 06:06:22 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,q-svc, ]] 2013-09-28 06:06:22 + return 0 2013-09-28 06:06:22 + echo_summary 'Starting Quantum' 2013-09-28 06:06:22 + [[ -t 3 ]] 2013-09-28 06:06:22 + echo -e Starting Quantum 2013-09-28 06:06:22 + start_quantum_service_and_check 2013-09-28 06:06:22 + local cfg_file 2013-09-28 06:06:22 + local 'CFG_FILE_OPTIONS=--config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-28 06:06:22 + screen_it q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-28 06:06:22 + SCREEN_NAME=stack 2013-09-28 06:06:22 + SERVICE_DIR=/opt/stack/new/status 2013-09-28 06:06:22 Starting Quantum 2013-09-28 06:06:22 ++ trueorfalse True False 2013-09-28 06:06:22 ++ local default=True 2013-09-28 06:06:22 ++ local testval=False 2013-09-28 06:06:22 ++ [[ -z False ]] 2013-09-28 06:06:25 ++ [[ 0 no false False FALSE =~ False ]] 2013-09-28 06:06:25 ++ echo False 2013-09-28 06:06:25 ++ return 2013-09-28 06:06:25 + USE_SCREEN=False 2013-09-28 06:06:25 + is_service_enabled q-svc 2013-09-28 06:06:25 + services=q-svc 2013-09-28 06:06:25 + for service in '${services}' 2013-09-28 06:06:25 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,q-svc, ]] 2013-09-28 06:06:25 + return 0 2013-09-28 06:06:25 + screen_rc q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-28 06:06:25 + SCREEN_NAME=stack 2013-09-28 06:06:25 + SCREENRC=/opt/stack/new/devstack/stack-screenrc 2013-09-28 06:06:25 + [[ ! -e /opt/stack/new/devstack/stack-screenrc ]] 2013-09-28 06:06:25 + grep q-svc /opt/stack/new/devstack/stack-screenrc 2013-09-28 06:06:25 ++ echo -ne '\015' 2013-09-28 06:06:25 + NL=$'\r' 2013-09-28 06:06:25 + echo 'screen -t q-svc bash' 2013-09-28 06:06:25 + echo 'stuff "cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini "' 2013-09-28 06:06:25 + [[ False = \T\r\u\e ]] 2013-09-28 06:06:25 + run_process q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-28 06:06:25 + local service=q-svc 2013-09-28 06:06:25 + local 'command=cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-28 06:06:25 + echo 20736 2013-09-28 06:06:25 + echo 'Waiting for Quantum to start...' 2013-09-28 06:06:25 Waiting for Quantum to start... 2013-09-28 06:06:25 + _run_process q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-28 06:06:25 + timeout 60 sh -c 'while ! http_proxy= wget -q -O- http://10.6.79.233:9696; do sleep 1; done' 2013-09-28 06:06:25 + local service=q-svc 2013-09-28 06:06:25 + local 'command=cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-28 06:06:25 + exec 2013-09-28 06:06:25 + exec 2013-09-28 06:06:25 {"versions": [{"status": "CURRENT", "id": "v2.0", "links": [{"href": "http://10.6.79.233:9696/v2.0", "rel": "self"}]}]}+ create_quantum_initial_network 2013-09-28 06:06:25 ++ keystone tenant-list 2013-09-28 06:06:25 ++ grep ' demo ' 2013-09-28 06:06:25 ++ get_field 1 2013-09-28 06:06:25 ++ read data 2013-09-28 06:06:25 ++ '[' 1 -lt 0 ']' 2013-09-28 06:06:25 ++ field='$2' 2013-09-28 06:06:25 ++ echo '| 9976c5ec2273493fb474092982744383 | demo | True |' 2013-09-28 06:06:25 ++ awk '-F[ \t]*\\|[ \t]*' '{print $2}' 2013-09-28 06:06:25 ++ read data 2013-09-28 06:06:25 + TENANT_ID=9976c5ec2273493fb474092982744383 2013-09-28 06:06:25 + is_baremetal 2013-09-28 06:06:25 + [[ 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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta =~ baremetal ]] 2013-09-28 06:06:25 + return 1 2013-09-28 06:06:25 ++ quantum net-create --tenant_id 9976c5ec2273493fb474092982744383 private 2013-09-28 06:06:25 ++ grep ' id ' 2013-09-28 06:06:25 ++ get_field 2 2013-09-28 06:06:25 ++ read data 2013-09-28 06:06:25 ++ '[' 2 -lt 0 ']' 2013-09-28 06:06:25 ++ field='$3' 2013-09-28 06:06:25 ++ echo '| id | a9f1666f-b311-4773-abd1-b114d6c7da40 |' 2013-09-28 06:06:25 ++ awk '-F[ \t]*\\|[ \t]*' '{print $3}' 2013-09-28 06:06:25 ++ read data 2013-09-28 06:06:25 + NET_ID=a9f1666f-b311-4773-abd1-b114d6c7da40 2013-09-28 06:06:25 ++ grep ' id ' 2013-09-28 06:06:25 ++ quantum subnet-create --tenant_id 9976c5ec2273493fb474092982744383 --ip_version 4 --gateway 10.1.0.1 a9f1666f-b311-4773-abd1-b114d6c7da40 10.1.0.0/24 2013-09-28 06:06:25 ++ get_field 2 2013-09-28 06:06:25 ++ read data 2013-09-28 06:06:25 ++ '[' 2 -lt 0 ']' 2013-09-28 06:06:25 ++ field='$3' 2013-09-28 06:06:25 ++ echo '| id | c673c2a9-f95a-41ca-b038-ef0d5476eb4d |' 2013-09-28 06:06:25 ++ awk '-F[ \t]*\\|[ \t]*' '{print $3}' 2013-09-28 06:06:25 ++ read data 2013-09-28 06:06:25 + SUBNET_ID=c673c2a9-f95a-41ca-b038-ef0d5476eb4d 2013-09-28 06:06:25 + [[ True == \T\r\u\e ]] 2013-09-28 06:06:25 + [[ True == \T\r\u\e ]] 2013-09-28 06:06:25 ++ quantum router-create --tenant_id 9976c5ec2273493fb474092982744383 router1 2013-09-28 06:06:25 ++ grep ' id ' 2013-09-28 06:06:25 ++ get_field 2 2013-09-28 06:06:25 ++ read data 2013-09-28 06:06:25 ++ '[' 2 -lt 0 ']' 2013-09-28 06:06:25 ++ field='$3' 2013-09-28 06:06:25 ++ echo '| id | b67738a0-e58e-4b87-80e7-907aa6d9d6ef |' 2013-09-28 06:06:25 ++ awk '-F[ \t]*\\|[ \t]*' '{print $3}' 2013-09-28 06:06:26 ++ read data 2013-09-28 06:06:26 + ROUTER_ID=b67738a0-e58e-4b87-80e7-907aa6d9d6ef 2013-09-28 06:06:26 + quantum router-interface-add b67738a0-e58e-4b87-80e7-907aa6d9d6ef c673c2a9-f95a-41ca-b038-ef0d5476eb4d 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-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/devstack-gate-cleanup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/devstack-gate-setup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/devstack-gate-setup-workspace-new.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/devstacklog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/iptables.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/kern_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/localrc.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/mysql [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/mysql/error.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/mysql/mysql-slow.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/pip-freeze.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/rabbitmq [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az2-412493-sasl.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az2-412493.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/rabbitmq/startup_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/screen-g-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/screen-g-reg.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/screen-horizon.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/screen-key.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/screen-n-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/screen-q-svc.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/screen-s-account.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/screen-s-container.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/screen-s-object.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/screen-s-proxy.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/screen-sysstat.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/sudoers.d [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/sudoers.d/50_stack_sh.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/sudoers.d/90-cloudimg-ubuntu.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/sudoers.d/README.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/sudoers.d/cinder-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/sudoers.d/jenkins-sudo-grep.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/sudoers.d/nova-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/sudoers.d/quantum-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/sudoers.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/logs/syslog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-412493/24/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.
See <https://jenkins02.openstack.org/job/periodic-tempest-devstack-vm-neutron-stable-grizzly/25/> ------------------------------------------ [...truncated 12949 lines...] 2013-09-30 06:05:52 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,swift3, ]] 2013-09-30 06:05:52 + [[ swift3 == \n\o\v\a ]] 2013-09-30 06:05:52 + [[ swift3 == \c\i\n\d\e\r ]] 2013-09-30 06:05:52 + [[ swift3 == \c\e\i\l\o\m\e\t\e\r ]] 2013-09-30 06:05:52 + [[ swift3 == \g\l\a\n\c\e ]] 2013-09-30 06:05:52 + [[ swift3 == \q\u\a\n\t\u\m ]] 2013-09-30 06:05:52 + [[ swift3 == \s\w\i\f\t ]] 2013-09-30 06:05:52 + [[ swift3 == s-* ]] 2013-09-30 06:05:52 + return 1 2013-09-30 06:05:52 + is_service_enabled zeromq 2013-09-30 06:05:52 + services=zeromq 2013-09-30 06:05:52 + for service in '${services}' 2013-09-30 06:05:52 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,zeromq, ]] 2013-09-30 06:05:52 + [[ zeromq == \n\o\v\a ]] 2013-09-30 06:05:52 + [[ zeromq == \c\i\n\d\e\r ]] 2013-09-30 06:05:52 + [[ zeromq == \c\e\i\l\o\m\e\t\e\r ]] 2013-09-30 06:05:52 + [[ zeromq == \g\l\a\n\c\e ]] 2013-09-30 06:05:52 + [[ zeromq == \q\u\a\n\t\u\m ]] 2013-09-30 06:05:52 + [[ zeromq == \s\w\i\f\t ]] 2013-09-30 06:05:52 + [[ zeromq == s-* ]] 2013-09-30 06:05:52 + return 1 2013-09-30 06:05:52 + is_service_enabled n-api 2013-09-30 06:05:52 + services=n-api 2013-09-30 06:05:52 + for service in '${services}' 2013-09-30 06:05:54 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,n-api, ]] 2013-09-30 06:05:54 + return 0 + exec + exec + [[ -n /opt/stack/new/screen-logs ]] + exec 2013-09-30 06:05:54 + echo_summary 'Starting Nova API' 2013-09-30 06:05:54 + [[ -t 3 ]] 2013-09-30 06:05:54 + echo -e Starting Nova API 2013-09-30 06:05:54 + start_nova_api 2013-09-30 06:05:54 + local service_port=8774 2013-09-30 06:05:54 + is_service_enabled tls-proxy 2013-09-30 06:05:54 + services=tls-proxy 2013-09-30 06:05:54 + for service in '${services}' 2013-09-30 06:05:54 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,tls-proxy, ]] 2013-09-30 06:05:54 + [[ tls-proxy == \n\o\v\a ]] 2013-09-30 06:05:54 + [[ tls-proxy == \c\i\n\d\e\r ]] 2013-09-30 06:05:54 + [[ tls-proxy == \c\e\i\l\o\m\e\t\e\r ]] 2013-09-30 06:05:54 + [[ tls-proxy == \g\l\a\n\c\e ]] 2013-09-30 06:05:54 + [[ tls-proxy == \q\u\a\n\t\u\m ]] 2013-09-30 06:05:54 + [[ tls-proxy == \s\w\i\f\t ]] 2013-09-30 06:05:54 + [[ tls-proxy == s-* ]] 2013-09-30 06:05:54 + return 1 2013-09-30 06:05:54 + screen_it n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-09-30 06:05:54 + SCREEN_NAME=stack 2013-09-30 06:05:54 + SERVICE_DIR=/opt/stack/new/status 2013-09-30 06:05:54 Starting Nova API 2013-09-30 06:05:54 ++ trueorfalse True False 2013-09-30 06:05:54 ++ local default=True 2013-09-30 06:05:54 ++ local testval=False 2013-09-30 06:05:54 ++ [[ -z False ]] 2013-09-30 06:05:54 ++ [[ 0 no false False FALSE =~ False ]] 2013-09-30 06:05:54 ++ echo False 2013-09-30 06:05:54 ++ return 2013-09-30 06:05:54 + USE_SCREEN=False 2013-09-30 06:05:54 + is_service_enabled n-api 2013-09-30 06:05:54 + services=n-api 2013-09-30 06:05:54 + for service in '${services}' 2013-09-30 06:05:54 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,n-api, ]] 2013-09-30 06:05:54 + return 0 2013-09-30 06:05:54 + screen_rc n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-09-30 06:05:54 + SCREEN_NAME=stack 2013-09-30 06:05:54 + SCREENRC=/opt/stack/new/devstack/stack-screenrc 2013-09-30 06:05:54 + [[ ! -e /opt/stack/new/devstack/stack-screenrc ]] 2013-09-30 06:05:54 + grep n-api /opt/stack/new/devstack/stack-screenrc 2013-09-30 06:05:54 ++ echo -ne '\015' 2013-09-30 06:05:54 + NL=$'\r' 2013-09-30 06:05:54 + echo 'screen -t n-api bash' 2013-09-30 06:05:54 + echo 'stuff "cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api "' 2013-09-30 06:05:54 + [[ False = \T\r\u\e ]] 2013-09-30 06:05:54 + run_process n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-09-30 06:05:54 + local service=n-api 2013-09-30 06:05:54 + local 'command=cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-09-30 06:05:54 + echo 20348 2013-09-30 06:05:54 + _run_process n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-09-30 06:05:54 + echo 'Waiting for nova-api to start...' 2013-09-30 06:05:54 Waiting for nova-api to start... 2013-09-30 06:05:54 + wait_for_service 60 http://127.0.0.1:8774 2013-09-30 06:05:54 + local timeout=60 2013-09-30 06:05:54 + local url=http://127.0.0.1:8774 2013-09-30 06:05:54 + timeout 60 sh -c 'while ! http_proxy= https_proxy= curl -s http://127.0.0.1:8774 >/dev/null; do sleep 1; done' 2013-09-30 06:05:54 + local service=n-api 2013-09-30 06:05:54 + local 'command=cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-09-30 06:05:54 + exec 2013-09-30 06:05:54 + exec 2013-09-30 06:05:54 + is_service_enabled tls-proxy 2013-09-30 06:05:54 + services=tls-proxy 2013-09-30 06:05:54 + for service in '${services}' 2013-09-30 06:05:54 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,tls-proxy, ]] 2013-09-30 06:05:54 + [[ tls-proxy == \n\o\v\a ]] 2013-09-30 06:05:54 + [[ tls-proxy == \c\i\n\d\e\r ]] 2013-09-30 06:05:54 + [[ tls-proxy == \c\e\i\l\o\m\e\t\e\r ]] 2013-09-30 06:05:54 + [[ tls-proxy == \g\l\a\n\c\e ]] 2013-09-30 06:05:54 + [[ tls-proxy == \q\u\a\n\t\u\m ]] 2013-09-30 06:05:54 + [[ tls-proxy == \s\w\i\f\t ]] 2013-09-30 06:05:54 + [[ tls-proxy == s-* ]] 2013-09-30 06:05:54 + return 1 2013-09-30 06:05:54 + is_service_enabled q-svc 2013-09-30 06:05:54 + services=q-svc 2013-09-30 06:05:54 + for service in '${services}' 2013-09-30 06:05:54 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,q-svc, ]] 2013-09-30 06:05:54 + return 0 2013-09-30 06:05:54 + echo_summary 'Starting Quantum' 2013-09-30 06:05:54 + [[ -t 3 ]] 2013-09-30 06:05:54 + echo -e Starting Quantum 2013-09-30 06:05:54 + start_quantum_service_and_check 2013-09-30 06:05:54 + local cfg_file 2013-09-30 06:05:54 + local 'CFG_FILE_OPTIONS=--config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-30 06:05:54 + screen_it q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-30 06:05:54 + SCREEN_NAME=stack 2013-09-30 06:05:54 + SERVICE_DIR=/opt/stack/new/status 2013-09-30 06:05:54 Starting Quantum 2013-09-30 06:05:54 ++ trueorfalse True False 2013-09-30 06:05:54 ++ local default=True 2013-09-30 06:05:54 ++ local testval=False 2013-09-30 06:05:54 ++ [[ -z False ]] 2013-09-30 06:05:58 ++ [[ 0 no false False FALSE =~ False ]] 2013-09-30 06:05:58 ++ echo False 2013-09-30 06:05:58 ++ return 2013-09-30 06:05:58 + USE_SCREEN=False 2013-09-30 06:05:58 + is_service_enabled q-svc 2013-09-30 06:05:58 + services=q-svc 2013-09-30 06:05:58 + for service in '${services}' 2013-09-30 06:05:58 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,q-svc, ]] 2013-09-30 06:05:58 + return 0 2013-09-30 06:05:58 + screen_rc q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-30 06:05:58 + SCREEN_NAME=stack 2013-09-30 06:05:58 + SCREENRC=/opt/stack/new/devstack/stack-screenrc 2013-09-30 06:05:58 + [[ ! -e /opt/stack/new/devstack/stack-screenrc ]] 2013-09-30 06:05:58 + grep q-svc /opt/stack/new/devstack/stack-screenrc 2013-09-30 06:05:58 ++ echo -ne '\015' 2013-09-30 06:05:58 + NL=$'\r' 2013-09-30 06:05:58 + echo 'screen -t q-svc bash' 2013-09-30 06:05:58 + echo 'stuff "cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini "' 2013-09-30 06:05:58 + [[ False = \T\r\u\e ]] 2013-09-30 06:05:58 + run_process q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-30 06:05:58 + local service=q-svc 2013-09-30 06:05:58 + local 'command=cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-30 06:05:58 + echo 20522 2013-09-30 06:05:58 + echo 'Waiting for Quantum to start...' 2013-09-30 06:05:58 + _run_process q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-30 06:05:58 Waiting for Quantum to start... 2013-09-30 06:05:58 + timeout 60 sh -c 'while ! http_proxy= wget -q -O- http://10.6.120.210:9696; do sleep 1; done' 2013-09-30 06:05:58 + local service=q-svc 2013-09-30 06:05:58 + local 'command=cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-09-30 06:05:58 + exec 2013-09-30 06:05:58 + exec 2013-09-30 06:05:58 {"versions": [{"status": "CURRENT", "id": "v2.0", "links": [{"href": "http://10.6.120.210:9696/v2.0", "rel": "self"}]}]}+ create_quantum_initial_network 2013-09-30 06:05:58 ++ keystone tenant-list 2013-09-30 06:05:58 ++ grep ' demo ' 2013-09-30 06:05:58 ++ get_field 1 2013-09-30 06:05:58 ++ read data 2013-09-30 06:05:58 ++ '[' 1 -lt 0 ']' 2013-09-30 06:05:58 ++ field='$2' 2013-09-30 06:05:58 ++ echo '| 521c6004a07c4062aa9a3319cf4f9928 | demo | True |' 2013-09-30 06:05:58 ++ awk '-F[ \t]*\\|[ \t]*' '{print $2}' 2013-09-30 06:05:58 ++ read data 2013-09-30 06:05:58 + TENANT_ID=521c6004a07c4062aa9a3319cf4f9928 2013-09-30 06:05:58 + is_baremetal 2013-09-30 06:05:58 + [[ 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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta =~ baremetal ]] 2013-09-30 06:05:58 + return 1 2013-09-30 06:05:58 ++ quantum net-create --tenant_id 521c6004a07c4062aa9a3319cf4f9928 private 2013-09-30 06:05:58 ++ grep ' id ' 2013-09-30 06:05:58 ++ get_field 2 2013-09-30 06:05:58 ++ read data 2013-09-30 06:05:58 ++ '[' 2 -lt 0 ']' 2013-09-30 06:05:58 ++ field='$3' 2013-09-30 06:05:58 ++ echo '| id | 44ef2cd8-9a13-460e-bc87-4e0ec16bb90f |' 2013-09-30 06:05:58 ++ awk '-F[ \t]*\\|[ \t]*' '{print $3}' 2013-09-30 06:05:58 ++ read data 2013-09-30 06:05:58 + NET_ID=44ef2cd8-9a13-460e-bc87-4e0ec16bb90f 2013-09-30 06:05:58 ++ grep ' id ' 2013-09-30 06:05:58 ++ quantum subnet-create --tenant_id 521c6004a07c4062aa9a3319cf4f9928 --ip_version 4 --gateway 10.1.0.1 44ef2cd8-9a13-460e-bc87-4e0ec16bb90f 10.1.0.0/24 2013-09-30 06:05:58 ++ get_field 2 2013-09-30 06:05:58 ++ read data 2013-09-30 06:05:58 ++ '[' 2 -lt 0 ']' 2013-09-30 06:05:58 ++ field='$3' 2013-09-30 06:05:58 ++ echo '| id | bba18d1a-4d1f-44d5-aef4-a8f0992ac7a2 |' 2013-09-30 06:05:58 ++ awk '-F[ \t]*\\|[ \t]*' '{print $3}' 2013-09-30 06:05:58 ++ read data 2013-09-30 06:05:58 + SUBNET_ID=bba18d1a-4d1f-44d5-aef4-a8f0992ac7a2 2013-09-30 06:05:58 + [[ True == \T\r\u\e ]] 2013-09-30 06:05:58 + [[ True == \T\r\u\e ]] 2013-09-30 06:05:58 ++ quantum router-create --tenant_id 521c6004a07c4062aa9a3319cf4f9928 router1 2013-09-30 06:05:58 ++ grep ' id ' 2013-09-30 06:05:58 ++ get_field 2 2013-09-30 06:05:58 ++ read data 2013-09-30 06:05:58 ++ '[' 2 -lt 0 ']' 2013-09-30 06:05:58 ++ field='$3' 2013-09-30 06:05:58 ++ echo '| id | 322698ee-300b-489a-af0e-8e31a02ef3fa |' 2013-09-30 06:05:58 ++ awk '-F[ \t]*\\|[ \t]*' '{print $3}' 2013-09-30 06:05:58 ++ read data 2013-09-30 06:05:58 + ROUTER_ID=322698ee-300b-489a-af0e-8e31a02ef3fa 2013-09-30 06:05:58 + quantum router-interface-add 322698ee-300b-489a-af0e-8e31a02ef3fa bba18d1a-4d1f-44d5-aef4-a8f0992ac7a2 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-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/devstack-gate-cleanup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/devstack-gate-setup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/devstack-gate-setup-workspace-new.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/devstacklog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/iptables.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/kern_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/localrc.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/mysql [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/mysql/error.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/mysql/mysql-slow.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/pip-freeze.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/rabbitmq [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az2-418185-sasl.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az2-418185.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/rabbitmq/startup_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/screen-g-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/screen-g-reg.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/screen-horizon.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/screen-key.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/screen-n-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/screen-q-svc.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/screen-s-account.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/screen-s-container.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/screen-s-object.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/screen-s-proxy.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/screen-sysstat.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/sudoers.d [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/sudoers.d/50_stack_sh.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/sudoers.d/90-cloudimg-ubuntu.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/sudoers.d/README.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/sudoers.d/cinder-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/sudoers.d/jenkins-sudo-grep.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/sudoers.d/nova-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/sudoers.d/quantum-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/sudoers.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/logs/syslog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-418185/25/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.
See <https://jenkins02.openstack.org/job/periodic-tempest-devstack-vm-neutron-stable-grizzly/26/> ------------------------------------------ [...truncated 12939 lines...] 2013-10-02 06:07:33 + local 'command=cd /opt/stack/new/glance; /opt/stack/new/glance/bin/glance-api --config-file=/etc/glance/glance-api.conf' 2013-10-02 06:07:33 + exec 2013-10-02 06:07:33 + exec 2013-10-02 06:07:33 {"versions": [{"status": "CURRENT", "id": "v2.1", "links": [{"href": "http://127.0.0.1:9292/v2/", "rel": "self"}]}, {"status": "SUPPORTED", "id": "v2.0", "links": [{"href": "http://127.0.0.1:9292/v2/", "rel": "self"}]}, {"status": "CURRENT", "id": "v1.1", "links": [{"href": "http://127.0.0.1:9292/v1/", "rel": "self"}]}, {"status": "SUPPORTED", "id": "v1.0", "links": [{"href": "http://127.0.0.1:9292/v1/", "rel": "self"}]}]}+ is_service_enabled key 2013-10-02 06:07:33 + services=key 2013-10-02 06:07:33 + for service in '${services}' 2013-10-02 06:07:33 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,key, ]] 2013-10-02 06:07:33 + return 0 2013-10-02 06:07:33 + is_service_enabled swift3 2013-10-02 06:07:33 + services=swift3 2013-10-02 06:07:33 + for service in '${services}' 2013-10-02 06:07:33 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,swift3, ]] 2013-10-02 06:07:33 + [[ swift3 == \n\o\v\a ]] 2013-10-02 06:07:33 + [[ swift3 == \c\i\n\d\e\r ]] 2013-10-02 06:07:33 + [[ swift3 == \c\e\i\l\o\m\e\t\e\r ]] 2013-10-02 06:07:33 + [[ swift3 == \g\l\a\n\c\e ]] 2013-10-02 06:07:33 + [[ swift3 == \q\u\a\n\t\u\m ]] 2013-10-02 06:07:33 + [[ swift3 == \s\w\i\f\t ]] 2013-10-02 06:07:33 + [[ swift3 == s-* ]] 2013-10-02 06:07:33 + return 1 2013-10-02 06:07:33 + is_service_enabled zeromq 2013-10-02 06:07:33 + services=zeromq 2013-10-02 06:07:33 + for service in '${services}' 2013-10-02 06:07:35 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,zeromq, ]] 2013-10-02 06:07:35 + [[ zeromq == \n\o\v\a ]] + exec 2013-10-02 06:07:35 + [[ zeromq == \c\i\n\d\e\r ]] + exec + [[ -n /opt/stack/new/screen-logs ]] + exec 2013-10-02 06:07:35 + [[ zeromq == \c\e\i\l\o\m\e\t\e\r ]] 2013-10-02 06:07:35 + [[ zeromq == \g\l\a\n\c\e ]] 2013-10-02 06:07:35 + [[ zeromq == \q\u\a\n\t\u\m ]] 2013-10-02 06:07:35 + [[ zeromq == \s\w\i\f\t ]] 2013-10-02 06:07:35 + [[ zeromq == s-* ]] 2013-10-02 06:07:35 + return 1 2013-10-02 06:07:35 + is_service_enabled n-api 2013-10-02 06:07:35 + services=n-api 2013-10-02 06:07:35 + for service in '${services}' 2013-10-02 06:07:35 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,n-api, ]] 2013-10-02 06:07:35 + return 0 2013-10-02 06:07:35 + echo_summary 'Starting Nova API' 2013-10-02 06:07:35 + [[ -t 3 ]] 2013-10-02 06:07:35 + echo -e Starting Nova API 2013-10-02 06:07:35 + start_nova_api 2013-10-02 06:07:35 + local service_port=8774 2013-10-02 06:07:35 + is_service_enabled tls-proxy 2013-10-02 06:07:35 + services=tls-proxy 2013-10-02 06:07:35 + for service in '${services}' 2013-10-02 06:07:35 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,tls-proxy, ]] 2013-10-02 06:07:35 + [[ tls-proxy == \n\o\v\a ]] 2013-10-02 06:07:35 + [[ tls-proxy == \c\i\n\d\e\r ]] 2013-10-02 06:07:35 + [[ tls-proxy == \c\e\i\l\o\m\e\t\e\r ]] 2013-10-02 06:07:35 + [[ tls-proxy == \g\l\a\n\c\e ]] 2013-10-02 06:07:35 + [[ tls-proxy == \q\u\a\n\t\u\m ]] 2013-10-02 06:07:35 + [[ tls-proxy == \s\w\i\f\t ]] 2013-10-02 06:07:35 + [[ tls-proxy == s-* ]] 2013-10-02 06:07:35 + return 1 2013-10-02 06:07:35 + screen_it n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-10-02 06:07:35 + SCREEN_NAME=stack 2013-10-02 06:07:35 + SERVICE_DIR=/opt/stack/new/status 2013-10-02 06:07:35 Starting Nova API 2013-10-02 06:07:35 ++ trueorfalse True False 2013-10-02 06:07:35 ++ local default=True 2013-10-02 06:07:35 ++ local testval=False 2013-10-02 06:07:35 ++ [[ -z False ]] 2013-10-02 06:07:35 ++ [[ 0 no false False FALSE =~ False ]] 2013-10-02 06:07:35 ++ echo False 2013-10-02 06:07:35 ++ return 2013-10-02 06:07:35 + USE_SCREEN=False 2013-10-02 06:07:35 + is_service_enabled n-api 2013-10-02 06:07:35 + services=n-api 2013-10-02 06:07:35 + for service in '${services}' 2013-10-02 06:07:35 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,n-api, ]] 2013-10-02 06:07:35 + return 0 2013-10-02 06:07:35 + screen_rc n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-10-02 06:07:35 + SCREEN_NAME=stack 2013-10-02 06:07:35 + SCREENRC=/opt/stack/new/devstack/stack-screenrc 2013-10-02 06:07:35 + [[ ! -e /opt/stack/new/devstack/stack-screenrc ]] 2013-10-02 06:07:35 + grep n-api /opt/stack/new/devstack/stack-screenrc 2013-10-02 06:07:35 ++ echo -ne '\015' 2013-10-02 06:07:35 + NL=$'\r' 2013-10-02 06:07:35 + echo 'screen -t n-api bash' 2013-10-02 06:07:35 + echo 'stuff "cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api "' 2013-10-02 06:07:35 + [[ False = \T\r\u\e ]] 2013-10-02 06:07:35 + run_process n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-10-02 06:07:35 + local service=n-api 2013-10-02 06:07:35 + local 'command=cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-10-02 06:07:35 + echo 20193 2013-10-02 06:07:35 + _run_process n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-10-02 06:07:35 + echo 'Waiting for nova-api to start...' 2013-10-02 06:07:35 Waiting for nova-api to start... 2013-10-02 06:07:35 + wait_for_service 60 http://127.0.0.1:8774 2013-10-02 06:07:35 + local timeout=60 2013-10-02 06:07:35 + local url=http://127.0.0.1:8774 2013-10-02 06:07:35 + timeout 60 sh -c 'while ! http_proxy= https_proxy= curl -s http://127.0.0.1:8774 >/dev/null; do sleep 1; done' 2013-10-02 06:07:35 + local service=n-api 2013-10-02 06:07:35 + local 'command=cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-10-02 06:07:35 + exec 2013-10-02 06:07:35 + exec 2013-10-02 06:07:35 + is_service_enabled tls-proxy 2013-10-02 06:07:35 + services=tls-proxy 2013-10-02 06:07:35 + for service in '${services}' 2013-10-02 06:07:35 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,tls-proxy, ]] 2013-10-02 06:07:35 + [[ tls-proxy == \n\o\v\a ]] 2013-10-02 06:07:35 + [[ tls-proxy == \c\i\n\d\e\r ]] 2013-10-02 06:07:35 + [[ tls-proxy == \c\e\i\l\o\m\e\t\e\r ]] 2013-10-02 06:07:35 + [[ tls-proxy == \g\l\a\n\c\e ]] 2013-10-02 06:07:35 + [[ tls-proxy == \q\u\a\n\t\u\m ]] 2013-10-02 06:07:35 + [[ tls-proxy == \s\w\i\f\t ]] 2013-10-02 06:07:35 + [[ tls-proxy == s-* ]] 2013-10-02 06:07:35 + return 1 2013-10-02 06:07:35 + is_service_enabled q-svc 2013-10-02 06:07:35 + services=q-svc 2013-10-02 06:07:35 + for service in '${services}' 2013-10-02 06:07:35 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,q-svc, ]] 2013-10-02 06:07:35 + return 0 2013-10-02 06:07:35 + echo_summary 'Starting Quantum' 2013-10-02 06:07:35 + [[ -t 3 ]] 2013-10-02 06:07:35 + echo -e Starting Quantum 2013-10-02 06:07:35 + start_quantum_service_and_check 2013-10-02 06:07:35 + local cfg_file 2013-10-02 06:07:38 + local 'CFG_FILE_OPTIONS=--config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-02 06:07:38 + screen_it q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-02 06:07:38 + SCREEN_NAME=stack 2013-10-02 06:07:38 + SERVICE_DIR=/opt/stack/new/status 2013-10-02 06:07:38 Starting Quantum 2013-10-02 06:07:38 ++ trueorfalse True False 2013-10-02 06:07:38 ++ local default=True 2013-10-02 06:07:38 ++ local testval=False 2013-10-02 06:07:38 ++ [[ -z False ]] 2013-10-02 06:07:38 ++ [[ 0 no false False FALSE =~ False ]] 2013-10-02 06:07:38 ++ echo False 2013-10-02 06:07:38 ++ return 2013-10-02 06:07:38 + USE_SCREEN=False 2013-10-02 06:07:38 + is_service_enabled q-svc 2013-10-02 06:07:38 + services=q-svc 2013-10-02 06:07:38 + for service in '${services}' 2013-10-02 06:07:38 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,q-svc, ]] 2013-10-02 06:07:38 + return 0 2013-10-02 06:07:38 + screen_rc q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-02 06:07:38 + SCREEN_NAME=stack 2013-10-02 06:07:38 + SCREENRC=/opt/stack/new/devstack/stack-screenrc 2013-10-02 06:07:38 + [[ ! -e /opt/stack/new/devstack/stack-screenrc ]] 2013-10-02 06:07:38 + grep q-svc /opt/stack/new/devstack/stack-screenrc 2013-10-02 06:07:38 ++ echo -ne '\015' 2013-10-02 06:07:38 + NL=$'\r' 2013-10-02 06:07:38 + echo 'screen -t q-svc bash' 2013-10-02 06:07:38 + echo 'stuff "cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini "' 2013-10-02 06:07:38 + [[ False = \T\r\u\e ]] 2013-10-02 06:07:38 + run_process q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-02 06:07:38 + local service=q-svc 2013-10-02 06:07:38 + local 'command=cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-02 06:07:38 + echo 20358 2013-10-02 06:07:38 + _run_process q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-02 06:07:38 + echo 'Waiting for Quantum to start...' 2013-10-02 06:07:38 Waiting for Quantum to start... 2013-10-02 06:07:38 + timeout 60 sh -c 'while ! http_proxy= wget -q -O- http://10.2.164.60:9696; do sleep 1; done' 2013-10-02 06:07:38 + local service=q-svc 2013-10-02 06:07:38 + local 'command=cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-02 06:07:38 + exec 2013-10-02 06:07:38 + exec 2013-10-02 06:07:38 {"versions": [{"status": "CURRENT", "id": "v2.0", "links": [{"href": "http://10.2.164.60:9696/v2.0", "rel": "self"}]}]}+ create_quantum_initial_network 2013-10-02 06:07:38 ++ keystone tenant-list 2013-10-02 06:07:38 ++ grep ' demo ' 2013-10-02 06:07:38 ++ get_field 1 2013-10-02 06:07:38 ++ read data 2013-10-02 06:07:38 ++ '[' 1 -lt 0 ']' 2013-10-02 06:07:38 ++ field='$2' 2013-10-02 06:07:38 ++ echo '| b7c88bd1f24b4270ad7ddd9368257819 | demo | True |' 2013-10-02 06:07:38 ++ awk '-F[ \t]*\\|[ \t]*' '{print $2}' 2013-10-02 06:07:38 ++ read data 2013-10-02 06:07:38 + TENANT_ID=b7c88bd1f24b4270ad7ddd9368257819 2013-10-02 06:07:38 + is_baremetal 2013-10-02 06:07:38 + [[ 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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta =~ baremetal ]] 2013-10-02 06:07:38 + return 1 2013-10-02 06:07:38 ++ quantum net-create --tenant_id b7c88bd1f24b4270ad7ddd9368257819 private 2013-10-02 06:07:38 ++ grep ' id ' 2013-10-02 06:07:38 ++ get_field 2 2013-10-02 06:07:38 ++ read data 2013-10-02 06:07:38 ++ '[' 2 -lt 0 ']' 2013-10-02 06:07:38 ++ field='$3' 2013-10-02 06:07:38 ++ echo '| id | 516904eb-1715-46b6-9a4d-bda8943cd401 |' 2013-10-02 06:07:38 ++ awk '-F[ \t]*\\|[ \t]*' '{print $3}' 2013-10-02 06:07:38 ++ read data 2013-10-02 06:07:38 + NET_ID=516904eb-1715-46b6-9a4d-bda8943cd401 2013-10-02 06:07:38 ++ quantum subnet-create --tenant_id b7c88bd1f24b4270ad7ddd9368257819 --ip_version 4 --gateway 10.1.0.1 516904eb-1715-46b6-9a4d-bda8943cd401 10.1.0.0/24 2013-10-02 06:07:38 ++ grep ' id ' 2013-10-02 06:07:38 ++ get_field 2 2013-10-02 06:07:38 ++ read data 2013-10-02 06:07:38 ++ '[' 2 -lt 0 ']' 2013-10-02 06:07:38 ++ field='$3' 2013-10-02 06:07:38 ++ echo '| id | 4f294ed8-ee66-4071-9380-679455068ef7 |' 2013-10-02 06:07:38 ++ awk '-F[ \t]*\\|[ \t]*' '{print $3}' 2013-10-02 06:07:38 ++ read data 2013-10-02 06:07:38 + SUBNET_ID=4f294ed8-ee66-4071-9380-679455068ef7 2013-10-02 06:07:38 + [[ True == \T\r\u\e ]] 2013-10-02 06:07:38 + [[ True == \T\r\u\e ]] 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-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/devstack-gate-cleanup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/devstack-gate-setup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/devstack-gate-setup-workspace-new.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/devstacklog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/iptables.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/kern_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/localrc.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/mysql [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/mysql/error.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/mysql/mysql-slow.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/pip-freeze.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/rabbitmq [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az3-438863-sasl.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az3-438863.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/rabbitmq/startup_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/screen-g-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/screen-g-reg.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/screen-horizon.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/screen-key.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/screen-n-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/screen-q-svc.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/screen-s-account.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/screen-s-container.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/screen-s-object.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/screen-s-proxy.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/screen-sysstat.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/sudoers.d [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/sudoers.d/50_stack_sh.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/sudoers.d/90-cloudimg-ubuntu.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/sudoers.d/README.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/sudoers.d/cinder-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/sudoers.d/jenkins-sudo-grep.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/sudoers.d/nova-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/sudoers.d/quantum-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/sudoers.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/logs/syslog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az3-438863/26/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.
See <https://jenkins02.openstack.org/job/periodic-tempest-devstack-vm-neutron-stable-grizzly/27/> ------------------------------------------ [...truncated 12958 lines...] 2013-10-06 06:06:01 + return 1 2013-10-06 06:06:01 + is_service_enabled zeromq 2013-10-06 06:06:01 + services=zeromq 2013-10-06 06:06:01 + for service in '${services}' 2013-10-06 06:06:01 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,zeromq, ]] 2013-10-06 06:06:01 + [[ zeromq == \n\o\v\a ]] 2013-10-06 06:06:01 + [[ zeromq == \c\i\n\d\e\r ]] 2013-10-06 06:06:01 + [[ zeromq == \c\e\i\l\o\m\e\t\e\r ]] 2013-10-06 06:06:01 + [[ zeromq == \g\l\a\n\c\e ]] 2013-10-06 06:06:01 + [[ zeromq == \q\u\a\n\t\u\m ]] 2013-10-06 06:06:01 + [[ zeromq == \s\w\i\f\t ]] 2013-10-06 06:06:01 + [[ zeromq == s-* ]] 2013-10-06 06:06:01 + return 1 2013-10-06 06:06:01 + is_service_enabled n-api 2013-10-06 06:06:01 + services=n-api 2013-10-06 06:06:01 + for service in '${services}' 2013-10-06 06:06:01 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,n-api, ]] 2013-10-06 06:06:03 + return 0 + exec 2013-10-06 06:06:03 + echo_summary 'Starting Nova API' + exec + [[ -n /opt/stack/new/screen-logs ]] + exec 2013-10-06 06:06:03 + [[ -t 3 ]] 2013-10-06 06:06:03 + echo -e Starting Nova API 2013-10-06 06:06:03 + start_nova_api 2013-10-06 06:06:03 + local service_port=8774 2013-10-06 06:06:03 + is_service_enabled tls-proxy 2013-10-06 06:06:03 + services=tls-proxy 2013-10-06 06:06:03 + for service in '${services}' 2013-10-06 06:06:03 Starting Nova API 2013-10-06 06:06:03 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,tls-proxy, ]] 2013-10-06 06:06:03 + [[ tls-proxy == \n\o\v\a ]] 2013-10-06 06:06:03 + [[ tls-proxy == \c\i\n\d\e\r ]] 2013-10-06 06:06:03 + [[ tls-proxy == \c\e\i\l\o\m\e\t\e\r ]] 2013-10-06 06:06:03 + [[ tls-proxy == \g\l\a\n\c\e ]] 2013-10-06 06:06:03 + [[ tls-proxy == \q\u\a\n\t\u\m ]] 2013-10-06 06:06:03 + [[ tls-proxy == \s\w\i\f\t ]] 2013-10-06 06:06:03 + [[ tls-proxy == s-* ]] 2013-10-06 06:06:03 + return 1 2013-10-06 06:06:03 + screen_it n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-10-06 06:06:03 + SCREEN_NAME=stack 2013-10-06 06:06:03 + SERVICE_DIR=/opt/stack/new/status 2013-10-06 06:06:03 ++ trueorfalse True False 2013-10-06 06:06:03 ++ local default=True 2013-10-06 06:06:03 ++ local testval=False 2013-10-06 06:06:03 ++ [[ -z False ]] 2013-10-06 06:06:03 ++ [[ 0 no false False FALSE =~ False ]] 2013-10-06 06:06:03 ++ echo False 2013-10-06 06:06:03 ++ return 2013-10-06 06:06:03 + USE_SCREEN=False 2013-10-06 06:06:03 + is_service_enabled n-api 2013-10-06 06:06:03 + services=n-api 2013-10-06 06:06:03 + for service in '${services}' 2013-10-06 06:06:03 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,n-api, ]] 2013-10-06 06:06:03 + return 0 2013-10-06 06:06:03 + screen_rc n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-10-06 06:06:03 + SCREEN_NAME=stack 2013-10-06 06:06:03 + SCREENRC=/opt/stack/new/devstack/stack-screenrc 2013-10-06 06:06:03 + [[ ! -e /opt/stack/new/devstack/stack-screenrc ]] 2013-10-06 06:06:03 + grep n-api /opt/stack/new/devstack/stack-screenrc 2013-10-06 06:06:03 ++ echo -ne '\015' 2013-10-06 06:06:03 + NL=$'\r' 2013-10-06 06:06:03 + echo 'screen -t n-api bash' 2013-10-06 06:06:03 + echo 'stuff "cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api "' 2013-10-06 06:06:03 + [[ False = \T\r\u\e ]] 2013-10-06 06:06:03 + run_process n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-10-06 06:06:03 + local service=n-api 2013-10-06 06:06:03 + local 'command=cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-10-06 06:06:03 + echo 20963 2013-10-06 06:06:03 + echo 'Waiting for nova-api to start...' 2013-10-06 06:06:03 Waiting for nova-api to start... 2013-10-06 06:06:03 + wait_for_service 60 http://127.0.0.1:8774 2013-10-06 06:06:03 + local timeout=60 2013-10-06 06:06:03 + local url=http://127.0.0.1:8774 2013-10-06 06:06:03 + timeout 60 sh -c 'while ! http_proxy= https_proxy= curl -s http://127.0.0.1:8774 >/dev/null; do sleep 1; done' 2013-10-06 06:06:03 + _run_process n-api 'cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-10-06 06:06:03 + local service=n-api 2013-10-06 06:06:03 + local 'command=cd /opt/stack/new/nova && /opt/stack/new/nova/bin/nova-api' 2013-10-06 06:06:03 + exec 2013-10-06 06:06:03 + exec 2013-10-06 06:06:03 + is_service_enabled tls-proxy 2013-10-06 06:06:03 + services=tls-proxy 2013-10-06 06:06:03 + for service in '${services}' 2013-10-06 06:06:03 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,tls-proxy, ]] 2013-10-06 06:06:03 + [[ tls-proxy == \n\o\v\a ]] 2013-10-06 06:06:03 + [[ tls-proxy == \c\i\n\d\e\r ]] 2013-10-06 06:06:03 + [[ tls-proxy == \c\e\i\l\o\m\e\t\e\r ]] 2013-10-06 06:06:03 + [[ tls-proxy == \g\l\a\n\c\e ]] 2013-10-06 06:06:03 + [[ tls-proxy == \q\u\a\n\t\u\m ]] 2013-10-06 06:06:03 + [[ tls-proxy == \s\w\i\f\t ]] 2013-10-06 06:06:03 + [[ tls-proxy == s-* ]] 2013-10-06 06:06:03 + return 1 2013-10-06 06:06:03 + is_service_enabled q-svc 2013-10-06 06:06:03 + services=q-svc 2013-10-06 06:06:03 + for service in '${services}' 2013-10-06 06:06:03 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,q-svc, ]] 2013-10-06 06:06:03 + return 0 2013-10-06 06:06:03 + echo_summary 'Starting Quantum' 2013-10-06 06:06:03 + [[ -t 3 ]] 2013-10-06 06:06:03 + echo -e Starting Quantum 2013-10-06 06:06:03 + start_quantum_service_and_check 2013-10-06 06:06:03 + local cfg_file 2013-10-06 06:06:03 Starting Quantum 2013-10-06 06:06:03 + local 'CFG_FILE_OPTIONS=--config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-06 06:06:03 + screen_it q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-06 06:06:03 + SCREEN_NAME=stack 2013-10-06 06:06:03 + SERVICE_DIR=/opt/stack/new/status 2013-10-06 06:06:03 ++ trueorfalse True False 2013-10-06 06:06:03 ++ local default=True 2013-10-06 06:06:03 ++ local testval=False 2013-10-06 06:06:03 ++ [[ -z False ]] 2013-10-06 06:06:03 ++ [[ 0 no false False FALSE =~ False ]] 2013-10-06 06:06:03 ++ echo False 2013-10-06 06:06:03 ++ return 2013-10-06 06:06:03 + USE_SCREEN=False 2013-10-06 06:06:03 + is_service_enabled q-svc 2013-10-06 06:06:03 + services=q-svc 2013-10-06 06:06:03 + for service in '${services}' 2013-10-06 06:06:08 + [[ ,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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta, =~ ,q-svc, ]] 2013-10-06 06:06:08 + return 0 2013-10-06 06:06:08 + screen_rc q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-06 06:06:08 + SCREEN_NAME=stack 2013-10-06 06:06:08 + SCREENRC=/opt/stack/new/devstack/stack-screenrc 2013-10-06 06:06:08 + [[ ! -e /opt/stack/new/devstack/stack-screenrc ]] 2013-10-06 06:06:08 + grep q-svc /opt/stack/new/devstack/stack-screenrc 2013-10-06 06:06:08 ++ echo -ne '\015' 2013-10-06 06:06:08 + NL=$'\r' 2013-10-06 06:06:08 + echo 'screen -t q-svc bash' 2013-10-06 06:06:08 + echo 'stuff "cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini "' 2013-10-06 06:06:08 + [[ False = \T\r\u\e ]] 2013-10-06 06:06:08 + run_process q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-06 06:06:08 + local service=q-svc 2013-10-06 06:06:08 + local 'command=cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-06 06:06:08 + echo 21134 2013-10-06 06:06:08 + _run_process q-svc 'cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-06 06:06:08 + echo 'Waiting for Quantum to start...' 2013-10-06 06:06:08 Waiting for Quantum to start... 2013-10-06 06:06:08 + timeout 60 sh -c 'while ! http_proxy= wget -q -O- http://10.7.20.15:9696; do sleep 1; done' 2013-10-06 06:06:08 + local service=q-svc 2013-10-06 06:06:08 + local 'command=cd /opt/stack/new/neutron && python /opt/stack/new/neutron/bin/quantum-server --config-file /etc/quantum/quantum.conf --config-file /etc/quantum/plugins/openvswitch/ovs_quantum_plugin.ini' 2013-10-06 06:06:08 + exec 2013-10-06 06:06:08 + exec 2013-10-06 06:06:08 {"versions": [{"status": "CURRENT", "id": "v2.0", "links": [{"href": "http://10.7.20.15:9696/v2.0", "rel": "self"}]}]}+ create_quantum_initial_network 2013-10-06 06:06:08 ++ grep ' demo ' 2013-10-06 06:06:08 ++ keystone tenant-list 2013-10-06 06:06:08 ++ get_field 1 2013-10-06 06:06:08 ++ read data 2013-10-06 06:06:08 ++ '[' 1 -lt 0 ']' 2013-10-06 06:06:08 ++ field='$2' 2013-10-06 06:06:08 ++ echo '| 526d3aab9f3048138cacdf21b72712ac | demo | True |' 2013-10-06 06:06:08 ++ awk '-F[ \t]*\\|[ \t]*' '{print $2}' 2013-10-06 06:06:08 ++ read data 2013-10-06 06:06:08 + TENANT_ID=526d3aab9f3048138cacdf21b72712ac 2013-10-06 06:06:08 + is_baremetal 2013-10-06 06:06:08 + [[ 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,quantum,q-svc,q-agt,q-dhcp,q-l3,q-meta =~ baremetal ]] 2013-10-06 06:06:08 + return 1 2013-10-06 06:06:08 ++ quantum net-create --tenant_id 526d3aab9f3048138cacdf21b72712ac private 2013-10-06 06:06:08 ++ grep ' id ' 2013-10-06 06:06:08 ++ get_field 2 2013-10-06 06:06:08 ++ read data 2013-10-06 06:06:08 ++ '[' 2 -lt 0 ']' 2013-10-06 06:06:08 ++ field='$3' 2013-10-06 06:06:08 ++ echo '| id | 2737d595-1a6c-48de-9c19-8aae7b091265 |' 2013-10-06 06:06:08 ++ awk '-F[ \t]*\\|[ \t]*' '{print $3}' 2013-10-06 06:06:08 ++ read data 2013-10-06 06:06:08 + NET_ID=2737d595-1a6c-48de-9c19-8aae7b091265 2013-10-06 06:06:08 ++ grep ' id ' 2013-10-06 06:06:08 ++ quantum subnet-create --tenant_id 526d3aab9f3048138cacdf21b72712ac --ip_version 4 --gateway 10.1.0.1 2737d595-1a6c-48de-9c19-8aae7b091265 10.1.0.0/24 2013-10-06 06:06:08 ++ get_field 2 2013-10-06 06:06:08 ++ read data 2013-10-06 06:06:08 ++ '[' 2 -lt 0 ']' 2013-10-06 06:06:08 ++ field='$3' 2013-10-06 06:06:08 ++ echo '| id | 6eef2c23-bcaa-49af-bc9f-58310c985d36 |' 2013-10-06 06:06:08 ++ awk '-F[ \t]*\\|[ \t]*' '{print $3}' 2013-10-06 06:06:08 ++ read data 2013-10-06 06:06:08 + SUBNET_ID=6eef2c23-bcaa-49af-bc9f-58310c985d36 2013-10-06 06:06:08 + [[ True == \T\r\u\e ]] 2013-10-06 06:06:08 + [[ True == \T\r\u\e ]] 2013-10-06 06:06:08 ++ grep ' id ' 2013-10-06 06:06:08 ++ quantum router-create --tenant_id 526d3aab9f3048138cacdf21b72712ac router1 2013-10-06 06:06:08 ++ get_field 2 2013-10-06 06:06:08 ++ read data 2013-10-06 06:06:08 ++ '[' 2 -lt 0 ']' 2013-10-06 06:06:08 ++ field='$3' 2013-10-06 06:06:08 ++ echo '| id | 429059b2-2475-4a2a-a72f-4f86f3276072 |' 2013-10-06 06:06:08 ++ awk '-F[ \t]*\\|[ \t]*' '{print $3}' 2013-10-06 06:06:08 ++ read data 2013-10-06 06:06:08 + ROUTER_ID=429059b2-2475-4a2a-a72f-4f86f3276072 2013-10-06 06:06:08 + quantum router-interface-add 429059b2-2475-4a2a-a72f-4f86f3276072 6eef2c23-bcaa-49af-bc9f-58310c985d36 2013-10-06 06:06:08 Added interface 03776d70-ea70-459f-9c4a-bd93345800aa to router 429059b2-2475-4a2a-a72f-4f86f3276072. 2013-10-06 06:06:08 ++ quantum net-create public -- --router:external=True 2013-10-06 06:06:08 ++ grep ' id ' 2013-10-06 06:06:08 ++ get_field 2 2013-10-06 06:06:08 ++ read data 2013-10-06 06:06:08 ++ '[' 2 -lt 0 ']' 2013-10-06 06:06:08 ++ field='$3' 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-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27 [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/devstack-gate-cleanup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/devstack-gate-setup-host.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/devstack-gate-setup-workspace-new.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/devstacklog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/iptables.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/kern_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/localrc.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/mysql [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/mysql/error.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/mysql/mysql-slow.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/pip-freeze.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/rabbitmq [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az2-556596-sasl.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/rabbitmq/rabbit@devstack-precise-hpcloud-az2-556596.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/rabbitmq/startup_log.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/screen-g-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/screen-g-reg.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/screen-horizon.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/screen-key.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/screen-n-api.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/screen-q-svc.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/screen-s-account.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/screen-s-container.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/screen-s-object.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/screen-s-proxy.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/screen-sysstat.txt.gz' [SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/sudoers.d [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/sudoers.d/50_stack_sh.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/sudoers.d/51_tempest_sh.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/sudoers.d/90-cloudimg-ubuntu.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/sudoers.d/README.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/sudoers.d/cinder-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/sudoers.d/jenkins-sudo-grep.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/sudoers.d/nova-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/sudoers.d/quantum-rootwrap.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/sudoers.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/27/logs/syslog.txt.gz' [SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-neutron-stable-grizzly/devstack-precise-hpcloud-az2-556596/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.
participants (1)
-
OpenStack Jenkins