Open Stack

See <https://jenkins01.openstack.org/job/periodic-glance-python27-stable-grizzly/52/>

------------------------------------------
[...truncated 1463 lines...]
routes.middleware: DEBUG: Matched GET /images/detail
routes.middleware: DEBUG: Route path: '/images/detail', defaults: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5bb2610>}
routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5bb2610>}
glance.api.policy: DEBUG: Loaded policy rules: {u'default': '@'}
glance.common.client: DEBUG: Constructed URL: http://0.0.0.0:9191/images/detail?changes-since=2011-09-05
routes.middleware: DEBUG: Initialized with method overriding = True, and path info altering = True
routes.middleware: DEBUG: Matched GET /images/detail
routes.middleware: DEBUG: Route path: '/images/detail', defaults: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5306310>}
routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5306310>}
iso8601.iso8601: DEBUG: Parsed 2011-09-05 into {'tz_sign': None, 'second_fraction': None, 'hour': None, 'tz_hour': None, 'month': u'09', 'timezone': None, 'second': None, 'tz_minute': None, 'year': u'2011', 'separator': None, 'day': u'05', 'minute': None} with default timezone <iso8601.iso8601.Utc object at 0x23ba2d0>
iso8601.iso8601: DEBUG: Got u'2011' for 'year' with default None
iso8601.iso8601: DEBUG: Got u'09' for 'month' with default None
iso8601.iso8601: DEBUG: Got u'05' for 'day' with default None
iso8601.iso8601: DEBUG: Got None for 'hour' with default None
iso8601.iso8601: DEBUG: Got None for 'minute' with default None
iso8601.iso8601: DEBUG: Got None for 'second' with default None
glance.registry.api.v1.images: INFO: Returning detailed image list
glance.registry.client: DEBUG: Registry request GET /images/detail HTTP 200 request id req-56d24ae8-14cb-4f41-bda7-e4b822be3d0c
--------------------- >> end captured logging << ---------------------

======================================================================
FAIL: glance.tests.unit.v1.test_api.TestRegistryAPI.test_get_details_filter_changes_since
----------------------------------------------------------------------
_StringException: Traceback (most recent call last):
  File "<https://jenkins01.openstack.org/job/periodic-glance-python27-stable-grizzly/ws/glance/tests/unit/v1/test_api.py",> line 1414, in test_get_details_filter_changes_since
    self.assertEquals(res.status_int, 400)
  File "<https://jenkins01.openstack.org/job/periodic-glance-python27-stable-grizzly/ws/.tox/py27/local/lib/python2.7/site-packages/testtools/testcase.py",> line 322, in assertEqual
    self.assertThat(observed, matcher, message)
  File "<https://jenkins01.openstack.org/job/periodic-glance-python27-stable-grizzly/ws/.tox/py27/local/lib/python2.7/site-packages/testtools/testcase.py",> line 417, in assertThat
    raise MismatchError(matchee, matcher, mismatch, verbose)
MismatchError: 200 != 400

