See <https://jenkins02.openstack.org/job/periodic-glance-python27-stable-grizzly/31/> ------------------------------------------ [...truncated 1464 lines...] routes.middleware: DEBUG: Route path: '/images/detail', defaults: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x51fb690>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x51fb690>} 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 0x49835d0>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x49835d0>} 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 0x198c2d0> 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-0bc4cf0a-dd1b-4d63-87a8-8a5c2e95510e --------------------- >> 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://jenkins02.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://jenkins02.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://jenkins02.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 0x2dea7d0> 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 0x2dea7d0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <function Store at 0x2dea7d0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <function Store at 0x2dea7d0>, '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 0x5f69890>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f69890>} 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 0x5f69890>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f69890>} iso8601.iso8601: DEBUG: Parsed 2013-10-23T06:14:23Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'23', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'23', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x198c2d0> 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'23' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'14' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'23' 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 0x5f69890>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f69890>} iso8601.iso8601: DEBUG: Parsed 2013-10-25T06:14:23Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'23', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'25', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x198c2d0> 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'25' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'14' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'23' 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 0x5f69890>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f69890>} iso8601.iso8601: DEBUG: Parsed 2013-10-26T06:14:23+01:00 into {'tz_sign': u'+', 'second_fraction': None, 'hour': u'06', 'tz_hour': u'01', 'month': u'10', 'timezone': u'+01:00', 'second': u'23', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'26', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x198c2d0> 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'10' for 'month' with default None iso8601.iso8601: DEBUG: Got u'26' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'14' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'23' 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 0x5f69890>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f69890>} iso8601.iso8601: DEBUG: Parsed 2013-10-26T06:14:23-01:00 into {'tz_sign': u'-', 'second_fraction': None, 'hour': u'06', 'tz_hour': u'01', 'month': u'10', 'timezone': u'-01:00', 'second': u'23', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'26', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x198c2d0> 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'10' for 'month' with default None iso8601.iso8601: DEBUG: Got u'26' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'14' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'23' 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 0x5f69890>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f69890>} iso8601.iso8601: DEBUG: Parsed 2013-10-27T06:14:23Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'23', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'27', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x198c2d0> 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'27' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'14' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'23' 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 0x5f69890>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f69890>} 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 0x5f69890>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f69890>} 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 0x198c2d0> 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 95.99 secs:  27.04 TestScrubber.test_scrubber_app  21.57 TestScrubber.test_delayed_delete  16.34 TestRootApi.test_version_configurations  15.83 TestBinGlanceCacheManage.test_queue  15.20 TestGlanceControl.test_reload ---------------------------------------------------------------------- Ran 1109 tests in 708.815s FAILED (SKIP=42, failures=2) ERROR: InvocationError: '<https://jenkins02.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.0 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.31 greenlet==0.4.1 httplib2==0.8 iso8601==0.1.8 jsonschema==2.2.0 kombu==3.0.0 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.21 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.0 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.Q1tI3VvTgB 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/31/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python27-stable-grizzly/precise20 [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python27-stable-grizzly/precise20/31 [SCP] Copying console log.

See <https://jenkins02.openstack.org/job/periodic-glance-python27-stable-grizzly/33/> ------------------------------------------ [...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 0x54396d0>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x54396d0>} 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 0x50860d0>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x50860d0>} 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 0x1ac52d0> 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-abae0b86-60d0-4a85-8c8e-6518eeec368b --------------------- >> 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://jenkins02.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://jenkins02.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://jenkins02.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 0x2f237d0> 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 0x2f237d0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <function Store at 0x2f237d0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <function Store at 0x2f237d0>, '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 0x60a1950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x60a1950>} 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 0x60a1950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x60a1950>} iso8601.iso8601: DEBUG: Parsed 2013-10-27T06:12:05Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'05', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'27', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x1ac52d0> 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'27' 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'05' 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 0x60a1950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x60a1950>} iso8601.iso8601: DEBUG: Parsed 2013-10-29T06:12:05Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'05', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'29', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x1ac52d0> 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'29' 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'05' 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 0x60a1950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x60a1950>} iso8601.iso8601: DEBUG: Parsed 2013-10-30T06:12:05+01:00 into {'tz_sign': u'+', 'second_fraction': None, 'hour': u'06', 'tz_hour': u'01', 'month': u'10', 'timezone': u'+01:00', 'second': u'05', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'30', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x1ac52d0> 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'10' for 'month' with default None iso8601.iso8601: DEBUG: Got u'30' 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'05' 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 0x60a1950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x60a1950>} iso8601.iso8601: DEBUG: Parsed 2013-10-30T06:12:05-01:00 into {'tz_sign': u'-', 'second_fraction': None, 'hour': u'06', 'tz_hour': u'01', 'month': u'10', 'timezone': u'-01:00', 'second': u'05', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'30', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x1ac52d0> 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'10' for 'month' with default None iso8601.iso8601: DEBUG: Got u'30' 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'05' 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 0x60a1950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x60a1950>} iso8601.iso8601: DEBUG: Parsed 2013-10-31T06:12:05Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'05', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'31', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x1ac52d0> 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'05' 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 0x60a1950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x60a1950>} 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 0x60a1950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x60a1950>} 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 0x1ac52d0> 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.04 secs:  25.53 TestScrubber.test_scrubber_app  20.16 TestScrubber.test_delayed_delete  14.44 TestGlanceControl.test_reload  14.08 TestGlanceControl.test_respawn  13.83 TestGlanceControl.test_bouncing ---------------------------------------------------------------------- Ran 1109 tests in 574.923s FAILED (SKIP=42, failures=2) ERROR: InvocationError: '<https://jenkins02.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.1 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.33 greenlet==0.4.1 httplib2==0.8 iso8601==0.1.8 jsonschema==2.2.0 kombu==3.0.1 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.22 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.V7fMqHZSKr 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/33/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python27-stable-grizzly/precise12/33 [SCP] Copying console log.

