New subject: Build failed in Jenkins: periodic-nova-python26-stable-folsom #222

9 May 2013


      See <https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/221/>

------------------------------------------
[...truncated 12591 lines...]
nova.compute.resource_tracker: INFO: Compute_service record created for f06e894b29bc4f62b93f7a84ef57373f 
nova.service: AUDIT: Starting volume node (version 2012.2.5-LOCALBRANCH:LOCALREVISION)
nova.service: AUDIT: Starting network node (version 2012.2.5-LOCALBRANCH:LOCALREVISION)
nova.service: AUDIT: Starting scheduler node (version 2012.2.5-LOCALBRANCH:LOCALREVISION)
nova.api.openstack.compute.extensions: AUDIT: Initializing extension manager.
nova.api.openstack.extensions: AUDIT: Loaded extension: os-keypairs
routes.middleware: DEBUG: Initialized with method overriding = True, and path info altering = True
nova.api.openstack.compute.extensions: AUDIT: Initializing extension manager.
nova.api.openstack.extensions: AUDIT: Loaded extension: os-keypairs
routes.middleware: DEBUG: Initialized with method overriding = True, and path info altering = True
nova.wsgi: INFO: osapi_compute listening on 127.0.0.1:49900
nova.tests.integrated.integrated_helpers: WARNING: http://127.0.0.1:49900/v2
nova.tests.integrated.api.client: INFO: Doing GET on /v2
nova.osapi_compute.wsgi.server: INFO: (3926) wsgi starting up on http://127.0.0.1:49900/

nova.osapi_compute.wsgi.server: INFO: (3926) accepted ('127.0.0.1', 41589)

nova.osapi_compute.wsgi.server: INFO: 127.0.0.1 - - [10/May/2013 06:27:46] "GET /v2 HTTP/1.1" 204 216 0.001049

nova.tests.integrated.api.client: INFO: Doing POST on /v2/openstack/os-keypairs
nova.tests.integrated.api.client: INFO: Body: <keypair>
    <name>keypair-fc6251f7-7bd0-4d99-acbb-cc5d3ec93237</name>
    <public_key>ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAAAgQDx8nkQv/zgGgB4rMYmIf+6A4l6Rr+o/6lHBQdW5aYd44bd8JttDCE/F/pNRr0lRE+PiqSPO8nDPHw0010JeMH9gYgnnFlyY3/OcJ02RhIPyyxYpv9FhY+2YiUkpwFOcLImyrxEsYXpD/0d3ac30bNH6Sw9JD9UZHYcpSxsIbECHw== Generated by Nova</public_key>
</keypair>
nova.osapi_compute.wsgi.server: INFO: (3926) accepted ('127.0.0.1', 41590)

routes.middleware: DEBUG: Matched POST /openstack/os-keypairs
routes.middleware: DEBUG: Route path: '/{project_id}/os-keypairs', defaults: {'action': u'create', 'controller': <nova.api.openstack.wsgi.Resource object at 0x7906f90>}
routes.middleware: DEBUG: Match dict: {'action': u'create', 'controller': <nova.api.openstack.wsgi.Resource object at 0x7906f90>, 'project_id': u'openstack'}
nova.api.openstack.wsgi: INFO: POST http://127.0.0.1:49900/v2/openstack/os-keypairs
nova.api.openstack.wsgi: ERROR: Exception handling resource: wait() got an unexpected keyword argument 'timeout'
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi Traceback (most recent call last):
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi   File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/api/openstack/wsgi.py",> line 944, in _process_stack
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi     action_result = self.dispatch(meth, request, action_args)
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi   File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/api/openstack/wsgi.py",> line 1032, in dispatch
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi     return method(req=request, **action_args)
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi   File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/api/openstack/compute/contrib/keypairs.py",> line 85, in create
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi     params['public_key'])
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi   File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/compute/api.py",> line 2207, in import_key_pair
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi     fingerprint = crypto.generate_fingerprint(public_key)
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi   File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/crypto.py",> line 133, in generate_fingerprint
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi     return _generate_fingerprint(pubfile)
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi   File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/crypto.py",> line 122, in _generate_fingerprint
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi     (out, err) = utils.execute('ssh-keygen', '-q', '-l', '-f', public_key_file)
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi   File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/utils.py",> line 200, in execute
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi     result = obj.communicate()
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi   File "/usr/lib64/python2.6/subprocess.py", line 729, in communicate
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi     stdout, stderr = self._communicate(input, endtime)
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi   File "/usr/lib64/python2.6/subprocess.py", line 1330, in _communicate
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi     self.wait(timeout=self._remaining_time(endtime))
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi TypeError: wait() got an unexpected keyword argument 'timeout'
2013-05-10 06:27:46 3926 TRACE nova.api.openstack.wsgi 
nova.api.openstack.wsgi: INFO: http://127.0.0.1:49900/v2/openstack/os-keypairs returned with HTTP 400
nova.osapi_compute.wsgi.server: INFO: 127.0.0.1 - - [10/May/2013 06:27:46] "POST /v2/openstack/os-keypairs HTTP/1.1" 400 399 0.252066