-------------------- >> begin captured logging << --------------------
glance.store: DEBUG: Attempting to import store glance.store.filesystem.Store
glance.store.filesystem: ERROR: Could not find filesystem_store_datadir in configuration options.
glance.store.base: WARNING: Failed to configure store correctly: Store filesystem could not be configured correctly. Reason: Could not find filesystem_store_datadir in configuration options. Disabling add method.
glance.store: DEBUG: Registering store <class 'glance.store.filesystem.Store'> with schemes ('file', 'filesystem')
glance.store.base: DEBUG: Late loading location class glance.store.filesystem.StoreLocation
glance.store.location: DEBUG: Registering scheme file with {'store_class': <class 'glance.store.filesystem.Store'>, 'location_class': <class 'glance.store.filesystem.StoreLocation'>}
glance.store.location: DEBUG: Registering scheme filesystem with {'store_class': <class 'glance.store.filesystem.Store'>, 'location_class': <class 'glance.store.filesystem.StoreLocation'>}
glance.store: DEBUG: Attempting to import store glance.store.http.Store
glance.store: DEBUG: Registering store <class 'glance.store.http.Store'> with schemes ('http', 'https')
glance.store.base: DEBUG: Late loading location class glance.store.http.StoreLocation
glance.store.location: DEBUG: Registering scheme http with {'store_class': <class 'glance.store.http.Store'>, 'location_class': <class 'glance.store.http.StoreLocation'>}
glance.store.location: DEBUG: Registering scheme https with {'store_class': <class 'glance.store.http.Store'>, 'location_class': <class 'glance.store.http.StoreLocation'>}
glance.store: DEBUG: Attempting to import store glance.store.rbd.Store
glance.store: DEBUG: Registering store <class 'glance.store.rbd.Store'> with schemes ('rbd',)
glance.store.base: DEBUG: Late loading location class glance.store.rbd.StoreLocation
glance.store.location: DEBUG: Registering scheme rbd with {'store_class': <class 'glance.store.rbd.Store'>, 'location_class': <class 'glance.store.rbd.StoreLocation'>}
glance.store: DEBUG: Attempting to import store glance.store.s3.Store
glance.store.s3: DEBUG: Could not find s3_store_host in configuration options.
glance.store.base: WARNING: Failed to configure store correctly: Store s3 could not be configured correctly. Reason: Could not find s3_store_host in configuration options. Disabling add method.
glance.store: DEBUG: Registering store <class 'glance.store.s3.Store'> with schemes ('s3', 's3+http', 's3+https')
glance.store.base: DEBUG: Late loading location class glance.store.s3.StoreLocation
glance.store.location: DEBUG: Registering scheme s3 with {'store_class': <class 'glance.store.s3.Store'>, 'location_class': <class 'glance.store.s3.StoreLocation'>}
glance.store.location: DEBUG: Registering scheme s3+http with {'store_class': <class 'glance.store.s3.Store'>, 'location_class': <class 'glance.store.s3.StoreLocation'>}
glance.store.location: DEBUG: Registering scheme s3+https with {'store_class': <class 'glance.store.s3.Store'>, 'location_class': <class 'glance.store.s3.StoreLocation'>}
glance.store: DEBUG: Attempting to import store glance.store.swift.Store
glance.store.swift: ERROR: Could not find swift_store_auth_address in configuration options.
glance.store.base: WARNING: Failed to configure store correctly: Store swift could not be configured correctly. Reason: Could not find swift_store_auth_address in configuration options. Disabling add method.
glance.store: DEBUG: Registering store <function Store at 0x38187d0> with schemes ('swift+https', 'swift', 'swift+http')
glance.store.base: DEBUG: Late loading location class glance.store.swift.StoreLocation
glance.store.location: DEBUG: Registering scheme swift+https with {'store_class': <function Store at 0x38187d0>, 'location_class': <class 'glance.store.swift.StoreLocation'>}
glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <function Store at 0x38187d0>, 'location_class': <class 'glance.store.swift.StoreLocation'>}
glance.store.location: DEBUG: Registering scheme swift with {'store_class': <function Store at 0x38187d0>, 'location_class': <class 'glance.store.swift.StoreLocation'>}
routes.middleware: DEBUG: Initialized with method overriding = True, and path info altering = True
routes.middleware: DEBUG: Matched GET /images/detail
routes.middleware: DEBUG: Route path: '/images/detail', defaults: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
glance.registry.api.v1.images: INFO: Returning detailed image list
routes.middleware: DEBUG: Matched GET /images/detail
routes.middleware: DEBUG: Route path: '/images/detail', defaults: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
iso8601.iso8601: DEBUG: Parsed 2013-10-31T06:12:14Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'14', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'31', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x23ba2d0>
iso8601.iso8601: DEBUG: Got u'2013' for 'year' with default None
iso8601.iso8601: DEBUG: Got u'10' for 'month' with default None
iso8601.iso8601: DEBUG: Got u'31' for 'day' with default None
iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None
iso8601.iso8601: DEBUG: Got u'12' for 'minute' with default None
iso8601.iso8601: DEBUG: Got u'14' for 'second' with default None
glance.registry.api.v1.images: INFO: Returning detailed image list
routes.middleware: DEBUG: Matched GET /images/detail
routes.middleware: DEBUG: Route path: '/images/detail', defaults: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
iso8601.iso8601: DEBUG: Parsed 2013-11-02T06:12:14Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'14', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'02', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x23ba2d0>
iso8601.iso8601: DEBUG: Got u'2013' for 'year' with default None
iso8601.iso8601: DEBUG: Got u'11' for 'month' with default None
iso8601.iso8601: DEBUG: Got u'02' for 'day' with default None
iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None
iso8601.iso8601: DEBUG: Got u'12' for 'minute' with default None
iso8601.iso8601: DEBUG: Got u'14' for 'second' with default None
glance.registry.api.v1.images: INFO: Returning detailed image list
routes.middleware: DEBUG: Matched GET /images/detail
routes.middleware: DEBUG: Route path: '/images/detail', defaults: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
iso8601.iso8601: DEBUG: Parsed 2013-11-03T06:12:14+01:00 into {'tz_sign': u'+', 'second_fraction': None, 'hour': u'06', 'tz_hour': u'01', 'month': u'11', 'timezone': u'+01:00', 'second': u'14', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'03', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x23ba2d0>
iso8601.iso8601: DEBUG: Got u'01' for 'tz_hour' with default None
iso8601.iso8601: DEBUG: Got u'00' for 'tz_minute' with default None
iso8601.iso8601: DEBUG: Got u'2013' for 'year' with default None
iso8601.iso8601: DEBUG: Got u'11' for 'month' with default None
iso8601.iso8601: DEBUG: Got u'03' for 'day' with default None
iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None
iso8601.iso8601: DEBUG: Got u'12' for 'minute' with default None
iso8601.iso8601: DEBUG: Got u'14' for 'second' with default None
glance.registry.api.v1.images: INFO: Returning detailed image list
routes.middleware: DEBUG: Matched GET /images/detail
routes.middleware: DEBUG: Route path: '/images/detail', defaults: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
iso8601.iso8601: DEBUG: Parsed 2013-11-03T06:12:14-01:00 into {'tz_sign': u'-', 'second_fraction': None, 'hour': u'06', 'tz_hour': u'01', 'month': u'11', 'timezone': u'-01:00', 'second': u'14', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'03', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x23ba2d0>
iso8601.iso8601: DEBUG: Got u'01' for 'tz_hour' with default None
iso8601.iso8601: DEBUG: Got u'00' for 'tz_minute' with default None
iso8601.iso8601: DEBUG: Got u'2013' for 'year' with default None
iso8601.iso8601: DEBUG: Got u'11' for 'month' with default None
iso8601.iso8601: DEBUG: Got u'03' for 'day' with default None
iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None
iso8601.iso8601: DEBUG: Got u'12' for 'minute' with default None
iso8601.iso8601: DEBUG: Got u'14' for 'second' with default None
glance.registry.api.v1.images: INFO: Returning detailed image list
routes.middleware: DEBUG: Matched GET /images/detail
routes.middleware: DEBUG: Route path: '/images/detail', defaults: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
iso8601.iso8601: DEBUG: Parsed 2013-11-04T06:12:14Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'14', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'04', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x23ba2d0>
iso8601.iso8601: DEBUG: Got u'2013' for 'year' with default None
iso8601.iso8601: DEBUG: Got u'11' for 'month' with default None
iso8601.iso8601: DEBUG: Got u'04' for 'day' with default None
iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None
iso8601.iso8601: DEBUG: Got u'12' for 'minute' with default None
iso8601.iso8601: DEBUG: Got u'14' for 'second' with default None
glance.registry.api.v1.images: INFO: Returning detailed image list
routes.middleware: DEBUG: Matched GET /images/detail
routes.middleware: DEBUG: Route path: '/images/detail', defaults: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
routes.middleware: DEBUG: Matched GET /images/detail
routes.middleware: DEBUG: Route path: '/images/detail', defaults: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6902c90>}
iso8601.iso8601: DEBUG: Parsed 2011-09-05 into {'tz_sign': None, 'second_fraction': None, 'hour': None, 'tz_hour': None, 'month': u'09', 'timezone': None, 'second': None, 'tz_minute': None, 'year': u'2011', 'separator': None, 'day': u'05', 'minute': None} with default timezone <iso8601.iso8601.Utc object at 0x23ba2d0>
iso8601.iso8601: DEBUG: Got u'2011' for 'year' with default None
iso8601.iso8601: DEBUG: Got u'09' for 'month' with default None
iso8601.iso8601: DEBUG: Got u'05' for 'day' with default None
iso8601.iso8601: DEBUG: Got None for 'hour' with default None
iso8601.iso8601: DEBUG: Got None for 'minute' with default None
iso8601.iso8601: DEBUG: Got None for 'second' with default None
glance.registry.api.v1.images: INFO: Returning detailed image list
--------------------- >> end captured logging << ---------------------

----------------------------------------------------------------------
XML: nosetests.xml
Slowest 5 tests took 88.39 secs:
    25.80    TestScrubber.test_scrubber_app
    20.26    TestScrubber.test_delayed_delete
    14.48    TestGlanceControl.test_reload
    14.29    TestGlanceControl.test_respawn
    13.56    TestRootApi.test_version_configurations
----------------------------------------------------------------------
Ran 1109 tests in 596.970s

FAILED (SKIP=42, failures=2)
ERROR: InvocationError: '<https://jenkins01.openstack.org/job/periodic-glance-python27-stable-grizzly/ws/.tox/py27/bin/nosetests'>
___________________________________ summary ____________________________________
ERROR:   py27: commands failed
+ result=1
+ echo 'Begin pip freeze output from test virtualenv:'
Begin pip freeze output from test virtualenv:
+ echo ======================================================================
======================================================================
+ .tox/py27/bin/pip freeze
Babel==1.3
Jinja2==2.7.1
MarkupSafe==0.18
MySQL-python==1.2.4
Paste==1.7.5.1
PasteDeploy==1.5.0
Pygments==1.6
Routes==1.13
SQLAlchemy==0.7.9
Sphinx==1.2b1
Tempita==0.5.1
WebOb==1.2.3
amqp==1.3.2
anyjson==0.3.3
argparse==1.2.1
boto==2.15.0
cffi==0.7.2
colorama==0.2.7
coverage==3.7
decorator==3.4.0
distribute==0.7.3
docutils==0.10
eventlet==0.14.0
extras==0.0.3
fixtures==0.3.14
glance==jenkins.periodic.glance.python27.stable.grizzly.52
greenlet==0.4.1
httplib2==0.8
iso8601==0.1.8
jsonschema==2.2.0
kombu==3.0.2
lxml==3.2.3
mox==0.5.3
netaddr==0.7.10
nose==1.3.0
nose-exclude==0.1.10
nosehtmloutput==0.0.5
openstack.nose-plugin==0.11
oslo.config==1.2.1
passlib==1.6.1
pbr==0.5.23
pep8==1.3.3
prettytable==0.7.2
psycopg2==2.5.1
pycparser==2.10
pycrypto==2.6.1
pysendfile==2.0.0
python-keystoneclient==0.4.1
python-mimeparse==0.1.4
python-swiftclient==1.8.0
pytz==2013.7
repoze.lru==0.6
requests==2.0.1
simplejson==3.3.1
six==1.4.1
sqlalchemy-migrate==0.7.2
termcolor==1.1.0
testtools==0.9.32
wsgiref==0.1.2
xattr==0.7.2
+ echo ======================================================================
======================================================================
+ '[' -d .testrepository ']'
+ sudo /usr/local/jenkins/slave_scripts/jenkins-sudo-grep.sh post
+ 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=./nose_results.html
+ '[' -f ./nose_results.html ']'
++ grep -c 'tr class=.passClass' ./nose_results.html
+ passcount=125
+ '[' 125 -eq 0 ']'
+ exit 1
+ rm -rf /tmp/tmp.ctD27h81yK
Build step 'Execute shell' marked build as failure
[SCP] Connecting to static.openstack.org
[SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python27-stable-grizzly/52/nose_results.html'
[SCP] Connecting to static.openstack.org
[SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python27-stable-grizzly/precise27/52
[SCP] Copying console log.