See <https://jenkins02.openstack.org/job/periodic-glance-python27-stable-grizzly/35/> ------------------------------------------ [...truncated 1464 lines...] routes.middleware: DEBUG: Route path: '/images/detail', defaults: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5c95550>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5c95550>} 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 0x5665090>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5665090>} 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 0x24932d0> 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-0f004fd3-75bd-4ced-aa16-823781dc74ee --------------------- >> 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://jenkins02.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://jenkins02.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://jenkins02.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 0x38f07d0> 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 0x38f07d0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <function Store at 0x38f07d0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <function Store at 0x38f07d0>, '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 0x69f3550>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x69f3550>} 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 0x69f3550>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x69f3550>} iso8601.iso8601: DEBUG: Parsed 2013-10-30T06:10:03Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'03', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'30', 'minute': u'10'} with default timezone <iso8601.iso8601.Utc object at 0x24932d0> 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'30' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'10' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'03' 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 0x69f3550>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x69f3550>} iso8601.iso8601: DEBUG: Parsed 2013-11-01T06:10:03Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'03', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'01', 'minute': u'10'} with default timezone <iso8601.iso8601.Utc object at 0x24932d0> 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'01' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'10' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'03' 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 0x69f3550>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x69f3550>} iso8601.iso8601: DEBUG: Parsed 2013-11-02T06:10:03+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'03', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'02', 'minute': u'10'} with default timezone <iso8601.iso8601.Utc object at 0x24932d0> 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'02' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'10' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'03' 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 0x69f3550>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x69f3550>} iso8601.iso8601: DEBUG: Parsed 2013-11-02T06:10:03-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'03', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'02', 'minute': u'10'} with default timezone <iso8601.iso8601.Utc object at 0x24932d0> 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'02' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'10' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'03' 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 0x69f3550>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x69f3550>} iso8601.iso8601: DEBUG: Parsed 2013-11-03T06:10:03Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'03', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'03', 'minute': u'10'} with default timezone <iso8601.iso8601.Utc object at 0x24932d0> 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'10' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'03' 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 0x69f3550>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x69f3550>} 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 0x69f3550>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x69f3550>} 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 0x24932d0> 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 84.13 secs:  24.57 TestScrubber.test_scrubber_app  19.14 TestScrubber.test_delayed_delete  13.81 TestGlanceControl.test_reload  13.61 TestGlanceControl.test_respawn  13.00 TestGlanceControl.test_bouncing ---------------------------------------------------------------------- Ran 1109 tests in 491.749s FAILED (SKIP=42, failures=2) ERROR: InvocationError: '<https://jenkins02.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.35 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.vmOUFTk4Zk 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/35/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python27-stable-grizzly/precise40 [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python27-stable-grizzly/precise40/35 [SCP] Copying console log.