--------------------- >> end captured logging << ---------------------

======================================================================
FAIL: Get api sample of key pairs post request
----------------------------------------------------------------------
Traceback (most recent call last):
  File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/tests/integrated/test_api_samples.py",> line 884, in test_keypairs_post
    self.assertEqual(response.status, 200)
AssertionError: 400 != 200
...
...
raise self.failureException, \
          (None or '%r != %r' % (400, 200))
-------------------- >> begin captured logging << --------------------
nova.compute.manager: INFO: Loading compute driver 'nova.virt.fake.FakeDriver'
nova.service: AUDIT: Starting compute node (version 2012.2.5-LOCALBRANCH:LOCALREVISION)
nova.compute.resource_tracker: AUDIT: Free ram (MB): 7680
nova.compute.resource_tracker: AUDIT: Free disk (GB): 1028
nova.compute.resource_tracker: AUDIT: Free VCPUS: 1
nova.compute.resource_tracker: INFO: Compute_service record created for 7ad567f5e82d4462bb53f5cf76f15bae 
nova.service: AUDIT: Starting volume node (version 2012.2.5-LOCALBRANCH:LOCALREVISION)
nova.service: AUDIT: Starting network node (version 2012.2.5-LOCALBRANCH:LOCALREVISION)
nova.service: AUDIT: Starting scheduler node (version 2012.2.5-LOCALBRANCH:LOCALREVISION)
nova.api.openstack.compute.extensions: AUDIT: Initializing extension manager.
nova.api.openstack.extensions: AUDIT: Loaded extension: os-keypairs
routes.middleware: DEBUG: Initialized with method overriding = True, and path info altering = True
nova.api.openstack.compute.extensions: AUDIT: Initializing extension manager.
nova.api.openstack.extensions: AUDIT: Loaded extension: os-keypairs
routes.middleware: DEBUG: Initialized with method overriding = True, and path info altering = True
nova.wsgi: INFO: osapi_compute listening on 127.0.0.1:52093
nova.tests.integrated.integrated_helpers: WARNING: http://127.0.0.1:52093/v2
nova.tests.integrated.api.client: INFO: Doing GET on /v2
nova.osapi_compute.wsgi.server: INFO: (3926) wsgi starting up on http://127.0.0.1:52093/

nova.osapi_compute.wsgi.server: INFO: (3926) accepted ('127.0.0.1', 36971)

nova.osapi_compute.wsgi.server: INFO: 127.0.0.1 - - [10/May/2013 06:27:46] "GET /v2 HTTP/1.1" 204 216 0.001042

nova.tests.integrated.api.client: INFO: Doing POST on /v2/openstack/os-keypairs
nova.tests.integrated.api.client: INFO: Body: <keypair>
    <name>keypair-05688319-c923-4705-b2b5-807e7499e56d</name>
</keypair>
nova.osapi_compute.wsgi.server: INFO: (3926) accepted ('127.0.0.1', 36972)

routes.middleware: DEBUG: Matched POST /openstack/os-keypairs
routes.middleware: DEBUG: Route path: '/{project_id}/os-keypairs', defaults: {'action': u'create', 'controller': <nova.api.openstack.wsgi.Resource object at 0x99c9890>}
routes.middleware: DEBUG: Match dict: {'action': u'create', 'controller': <nova.api.openstack.wsgi.Resource object at 0x99c9890>, 'project_id': u'openstack'}
nova.api.openstack.wsgi: INFO: POST http://127.0.0.1:52093/v2/openstack/os-keypairs
nova.api.openstack.wsgi: ERROR: Exception handling resource: wait() got an unexpected keyword argument 'timeout'
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi Traceback (most recent call last):
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi   File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/api/openstack/wsgi.py",> line 944, in _process_stack
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi     action_result = self.dispatch(meth, request, action_args)
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi   File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/api/openstack/wsgi.py",> line 1032, in dispatch
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi     return method(req=request, **action_args)
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi   File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/api/openstack/compute/contrib/keypairs.py",> line 88, in create
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi     name)
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi   File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/compute/api.py",> line 2230, in create_key_pair
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi     private_key, public_key, fingerprint = crypto.generate_key_pair()
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi   File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/crypto.py",> line 144, in generate_key_pair
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi     '-t', 'rsa', '-f', keyfile, '-C', 'Generated by Nova')
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi   File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/utils.py",> line 200, in execute
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi     result = obj.communicate()
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi   File "/usr/lib64/python2.6/subprocess.py", line 729, in communicate
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi     stdout, stderr = self._communicate(input, endtime)
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi   File "/usr/lib64/python2.6/subprocess.py", line 1330, in _communicate
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi     self.wait(timeout=self._remaining_time(endtime))
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi TypeError: wait() got an unexpected keyword argument 'timeout'
2013-05-10 06:27:47 3926 TRACE nova.api.openstack.wsgi 
nova.api.openstack.wsgi: INFO: http://127.0.0.1:52093/v2/openstack/os-keypairs returned with HTTP 400
nova.osapi_compute.wsgi.server: INFO: 127.0.0.1 - - [10/May/2013 06:27:47] "POST /v2/openstack/os-keypairs HTTP/1.1" 400 399 0.306264