See <https://jenkins02.openstack.org/job/periodic-glance-python27-stable-grizzly/37/> ------------------------------------------ [...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 0x60ae850>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x60ae850>} 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 0x666ac90>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x666ac90>} 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 0x31312d0> 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-cb9a37d8-7383-4c9d-85aa-663627c2e0cd --------------------- >> 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://jenkins02.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://jenkins02.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://jenkins02.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 0x458e7d0> 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 0x458e7d0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <function Store at 0x458e7d0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <function Store at 0x458e7d0>, '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 0x769b590>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x769b590>} 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 0x769b590>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x769b590>} iso8601.iso8601: DEBUG: Parsed 2013-11-03T06:11:46Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'46', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'03', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x31312d0> 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'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'46' 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 0x769b590>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x769b590>} iso8601.iso8601: DEBUG: Parsed 2013-11-05T06:11:46Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'46', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'05', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x31312d0> 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'05' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'46' 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 0x769b590>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x769b590>} iso8601.iso8601: DEBUG: Parsed 2013-11-06T06:11:46+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'46', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'06', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x31312d0> 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'06' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'46' 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 0x769b590>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x769b590>} iso8601.iso8601: DEBUG: Parsed 2013-11-06T06:11:46-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'46', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'06', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x31312d0> 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'06' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'46' 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 0x769b590>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x769b590>} iso8601.iso8601: DEBUG: Parsed 2013-11-07T06:11:46Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'46', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'07', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x31312d0> 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'07' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'46' 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 0x769b590>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x769b590>} 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 0x769b590>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x769b590>} 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 0x31312d0> 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 87.77 secs:  25.67 TestScrubber.test_scrubber_app  20.11 TestScrubber.test_delayed_delete  14.44 TestGlanceControl.test_reload  14.19 TestGlanceControl.test_respawn  13.36 TestGlanceControl.test_bouncing ---------------------------------------------------------------------- Ran 1109 tests in 579.206s FAILED (SKIP=42, failures=2) ERROR: InvocationError: '<https://jenkins02.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.37 greenlet==0.4.1 httplib2==0.8 iso8601==0.1.8 jsonschema==2.3.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.uMoMEeLHgI 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/37/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python27-stable-grizzly/precise2/37 [SCP] Copying console log.

See <https://jenkins02.openstack.org/job/periodic-glance-python27-stable-grizzly/39/> ------------------------------------------ [...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 0x6b356d0>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6b356d0>} 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 0x6641a50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6641a50>} 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 0x2f962d0> 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-2c92ddc5-1e94-4f3c-bdba-606222175b12 --------------------- >> 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://jenkins02.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://jenkins02.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://jenkins02.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 0x43f57d0> 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 0x43f57d0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <function Store at 0x43f57d0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <function Store at 0x43f57d0>, '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 0x74ead50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x74ead50>} 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 0x74ead50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x74ead50>} iso8601.iso8601: DEBUG: Parsed 2013-11-05T06:11:45Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'45', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'05', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x2f962d0> 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'05' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'45' 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 0x74ead50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x74ead50>} iso8601.iso8601: DEBUG: Parsed 2013-11-07T06:11:45Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'45', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'07', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x2f962d0> 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'07' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'45' 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 0x74ead50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x74ead50>} iso8601.iso8601: DEBUG: Parsed 2013-11-08T06:11:45+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'45', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'08', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x2f962d0> 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'08' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'45' 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 0x74ead50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x74ead50>} iso8601.iso8601: DEBUG: Parsed 2013-11-08T06:11:45-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'45', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'08', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x2f962d0> 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'08' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'45' 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 0x74ead50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x74ead50>} iso8601.iso8601: DEBUG: Parsed 2013-11-09T06:11:45Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'45', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'09', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x2f962d0> 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'09' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'45' 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 0x74ead50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x74ead50>} 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 0x74ead50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x74ead50>} 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 0x2f962d0> 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.65 TestScrubber.test_scrubber_app  20.16 TestScrubber.test_delayed_delete  14.46 TestGlanceControl.test_reload  14.22 TestGlanceControl.test_respawn  13.89 TestGlanceControl.test_bouncing ---------------------------------------------------------------------- Ran 1109 tests in 580.649s FAILED (SKIP=42, failures=2) ERROR: InvocationError: '<https://jenkins02.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.39 greenlet==0.4.1 httplib2==0.8 iso8601==0.1.8 jsonschema==2.3.0 kombu==3.0.3 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.MLpHVgWK3x 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/39/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python27-stable-grizzly/precise26/39 [SCP] Copying console log.