--------------------- >> end captured logging << ---------------------

======================================================================
FAIL: test_get_product_version_product_brand_does_not_fail (nova.tests.test_xenapi.XenAPISessionTestCase)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/tests/test_xenapi.py",> line 2855, in test_get_product_version_product_brand_does_not_fail
    session._get_product_version_and_brand()
AssertionError: (None, None) != ((5, 6, 2), 'XenServer')
...
...
raise self.failureException, \
          (None or '%r != %r' % ((None, None), ((5, 6, 2), 'XenServer')))
======================================================================
FAIL: test_get_product_version_product_brand_xs_6 (nova.tests.test_xenapi.XenAPISessionTestCase)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/nova/tests/test_xenapi.py",> line 2866, in test_get_product_version_product_brand_xs_6
    session._get_product_version_and_brand()
AssertionError: ((6, 0, 50), 'XenServer') != ((5, 6, 2), 'XenServer')
...
...
raise self.failureException, \
          (None or '%r != %r' % (((6, 0, 50), 'XenServer'), ((5, 6, 2), 'XenServer')))
----------------------------------------------------------------------
XML: nosetests.xml
ERROR: InvocationError: '<https://jenkins.openstack.org/job/periodic-nova-python26-stable-folsom/ws/.tox/py26/bin/nosetests'>
___________________________________ summary ____________________________________
ERROR:   py26: commands failed
+ result=1
+ echo 'Begin pip freeze output from test virtualenv:'
Begin pip freeze output from test virtualenv:
+ echo ======================================================================
======================================================================
+ .tox/py26/bin/pip freeze
Babel==0.9.6
Cheetah==2.4.4
Jinja2==2.6
Markdown==2.3.1
Paste==1.7.5.1
PasteDeploy==1.5.0
Pygments==1.6
Routes==1.12.3
SQLAlchemy==0.7.9
Sphinx==1.2b1
Tempita==0.5.1
WebOb==1.0.8
amqplib==0.6.1
anyjson==0.2.4
argparse==1.2.1
boto==2.1.1
cliff==1.3.3
cmd2==0.6.5.1
colorama==0.2.5
coverage==3.6
decorator==3.4.0
distribute==0.6.34
docutils==0.10
eventlet==0.12.1
feedparser==5.1.3
greenlet==0.4.0
httplib2==0.8
iso8601==0.1.4
jsonpatch==1.0
jsonpointer==1.0
jsonschema==1.3.0
kombu==1.0.4
logilab-astng==0.24.3
logilab-common==0.59.1
lxml==2.3.5
mox==0.5.3
netaddr==0.7.10
nose==1.2.1
nosehtmloutput==0.0.4
nova==2012.2.5
openstack.nose-plugin==0.11
oslo.config==1.1.0
paramiko==1.10.1
pep8==1.1
prettytable==0.7.2
pyOpenSSL==0.13
pycrypto==2.6
pylint==0.25.2
pyparsing==1.5.7
python-cinderclient==1.0.4
python-glanceclient==0.9.0
python-keystoneclient==0.2.3
python-quantumclient==2.2.1
requests==1.2.0
setuptools-git==1.0b1
simplejson==3.3.0
sqlalchemy-migrate==0.7.2
suds==0.4
termcolor==1.1.0
unittest2==0.5.1
warlock==1.0.0
+ echo ======================================================================
======================================================================
+ '[' -d .testrepository ']'
+ sudo /usr/local/jenkins/slave_scripts/jenkins-sudo-grep.sh post
grep: /var/log/auth.log: No such file or directory
+ sudoresult=0
+ '[' 0 -ne 0 ']'
+ /usr/local/jenkins/slave_scripts/jenkins-oom-grep.sh post
+ oomresult=0
+ '[' 0 -ne 0 ']'
++ find . -name nose_results.html
+ htmlreport=
+ '[' -f '' ']'
+ echo

+ echo 'WARNING: Unable to find  to confirm results!'
WARNING: Unable to find  to confirm results!
+ echo

+ exit 1
+ rm -rf /tmp/tmp.izY2Jzu1r9
Build step 'Execute shell' marked build as failure
[SCP] Connecting to static.openstack.org
[SCP] No file(s) found: **/*nose_results.html
[SCP] '**/*nose_results.html' doesn't match anything: '**' exists but not '**/*nose_results.html'
[SCP] Connecting to static.openstack.org
[SCP] Trying to create /srv/static/logs/periodic/periodic-nova-python26-stable-folsom/221
[SCP] Copying console log.

Build failed in Jenkins: periodic-nova-python26-stable-folsom #221