See <https://jenkins02.openstack.org/job/periodic-glance-python27-stable-grizzly/41/> ------------------------------------------ [...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 0x4993750>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4993750>} 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 0x4e4f710>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4e4f710>} 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 0x19082d0> 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-96764775-6eec-4972-a49b-97fbec12db10 --------------------- >> 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://jenkins02.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://jenkins02.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://jenkins02.openstack.org/job/periodic-glance-python27-stable-grizzly/ws/.tox/py27/local/lib/python2.7/site-packages/testtools/testcase.py",> line 412, 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 0x2d658c0> 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 0x2d658c0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <function Store at 0x2d658c0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <function Store at 0x2d658c0>, '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 0x5f6f150>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f6f150>} 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 0x5f6f150>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f6f150>} iso8601.iso8601: DEBUG: Parsed 2013-11-07T06:13:28Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'28', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'07', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x19082d0> 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'07' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'13' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'28' 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 0x5f6f150>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f6f150>} iso8601.iso8601: DEBUG: Parsed 2013-11-09T06:13:28Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'28', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'09', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x19082d0> 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'09' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'13' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'28' 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 0x5f6f150>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f6f150>} iso8601.iso8601: DEBUG: Parsed 2013-11-10T06:13:28+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'28', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'10', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x19082d0> 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'10' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'13' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'28' 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 0x5f6f150>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f6f150>} iso8601.iso8601: DEBUG: Parsed 2013-11-10T06:13:28-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'28', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'10', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x19082d0> 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'10' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'13' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'28' 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 0x5f6f150>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f6f150>} iso8601.iso8601: DEBUG: Parsed 2013-11-11T06:13:28Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'28', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'11', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x19082d0> 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'11' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'13' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'28' 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 0x5f6f150>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f6f150>} 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 0x5f6f150>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5f6f150>} 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 0x19082d0> 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 99.06 secs:  28.06 TestScrubber.test_scrubber_app  21.94 TestScrubber.test_delayed_delete  16.96 TestBinGlanceCacheManage.test_queue  16.73 TestRootApi.test_version_configurations  15.36 TestGlanceControl.test_reload ---------------------------------------------------------------------- Ran 1109 tests in 736.432s FAILED (SKIP=42, failures=2) ERROR: InvocationError: '<https://jenkins02.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.41 greenlet==0.4.1 httplib2==0.8 iso8601==0.1.8 jsonschema==2.3.0 kombu==3.0.3 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.8 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.33 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.zifbtYMZqP 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/41/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python27-stable-grizzly/precise28/41 [SCP] Copying console log.

See <https://jenkins02.openstack.org/job/periodic-glance-python27-stable-grizzly/43/> ------------------------------------------ [...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 0x591a810>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x591a810>} 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 0x5440290>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5440290>} 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 0x21422d0> 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-41daf398-8e08-4461-8641-43a1662f2004 --------------------- >> 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://jenkins02.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://jenkins02.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://jenkins02.openstack.org/job/periodic-glance-python27-stable-grizzly/ws/.tox/py27/local/lib/python2.7/site-packages/testtools/testcase.py",> line 412, 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 0x359e8c0> 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 0x359e8c0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <function Store at 0x359e8c0>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <function Store at 0x359e8c0>, '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 0x655a110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x655a110>} 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 0x655a110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x655a110>} iso8601.iso8601: DEBUG: Parsed 2013-11-09T06:14:32Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'32', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'09', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x21422d0> 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'09' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'14' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'32' 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 0x655a110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x655a110>} iso8601.iso8601: DEBUG: Parsed 2013-11-11T06:14:32Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'32', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'11', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x21422d0> 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'11' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'14' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'32' 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 0x655a110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x655a110>} iso8601.iso8601: DEBUG: Parsed 2013-11-12T06:14:32+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'32', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'12', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x21422d0> 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'12' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'14' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'32' 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 0x655a110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x655a110>} iso8601.iso8601: DEBUG: Parsed 2013-11-12T06:14:32-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'32', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'12', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x21422d0> 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'12' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'14' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'32' 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 0x655a110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x655a110>} iso8601.iso8601: DEBUG: Parsed 2013-11-13T06:14:32Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'32', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'13', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x21422d0> 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'13' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'14' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'32' 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 0x655a110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x655a110>} 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 0x655a110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x655a110>} 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 0x21422d0> 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 96.35 secs:  26.98 TestScrubber.test_scrubber_app  21.63 TestScrubber.test_delayed_delete  16.46 TestRootApi.test_version_configurations  15.99 TestBinGlanceCacheManage.test_queue  15.29 TestGlanceControl.test_reload ---------------------------------------------------------------------- Ran 1109 tests in 722.342s FAILED (SKIP=42, failures=2) ERROR: InvocationError: '<https://jenkins02.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.16.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.43 greenlet==0.4.1 httplib2==0.8 iso8601==0.1.8 jsonschema==2.3.0 kombu==3.0.4 lxml==3.2.4 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.8 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.33 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.QcMQBllh8Z 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/43/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python27-stable-grizzly/precise30/43 [SCP] Copying console log.

See <https://jenkins02.openstack.org/job/periodic-glance-python27-stable-grizzly/45/changes>