Build failed in Jenkins: periodic-glance-python26-stable-folsom #31
See <https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/31/changes> Changes: [zhiyanl] Adding 'download_image' policy enforcement to image cache middleware ------------------------------------------ [...truncated 954 lines...] test_add_already_existing [32mOK [0m[32m 0.01[0m test_add_host_variations [32mOK [0m[33m 0.03[0m test_calling_format_default [32mOK [0m[32m 0.01[0m test_calling_format_path [32mOK [0m[32m 0.01[0m test_calling_format_subdomain [32mOK [0m[32m 0.01[0m test_delete [32mOK [0m[32m 0.02[0m test_delete_non_existing [32mOK [0m[32m 0.01[0m test_get [32mOK [0m[32m 0.01[0m test_get_calling_format_default [32mOK [0m[32m 0.01[0m test_get_calling_format_path [32mOK [0m[32m 0.01[0m test_get_non_existing [32mOK [0m[32m 0.01[0m test_get_s3_bad_location [32mOK [0m[32m 0.01[0m test_get_s3_good_location [32mOK [0m[32m 0.01[0m test_no_access_key [32mOK [0m[32m 0.01[0m test_no_host [32mOK [0m[32m 0.01[0m test_no_secret_key [32mOK [0m[32m 0.01[0m TestBasicSchema test_filter_strips_extra_properties [32mOK [0m[32m 0.01[0m test_merge_conflicting_but_identical_properties [32mOK [0m[32m 0.01[0m test_merge_conflicting_properties [32mOK [0m[32m 0.01[0m test_merge_properties [32mOK [0m[32m 0.01[0m test_raw_json_schema [32mOK [0m[32m 0.01[0m test_validate_fails_on_bad_type [32mOK [0m[32m 0.01[0m test_validate_fails_on_extra_properties [32mOK [0m[32m 0.01[0m test_validate_passes [32mOK [0m[32m 0.01[0m TestBasicSchemaLinks test_raw_json_schema [32mOK [0m[32m 0.01[0m TestCollectionSchema test_raw_json_schema [32mOK [0m[32m 0.01[0m TestPermissiveSchema test_filter_passes_extra_properties [32mOK [0m[32m 0.01[0m test_raw_json_schema [32mOK [0m[32m 0.01[0m test_validate_rejects_non_string_extra_properties [32mOK [0m[32m 0.01[0m test_validate_with_additional_properties_allowed [32mOK [0m[32m 0.01[0m TestSkipExamples test_001_increase_test_counter [32mOK [0m[32m 0.01[0m test_002_increase_test_counter [32mOK [0m[32m 0.01[0m test_003_verify_test_counter [32mOK [0m[32m 0.01[0m test_skip_if_example [34mSKIP[0m test_skip_test_example [34mSKIP[0m test_skip_unless_example [34mSKIP[0m TestStoreLocation test_bad_store_scheme [32mOK [0m[32m 0.01[0m test_filesystem_store_location [32mOK [0m[32m 0.02[0m test_get_location_from_uri_back_to_uri [32mOK [0m[32m 0.01[0m test_get_store_from_scheme [32mOK [0m[32m 0.02[0m test_http_store_location [32mOK [0m[32m 0.01[0m test_rbd_store_location [32mOK [0m[32m 0.02[0m test_s3_store_location [32mOK [0m[32m 0.01[0m test_swift_store_location [32mOK [0m[32m 0.01[0m TestChunkReader test_read_all_data [32mOK [0m[32m 0.01[0m TestStoreAuthV1 test_add [32mOK [0m[32m 0.01[0m test_add_already_existing [32mOK [0m[32m 0.01[0m test_add_auth_url_variations [32mOK [0m[32m 0.02[0m test_add_large_object [32mOK [0m[32m 0.01[0m test_add_large_object_zero_size [32mOK [0m[32m 0.01[0m test_add_no_container_and_create [32mOK [0m[32m 0.01[0m test_add_no_container_no_create [32mOK [0m[32m 0.01[0m test_delete [32mOK [0m[32m 0.01[0m test_delete_non_existing [32mOK [0m[32m 0.01[0m test_get [32mOK [0m[32m 0.01[0m test_get_non_existing [32mOK [0m[32m 0.01[0m test_get_size [32mOK [0m[32m 0.01[0m test_get_with_http_auth [32mOK [0m[32m 0.01[0m test_no_auth_address [32mOK [0m[32m 0.01[0m test_no_key [32mOK [0m[32m 0.01[0m test_no_user [32mOK [0m[32m 0.01[0m test_read_acl_public [32mOK [0m[32m 0.01[0m test_read_acl_tenants [32mOK [0m[32m 0.01[0m test_read_write_public [32mOK [0m[32m 0.01[0m TestStoreAuthV2 test_add [32mOK [0m[32m 0.01[0m test_add_already_existing [32mOK [0m[32m 0.01[0m test_add_auth_url_variations [32mOK [0m[32m 0.02[0m test_add_large_object [32mOK [0m[32m 0.01[0m test_add_large_object_zero_size [32mOK [0m[32m 0.01[0m test_add_no_container_and_create [32mOK [0m[32m 0.01[0m test_add_no_container_no_create [32mOK [0m[32m 0.01[0m test_delete [32mOK [0m[31m 0.20[0m test_delete_non_existing [32mOK [0m[32m 0.01[0m test_get [32mOK [0m[32m 0.01[0m test_get_non_existing [32mOK [0m[32m 0.01[0m test_get_size [32mOK [0m[32m 0.01[0m test_get_with_http_auth [32mOK [0m[32m 0.01[0m test_no_auth_address [32mOK [0m[32m 0.01[0m test_no_key [32mOK [0m[32m 0.01[0m test_no_user [32mOK [0m[32m 0.01[0m test_read_acl_public [32mOK [0m[32m 0.01[0m test_read_acl_tenants [32mOK [0m[32m 0.01[0m test_read_write_public [32mOK [0m[32m 0.01[0m test_v2_multi_tenant_location [32mOK [0m[32m 0.01[0m test_v2_with_no_tenant [32mOK [0m[32m 0.01[0m TestUtils test_cooperative_reader [32mOK [0m[33m 0.05[0m test_cooperative_reader_of_iterator [32mOK [0m[32m 0.01[0m test_generate_uuid_format [32mOK [0m[32m 0.01[0m test_generate_uuid_unique [32mOK [0m[32m 0.01[0m test_is_uuid_like_fails [32mOK [0m[32m 0.01[0m test_is_uuid_like_success [32mOK [0m[32m 0.01[0m test_limiting_reader [32mOK [0m[32m 0.01[0m test_limiting_reader_fails [32mOK [0m[32m 0.01[0m VersionNegotiationTest test_request_accept_v1 [32mOK [0m[32m 0.01[0m test_request_url_v1 [32mOK [0m[32m 0.02[0m test_request_url_v1_0 [32mOK [0m[32m 0.01[0m test_request_url_v1_1 [32mOK [0m[32m 0.01[0m test_request_url_v2 [32mOK [0m[32m 0.01[0m test_request_url_v2_0 [32mOK [0m[32m 0.01[0m test_request_url_v2_1_unsupported [32mOK [0m[32m 0.01[0m test_request_url_v3_unsupported [32mOK [0m[32m 0.01[0m VersionsTest test_get_version_list [32mOK [0m[32m 0.01[0m JSONRequestDeserializerTest test_default_no_body [32mOK [0m[32m 0.01[0m test_default_with_body [32mOK [0m[32m 0.01[0m test_from_json [32mOK [0m[32m 0.01[0m test_from_json_malformed [32mOK [0m[32m 0.01[0m test_has_body_has_content_length [32mOK [0m[32m 0.01[0m test_has_body_no_content_length [32mOK [0m[32m 0.01[0m test_has_body_zero_content_length [32mOK [0m[32m 0.01[0m test_no_body_no_content_length [32mOK [0m[32m 0.01[0m JSONResponseSerializerTest test_default [32mOK [0m[32m 0.01[0m test_to_json [32mOK [0m[32m 0.01[0m RequestTest test_content_type_accept_default [32mOK [0m[32m 0.01[0m test_content_type_from_accept_json [32mOK [0m[32m 0.01[0m test_content_type_from_accept_json_xml_quality [32mOK [0m[32m 0.01[0m test_content_type_from_accept_xml [32mOK [0m[32m 0.01[0m test_content_type_from_accept_xml_json [32mOK [0m[32m 0.01[0m test_content_type_missing [32mOK [0m[32m 0.01[0m test_content_type_unsupported [32mOK [0m[32m 0.01[0m test_content_type_with_charset [32mOK [0m[32m 0.01[0m ResourceTest test_dispatch [32mOK [0m[32m 0.01[0m test_dispatch_default [32mOK [0m[32m 0.01[0m test_dispatch_no_default [32mOK [0m[32m 0.01[0m test_get_action_args [32mOK [0m[32m 0.01[0m TestHelpers test_data_passed_properly_through_headers [32mOK [0m[32m 0.01[0m test_headers_are_unicode [32mOK [0m[32m 0.01[0m ====================================================================== FAIL: Ensure an interrupt signal does not cause a respawn storm. ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/tests/functional/v1/test_multiprocessing.py",> line 71, in test_interrupt_avoids_respawn_storm self.assertTrue(child in children) AssertionError: """Fail the test unless the expression is true."""
if not False: raise self.failureException, None
---------------------------------------------------------------------- XML: nosetests.xml Slowest 5 tests took 71.47 secs: [31m 15.57 TestGlanceControl.test_bouncing[0m [31m 14.23 TestGlanceControl.test_reload[0m [31m 14.01 TestGlanceControl.test_respawn[0m [31m 13.87 TestBinGlanceCacheManage.test_queue[0m [31m 13.80 TestRootApi.test_version_configurations[0m ---------------------------------------------------------------------- Ran 932 tests in 602.486s FAILED (SKIP=89, failures=1) ERROR: InvocationError: '<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/.tox/py26/bin/nosetests'> ___________________________________ summary ____________________________________ ERROR: py26: commands failed + result=1 + echo 'Begin pip freeze output from test virtualenv:' Begin pip freeze output from test virtualenv: + echo ====================================================================== ====================================================================== + .tox/py26/bin/pip freeze Babel==1.3 Jinja2==2.7.1 MarkupSafe==0.18 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.0.8 amqp==1.0.13 anyjson==0.3.3 argparse==1.2.1 boto==2.1.1 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 glance==jenkins-periodic-glance-python26-stable-folsom-31 greenlet==0.4.1 httplib2==0.8 importlib==1.0.2 iso8601==0.1.4 jsonschema==2.1.0 kombu==2.5.15 lxml==3.2.3 mox==0.5.3 nose==1.3.0 nose-exclude==0.1.10 nosehtmloutput==0.0.5 openstack.nose-plugin==0.11 ordereddict==1.1 passlib==1.6.1 pep8==1.1 pycparser==2.10 pycrypto==2.6 pysendfile==2.0.0 python-swiftclient==1.8.0 pytz==2013.7 repoze.lru==0.6 requests==2.0.0 simplejson==3.3.1 sqlalchemy-migrate==0.7.2 termcolor==1.1.0 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=96 + '[' 96 -eq 0 ']' + exit 1 + rm -rf /tmp/tmp.zNu4dtb2PY Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python26-stable-folsom/31/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python26-stable-folsom/centos6-10/31 [SCP] Copying console log.
See <https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/32/> ------------------------------------------ [...truncated 954 lines...] test_add_already_existing [32mOK [0m[32m 0.01[0m test_add_host_variations [32mOK [0m[33m 0.03[0m test_calling_format_default [32mOK [0m[32m 0.01[0m test_calling_format_path [32mOK [0m[32m 0.01[0m test_calling_format_subdomain [32mOK [0m[32m 0.01[0m test_delete [32mOK [0m[32m 0.01[0m test_delete_non_existing [32mOK [0m[32m 0.02[0m test_get [32mOK [0m[32m 0.01[0m test_get_calling_format_default [32mOK [0m[32m 0.01[0m test_get_calling_format_path [32mOK [0m[32m 0.01[0m test_get_non_existing [32mOK [0m[32m 0.01[0m test_get_s3_bad_location [32mOK [0m[32m 0.01[0m test_get_s3_good_location [32mOK [0m[32m 0.01[0m test_no_access_key [32mOK [0m[32m 0.01[0m test_no_host [32mOK [0m[32m 0.01[0m test_no_secret_key [32mOK [0m[32m 0.01[0m TestBasicSchema test_filter_strips_extra_properties [32mOK [0m[32m 0.01[0m test_merge_conflicting_but_identical_properties [32mOK [0m[32m 0.01[0m test_merge_conflicting_properties [32mOK [0m[32m 0.01[0m test_merge_properties [32mOK [0m[32m 0.01[0m test_raw_json_schema [32mOK [0m[32m 0.01[0m test_validate_fails_on_bad_type [32mOK [0m[32m 0.01[0m test_validate_fails_on_extra_properties [32mOK [0m[32m 0.01[0m test_validate_passes [32mOK [0m[32m 0.01[0m TestBasicSchemaLinks test_raw_json_schema [32mOK [0m[32m 0.01[0m TestCollectionSchema test_raw_json_schema [32mOK [0m[32m 0.01[0m TestPermissiveSchema test_filter_passes_extra_properties [32mOK [0m[32m 0.01[0m test_raw_json_schema [32mOK [0m[32m 0.01[0m test_validate_rejects_non_string_extra_properties [32mOK [0m[32m 0.01[0m test_validate_with_additional_properties_allowed [32mOK [0m[32m 0.01[0m TestSkipExamples test_001_increase_test_counter [32mOK [0m[32m 0.01[0m test_002_increase_test_counter [32mOK [0m[32m 0.01[0m test_003_verify_test_counter [32mOK [0m[32m 0.01[0m test_skip_if_example [34mSKIP[0m test_skip_test_example [34mSKIP[0m test_skip_unless_example [34mSKIP[0m TestStoreLocation test_bad_store_scheme [32mOK [0m[32m 0.01[0m test_filesystem_store_location [32mOK [0m[32m 0.01[0m test_get_location_from_uri_back_to_uri [32mOK [0m[32m 0.02[0m test_get_store_from_scheme [32mOK [0m[32m 0.02[0m test_http_store_location [32mOK [0m[32m 0.01[0m test_rbd_store_location [32mOK [0m[32m 0.02[0m test_s3_store_location [32mOK [0m[32m 0.01[0m test_swift_store_location [32mOK [0m[32m 0.01[0m TestChunkReader test_read_all_data [32mOK [0m[32m 0.02[0m TestStoreAuthV1 test_add [32mOK [0m[33m 0.03[0m test_add_already_existing [32mOK [0m[32m 0.01[0m test_add_auth_url_variations [32mOK [0m[32m 0.02[0m test_add_large_object [32mOK [0m[32m 0.01[0m test_add_large_object_zero_size [32mOK [0m[32m 0.01[0m test_add_no_container_and_create [32mOK [0m[32m 0.01[0m test_add_no_container_no_create [32mOK [0m[32m 0.01[0m test_delete [32mOK [0m[32m 0.01[0m test_delete_non_existing [32mOK [0m[32m 0.01[0m test_get [32mOK [0m[32m 0.01[0m test_get_non_existing [32mOK [0m[32m 0.01[0m test_get_size [32mOK [0m[31m 0.20[0m test_get_with_http_auth [32mOK [0m[32m 0.01[0m test_no_auth_address [32mOK [0m[32m 0.01[0m test_no_key [32mOK [0m[32m 0.01[0m test_no_user [32mOK [0m[32m 0.01[0m test_read_acl_public [32mOK [0m[32m 0.01[0m test_read_acl_tenants [32mOK [0m[32m 0.01[0m test_read_write_public [32mOK [0m[32m 0.01[0m TestStoreAuthV2 test_add [32mOK [0m[32m 0.01[0m test_add_already_existing [32mOK [0m[32m 0.01[0m test_add_auth_url_variations [32mOK [0m[32m 0.02[0m test_add_large_object [32mOK [0m[32m 0.01[0m test_add_large_object_zero_size [32mOK [0m[32m 0.01[0m test_add_no_container_and_create [32mOK [0m[32m 0.01[0m test_add_no_container_no_create [32mOK [0m[32m 0.01[0m test_delete [32mOK [0m[32m 0.01[0m test_delete_non_existing [32mOK [0m[32m 0.02[0m test_get [32mOK [0m[32m 0.01[0m test_get_non_existing [32mOK [0m[32m 0.01[0m test_get_size [32mOK [0m[32m 0.01[0m test_get_with_http_auth [32mOK [0m[32m 0.01[0m test_no_auth_address [32mOK [0m[32m 0.01[0m test_no_key [32mOK [0m[32m 0.01[0m test_no_user [32mOK [0m[32m 0.01[0m test_read_acl_public [32mOK [0m[32m 0.01[0m test_read_acl_tenants [32mOK [0m[32m 0.01[0m test_read_write_public [32mOK [0m[32m 0.01[0m test_v2_multi_tenant_location [32mOK [0m[32m 0.01[0m test_v2_with_no_tenant [32mOK [0m[32m 0.01[0m TestUtils test_cooperative_reader [32mOK [0m[33m 0.05[0m test_cooperative_reader_of_iterator [32mOK [0m[32m 0.01[0m test_generate_uuid_format [32mOK [0m[32m 0.01[0m test_generate_uuid_unique [32mOK [0m[32m 0.01[0m test_is_uuid_like_fails [32mOK [0m[32m 0.01[0m test_is_uuid_like_success [32mOK [0m[32m 0.01[0m test_limiting_reader [32mOK [0m[32m 0.01[0m test_limiting_reader_fails [32mOK [0m[32m 0.01[0m VersionNegotiationTest test_request_accept_v1 [32mOK [0m[32m 0.01[0m test_request_url_v1 [32mOK [0m[32m 0.01[0m test_request_url_v1_0 [32mOK [0m[32m 0.01[0m test_request_url_v1_1 [32mOK [0m[32m 0.01[0m test_request_url_v2 [32mOK [0m[32m 0.01[0m test_request_url_v2_0 [32mOK [0m[32m 0.01[0m test_request_url_v2_1_unsupported [32mOK [0m[32m 0.01[0m test_request_url_v3_unsupported [32mOK [0m[32m 0.01[0m VersionsTest test_get_version_list [32mOK [0m[32m 0.01[0m JSONRequestDeserializerTest test_default_no_body [32mOK [0m[32m 0.01[0m test_default_with_body [32mOK [0m[32m 0.01[0m test_from_json [32mOK [0m[32m 0.01[0m test_from_json_malformed [32mOK [0m[32m 0.01[0m test_has_body_has_content_length [32mOK [0m[32m 0.01[0m test_has_body_no_content_length [32mOK [0m[32m 0.01[0m test_has_body_zero_content_length [32mOK [0m[32m 0.01[0m test_no_body_no_content_length [32mOK [0m[32m 0.01[0m JSONResponseSerializerTest test_default [32mOK [0m[32m 0.01[0m test_to_json [32mOK [0m[32m 0.01[0m RequestTest test_content_type_accept_default [32mOK [0m[32m 0.01[0m test_content_type_from_accept_json [32mOK [0m[32m 0.01[0m test_content_type_from_accept_json_xml_quality [32mOK [0m[32m 0.01[0m test_content_type_from_accept_xml [32mOK [0m[32m 0.01[0m test_content_type_from_accept_xml_json [32mOK [0m[32m 0.01[0m test_content_type_missing [32mOK [0m[32m 0.01[0m test_content_type_unsupported [32mOK [0m[32m 0.01[0m test_content_type_with_charset [32mOK [0m[32m 0.01[0m ResourceTest test_dispatch [32mOK [0m[32m 0.01[0m test_dispatch_default [32mOK [0m[32m 0.01[0m test_dispatch_no_default [32mOK [0m[32m 0.01[0m test_get_action_args [32mOK [0m[32m 0.01[0m TestHelpers test_data_passed_properly_through_headers [32mOK [0m[32m 0.01[0m test_headers_are_unicode [32mOK [0m[32m 0.01[0m ====================================================================== FAIL: Spin up the api servers with multiprocessing on ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/tests/functional/v1/test_multiprocessing.py",> line 41, in test_multiprocessing self.assertEqual(response.status, 200) AssertionError: 500 != 200
raise self.failureException, \ (None or '%r != %r' % (500, 200))
---------------------------------------------------------------------- XML: nosetests.xml Slowest 5 tests took 140.65 secs: [31m 75.73 TestGlanceControl.test_bouncing[0m [31m 17.09 TestBinGlanceCacheManage.test_queue[0m [31m 16.85 TestBinGlance.test_results_filtering[0m [31m 16.16 TestRootApi.test_version_configurations[0m [31m 14.82 TestGlanceControl.test_reload[0m ---------------------------------------------------------------------- Ran 932 tests in 792.988s FAILED (SKIP=89, failures=1) ERROR: InvocationError: '<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/.tox/py26/bin/nosetests'> ___________________________________ summary ____________________________________ ERROR: py26: commands failed + result=1 + echo 'Begin pip freeze output from test virtualenv:' Begin pip freeze output from test virtualenv: + echo ====================================================================== ====================================================================== + .tox/py26/bin/pip freeze Babel==1.3 Jinja2==2.7.1 MarkupSafe==0.18 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.0.8 amqp==1.3.0 anyjson==0.3.3 argparse==1.2.1 boto==2.1.1 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 glance==jenkins-periodic-glance-python26-stable-folsom-32 greenlet==0.4.1 httplib2==0.8 importlib==1.0.2 iso8601==0.1.4 jsonschema==2.1.0 kombu==3.0.0 lxml==3.2.3 mox==0.5.3 nose==1.3.0 nose-exclude==0.1.10 nosehtmloutput==0.0.5 openstack.nose-plugin==0.11 ordereddict==1.1 passlib==1.6.1 pep8==1.1 pycparser==2.10 pycrypto==2.6 pysendfile==2.0.0 python-swiftclient==1.8.0 pytz==2013.7 repoze.lru==0.6 requests==2.0.0 simplejson==3.3.1 sqlalchemy-migrate==0.7.2 termcolor==1.1.0 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=96 + '[' 96 -eq 0 ']' + exit 1 + rm -rf /tmp/tmp.fjRbdpLylr Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python26-stable-folsom/32/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python26-stable-folsom/centos6-2/32 [SCP] Copying console log.
See <https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/33/> ------------------------------------------ [...truncated 1287 lines...] File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 574, in _do_request raise exception.Invalid(res.read()) Invalid: 400 Bad Request The server could not comply with the request since it is either malformed or otherwise incorrect. Unrecognized changes-since value 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 0x56a3bd0>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x56a3bd0>} 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 0x473fed0>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x473fed0>} 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 0x22d06d0> iso8601.iso8601: DEBUG: Got None for 'second_fraction' with default None 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-844592a7-d444-44b1-8b23-d937b275d111 --------------------- >> end captured logging << --------------------- ====================================================================== FAIL: Tests that the /images/detail registry API returns list of ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/tests/unit/v1/test_api.py",> line 1409, in test_get_details_filter_changes_since self.assertEquals(res.status_int, 400) AssertionError: 200 != 400
raise self.failureException, \ (None or '%r != %r' % (200, 400))
-------------------- >> begin captured logging << -------------------- glance.store: DEBUG: Attempting to import store glance.store.filesystem.Store 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: ERROR: Could not find s3_store_host in configuration options. glance.store.base: ERROR: Failed to configure store correctly. 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: ERROR: Failed to configure store correctly. Disabling add method. glance.store: DEBUG: Registering store <class 'glance.store.swift.Store'> 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': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <class 'glance.store.swift.Store'>, '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 0x579bb50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x579bb50>} 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 0x579bb50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x579bb50>} iso8601.iso8601: DEBUG: Parsed 2013-10-17T06:11:13Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'13', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'17', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x22d06d0> iso8601.iso8601: DEBUG: Got None for 'second_fraction' 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'17' 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'13' 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 0x579bb50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x579bb50>} iso8601.iso8601: DEBUG: Parsed 2013-10-19T06:11:13Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'13', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'19', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x22d06d0> iso8601.iso8601: DEBUG: Got None for 'second_fraction' 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'19' 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'13' 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 0x579bb50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x579bb50>} iso8601.iso8601: DEBUG: Parsed 2013-10-20T06:11:13+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'13', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'20', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x22d06d0> 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 None for 'second_fraction' 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'20' 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'13' 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 0x579bb50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x579bb50>} iso8601.iso8601: DEBUG: Parsed 2013-10-20T06:11:13-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'13', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'20', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x22d06d0> 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 None for 'second_fraction' 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'20' 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'13' 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 0x579bb50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x579bb50>} iso8601.iso8601: DEBUG: Parsed 2013-10-21T06:11:13Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'13', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'21', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x22d06d0> iso8601.iso8601: DEBUG: Got None for 'second_fraction' 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'21' 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'13' 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 0x579bb50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x579bb50>} 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 0x579bb50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x579bb50>} 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 0x22d06d0> iso8601.iso8601: DEBUG: Got None for 'second_fraction' with default None 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 74.47 secs: [31m 17.34 TestGlanceControl.test_bouncing[0m [31m 14.90 TestScrubber.test_delayed_delete[0m [31m 14.26 TestGlanceControl.test_reload[0m [31m 14.05 TestGlanceControl.test_respawn[0m [31m 13.93 TestBinGlanceCacheManage.test_queue[0m ---------------------------------------------------------------------- Ran 932 tests in 614.797s FAILED (SKIP=89, failures=2) ERROR: InvocationError: '<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/.tox/py26/bin/nosetests'> ___________________________________ summary ____________________________________ ERROR: py26: commands failed + result=1 + echo 'Begin pip freeze output from test virtualenv:' Begin pip freeze output from test virtualenv: + echo ====================================================================== ====================================================================== + .tox/py26/bin/pip freeze Babel==1.3 Jinja2==2.7.1 MarkupSafe==0.18 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.0.8 amqp==1.3.0 anyjson==0.3.3 argparse==1.2.1 boto==2.1.1 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 glance==jenkins-periodic-glance-python26-stable-folsom-33 greenlet==0.4.1 httplib2==0.8 importlib==1.0.2 iso8601==0.1.5 jsonschema==2.1.0 kombu==3.0.0 lxml==3.2.3 mox==0.5.3 nose==1.3.0 nose-exclude==0.1.10 nosehtmloutput==0.0.5 openstack.nose-plugin==0.11 ordereddict==1.1 passlib==1.6.1 pep8==1.1 pycparser==2.10 pycrypto==2.6.1 pysendfile==2.0.0 python-swiftclient==1.8.0 pytz==2013.7 repoze.lru==0.6 requests==2.0.0 simplejson==3.3.1 sqlalchemy-migrate==0.7.2 termcolor==1.1.0 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=95 + '[' 95 -eq 0 ']' + exit 1 + rm -rf /tmp/tmp.3n0mHuxHs9 Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python26-stable-folsom/33/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python26-stable-folsom/centos6-10/33 [SCP] Copying console log.
See <https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/34/> ------------------------------------------ [...truncated 1287 lines...] File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 574, in _do_request raise exception.Invalid(res.read()) Invalid: 400 Bad Request The server could not comply with the request since it is either malformed or otherwise incorrect. Unrecognized changes-since value 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 0x4e48b10>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4e48b10>} 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 0x46c1710>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x46c1710>} 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 0x1af56d0> iso8601.iso8601: DEBUG: Got None for 'second_fraction' with default None 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-719c565b-0788-47a1-9c3a-48014a3e20a7 --------------------- >> end captured logging << --------------------- ====================================================================== FAIL: Tests that the /images/detail registry API returns list of ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/tests/unit/v1/test_api.py",> line 1409, in test_get_details_filter_changes_since self.assertEquals(res.status_int, 400) AssertionError: 200 != 400
raise self.failureException, \ (None or '%r != %r' % (200, 400))
-------------------- >> begin captured logging << -------------------- glance.store: DEBUG: Attempting to import store glance.store.filesystem.Store 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: ERROR: Could not find s3_store_host in configuration options. glance.store.base: ERROR: Failed to configure store correctly. 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: ERROR: Failed to configure store correctly. Disabling add method. glance.store: DEBUG: Registering store <class 'glance.store.swift.Store'> 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': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <class 'glance.store.swift.Store'>, '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 0x4802110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4802110>} 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 0x4802110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4802110>} iso8601.iso8601: DEBUG: Parsed 2013-10-18T06:11:01Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'01', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'18', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x1af56d0> iso8601.iso8601: DEBUG: Got None for 'second_fraction' 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'18' 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'01' 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 0x4802110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4802110>} iso8601.iso8601: DEBUG: Parsed 2013-10-20T06:11:01Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'01', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'20', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x1af56d0> iso8601.iso8601: DEBUG: Got None for 'second_fraction' 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'20' 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'01' 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 0x4802110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4802110>} iso8601.iso8601: DEBUG: Parsed 2013-10-21T06:11:01+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'01', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'21', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x1af56d0> 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 None for 'second_fraction' 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'21' 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'01' 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 0x4802110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4802110>} iso8601.iso8601: DEBUG: Parsed 2013-10-21T06:11:01-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'01', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'21', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x1af56d0> 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 None for 'second_fraction' 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'21' 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'01' 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 0x4802110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4802110>} iso8601.iso8601: DEBUG: Parsed 2013-10-22T06:11:01Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'01', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'22', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x1af56d0> iso8601.iso8601: DEBUG: Got None for 'second_fraction' 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'22' 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'01' 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 0x4802110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4802110>} 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 0x4802110>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4802110>} 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 0x1af56d0> iso8601.iso8601: DEBUG: Got None for 'second_fraction' with default None 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 70.88 secs: [31m 14.83 TestGlanceControl.test_bouncing[0m [31m 14.26 TestGlanceControl.test_reload[0m [31m 14.00 TestGlanceControl.test_respawn[0m [31m 13.91 TestBinGlanceCacheManage.test_queue[0m [31m 13.88 TestRootApi.test_version_configurations[0m ---------------------------------------------------------------------- Ran 932 tests in 607.498s FAILED (SKIP=89, failures=2) ERROR: InvocationError: '<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/.tox/py26/bin/nosetests'> ___________________________________ summary ____________________________________ ERROR: py26: commands failed + result=1 + echo 'Begin pip freeze output from test virtualenv:' Begin pip freeze output from test virtualenv: + echo ====================================================================== ====================================================================== + .tox/py26/bin/pip freeze Babel==1.3 Jinja2==2.7.1 MarkupSafe==0.18 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.0.8 amqp==1.3.0 anyjson==0.3.3 argparse==1.2.1 boto==2.1.1 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 glance==jenkins-periodic-glance-python26-stable-folsom-34 greenlet==0.4.1 httplib2==0.8 importlib==1.0.2 iso8601==0.1.5 jsonschema==2.1.0 kombu==3.0.0 lxml==3.2.3 mox==0.5.3 nose==1.3.0 nose-exclude==0.1.10 nosehtmloutput==0.0.5 openstack.nose-plugin==0.11 ordereddict==1.1 passlib==1.6.1 pep8==1.1 pycparser==2.10 pycrypto==2.6.1 pysendfile==2.0.0 python-swiftclient==1.8.0 pytz==2013.7 repoze.lru==0.6 requests==2.0.0 simplejson==3.3.1 sqlalchemy-migrate==0.7.2 termcolor==1.1.0 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=95 + '[' 95 -eq 0 ']' + exit 1 + rm -rf /tmp/tmp.SvJIUXKcrR Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python26-stable-folsom/34/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python26-stable-folsom/centos6-10/34 [SCP] Copying console log.
See <https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/35/> ------------------------------------------ [...truncated 1275 lines...] action, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 63, in wrapped return func(self, *args, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 444, in do_request headers=headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 80, in wrapped return func(self, method, url, body, headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 574, in _do_request raise exception.Invalid(res.read()) Invalid: 400 Bad Request The server could not comply with the request since it is either malformed or otherwise incorrect. Unrecognized changes-since value 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 0x4800610>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4800610>} 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 0x3886890>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x3886890>} 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 0x15cf6d0> 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-e1ac97f3-9490-4440-9be0-0f53d8dbef46 --------------------- >> end captured logging << --------------------- ====================================================================== FAIL: Tests that the /images/detail registry API returns list of ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/tests/unit/v1/test_api.py",> line 1409, in test_get_details_filter_changes_since self.assertEquals(res.status_int, 400) AssertionError: 200 != 400
raise self.failureException, \ (None or '%r != %r' % (200, 400))
-------------------- >> begin captured logging << -------------------- glance.store: DEBUG: Attempting to import store glance.store.filesystem.Store 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: ERROR: Could not find s3_store_host in configuration options. glance.store.base: ERROR: Failed to configure store correctly. 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: ERROR: Failed to configure store correctly. Disabling add method. glance.store: DEBUG: Registering store <class 'glance.store.swift.Store'> 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': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <class 'glance.store.swift.Store'>, '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 0x4f72850>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4f72850>} 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 0x4f72850>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4f72850>} iso8601.iso8601: DEBUG: Parsed 2013-10-19T06:11: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'19', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x15cf6d0> 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'19' 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'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 0x4f72850>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4f72850>} iso8601.iso8601: DEBUG: Parsed 2013-10-21T06:11: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'21', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x15cf6d0> 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'21' 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'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 0x4f72850>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4f72850>} iso8601.iso8601: DEBUG: Parsed 2013-10-22T06:11: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'22', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x15cf6d0> 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'22' 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'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 0x4f72850>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4f72850>} iso8601.iso8601: DEBUG: Parsed 2013-10-22T06:11: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'22', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x15cf6d0> 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'22' 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'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 0x4f72850>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4f72850>} iso8601.iso8601: DEBUG: Parsed 2013-10-23T06:11: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'23', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x15cf6d0> 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'11' 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 0x4f72850>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4f72850>} 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 0x4f72850>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4f72850>} 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 0x15cf6d0> 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 75.85 secs: [31m 19.00 TestGlanceControl.test_bouncing[0m [31m 14.79 TestScrubber.test_delayed_delete[0m [31m 14.23 TestGlanceControl.test_reload[0m [31m 13.97 TestGlanceControl.test_respawn[0m [31m 13.88 TestBinGlanceCacheManage.test_queue[0m ---------------------------------------------------------------------- Ran 932 tests in 610.771s FAILED (SKIP=89, failures=2) ERROR: InvocationError: '<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/.tox/py26/bin/nosetests'> ___________________________________ summary ____________________________________ ERROR: py26: commands failed + result=1 + echo 'Begin pip freeze output from test virtualenv:' Begin pip freeze output from test virtualenv: + echo ====================================================================== ====================================================================== + .tox/py26/bin/pip freeze Babel==1.3 Jinja2==2.7.1 MarkupSafe==0.18 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.0.8 amqp==1.3.0 anyjson==0.3.3 argparse==1.2.1 boto==2.1.1 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 glance==jenkins-periodic-glance-python26-stable-folsom-35 greenlet==0.4.1 httplib2==0.8 importlib==1.0.2 iso8601==0.1.7 jsonschema==2.2.0 kombu==3.0.0 lxml==3.2.3 mox==0.5.3 nose==1.3.0 nose-exclude==0.1.10 nosehtmloutput==0.0.5 openstack.nose-plugin==0.11 ordereddict==1.1 passlib==1.6.1 pep8==1.1 pycparser==2.10 pycrypto==2.6.1 pysendfile==2.0.0 python-swiftclient==1.8.0 pytz==2013.7 repoze.lru==0.6 requests==2.0.0 simplejson==3.3.1 sqlalchemy-migrate==0.7.2 termcolor==1.1.0 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=95 + '[' 95 -eq 0 ']' + exit 1 + rm -rf /tmp/tmp.NsNy8ej5b5 Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python26-stable-folsom/35/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python26-stable-folsom/centos6-10/35 [SCP] Copying console log.
See <https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/36/> ------------------------------------------ [...truncated 1275 lines...] action, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 63, in wrapped return func(self, *args, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 444, in do_request headers=headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 80, in wrapped return func(self, method, url, body, headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 574, in _do_request raise exception.Invalid(res.read()) Invalid: 400 Bad Request The server could not comply with the request since it is either malformed or otherwise incorrect. Unrecognized changes-since value 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 0x4f420d0>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4f420d0>} 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 0x47ce7d0>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x47ce7d0>} 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 0x1bed6d0> 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-1ea48f7b-5c8b-4579-b181-ed7ae4688ed0 --------------------- >> end captured logging << --------------------- ====================================================================== FAIL: Tests that the /images/detail registry API returns list of ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/tests/unit/v1/test_api.py",> line 1409, in test_get_details_filter_changes_since self.assertEquals(res.status_int, 400) AssertionError: 200 != 400
raise self.failureException, \ (None or '%r != %r' % (200, 400))
-------------------- >> begin captured logging << -------------------- glance.store: DEBUG: Attempting to import store glance.store.filesystem.Store 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: ERROR: Could not find s3_store_host in configuration options. glance.store.base: ERROR: Failed to configure store correctly. 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: ERROR: Failed to configure store correctly. Disabling add method. glance.store: DEBUG: Registering store <class 'glance.store.swift.Store'> 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': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <class 'glance.store.swift.Store'>, '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 0x5673950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5673950>} 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 0x5673950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5673950>} iso8601.iso8601: DEBUG: Parsed 2013-10-22T06:08:50Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'50', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'22', 'minute': u'08'} with default timezone <iso8601.iso8601.Utc object at 0x1bed6d0> 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'22' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'08' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'50' 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 0x5673950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5673950>} iso8601.iso8601: DEBUG: Parsed 2013-10-24T06:08:50Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'50', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'24', 'minute': u'08'} with default timezone <iso8601.iso8601.Utc object at 0x1bed6d0> 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'24' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'08' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'50' 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 0x5673950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5673950>} iso8601.iso8601: DEBUG: Parsed 2013-10-25T06:08:50+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'50', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'25', 'minute': u'08'} with default timezone <iso8601.iso8601.Utc object at 0x1bed6d0> 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'25' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'08' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'50' 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 0x5673950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5673950>} iso8601.iso8601: DEBUG: Parsed 2013-10-25T06:08:50-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'50', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'25', 'minute': u'08'} with default timezone <iso8601.iso8601.Utc object at 0x1bed6d0> 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'25' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'08' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'50' 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 0x5673950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5673950>} iso8601.iso8601: DEBUG: Parsed 2013-10-26T06:08:50Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'50', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'26', 'minute': u'08'} with default timezone <iso8601.iso8601.Utc object at 0x1bed6d0> 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'08' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'50' 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 0x5673950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5673950>} 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 0x5673950>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5673950>} 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 0x1bed6d0> 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 66.04 secs: [31m 13.76 TestScrubber.test_delayed_delete[0m [31m 13.47 TestGlanceControl.test_reload[0m [31m 13.30 TestGlanceControl.test_respawn[0m [31m 13.26 TestGlanceControl.test_bouncing[0m [31m 12.26 TestApi.test_unsupported_default_store[0m ---------------------------------------------------------------------- Ran 932 tests in 482.431s FAILED (SKIP=89, failures=2) ERROR: InvocationError: '<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/.tox/py26/bin/nosetests'> ___________________________________ summary ____________________________________ ERROR: py26: commands failed + result=1 + echo 'Begin pip freeze output from test virtualenv:' Begin pip freeze output from test virtualenv: + echo ====================================================================== ====================================================================== + .tox/py26/bin/pip freeze Babel==1.3 Jinja2==2.7.1 MarkupSafe==0.18 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.0.8 amqp==1.3.0 anyjson==0.3.3 argparse==1.2.1 boto==2.1.1 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 glance==jenkins-periodic-glance-python26-stable-folsom-36 greenlet==0.4.1 httplib2==0.8 importlib==1.0.2 iso8601==0.1.8 jsonschema==2.2.0 kombu==3.0.0 lxml==3.2.3 mox==0.5.3 nose==1.3.0 nose-exclude==0.1.10 nosehtmloutput==0.0.5 openstack.nose-plugin==0.11 ordereddict==1.1 passlib==1.6.1 pep8==1.1 pycparser==2.10 pycrypto==2.6.1 pysendfile==2.0.0 python-swiftclient==1.8.0 pytz==2013.7 repoze.lru==0.6 requests==2.0.0 simplejson==3.3.1 sqlalchemy-migrate==0.7.2 termcolor==1.1.0 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=95 + '[' 95 -eq 0 ']' + exit 1 + rm -rf /tmp/tmp.QwpfJ8kXV1 Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python26-stable-folsom/36/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python26-stable-folsom/centos6-14/36 [SCP] Copying console log.
See <https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/37/> ------------------------------------------ [...truncated 1275 lines...] action, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 63, in wrapped return func(self, *args, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 444, in do_request headers=headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 80, in wrapped return func(self, method, url, body, headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 574, in _do_request raise exception.Invalid(res.read()) Invalid: 400 Bad Request The server could not comply with the request since it is either malformed or otherwise incorrect. Unrecognized changes-since value 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 0x4cc1310>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4cc1310>} 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 0x3f24450>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x3f24450>} 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 0x192a6d0> 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-6de96fd6-55e6-4cef-9e2c-71b5051f0c76 --------------------- >> end captured logging << --------------------- ====================================================================== FAIL: Tests that the /images/detail registry API returns list of ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/tests/unit/v1/test_api.py",> line 1409, in test_get_details_filter_changes_since self.assertEquals(res.status_int, 400) AssertionError: 200 != 400
raise self.failureException, \ (None or '%r != %r' % (200, 400))
-------------------- >> begin captured logging << -------------------- glance.store: DEBUG: Attempting to import store glance.store.filesystem.Store 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: ERROR: Could not find s3_store_host in configuration options. glance.store.base: ERROR: Failed to configure store correctly. 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: ERROR: Failed to configure store correctly. Disabling add method. glance.store: DEBUG: Registering store <class 'glance.store.swift.Store'> 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': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <class 'glance.store.swift.Store'>, '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 0x52a7e10>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x52a7e10>} 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 0x52a7e10>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x52a7e10>} iso8601.iso8601: DEBUG: Parsed 2013-10-24T06:10:52Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'52', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'24', 'minute': u'10'} with default timezone <iso8601.iso8601.Utc object at 0x192a6d0> 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'24' 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'52' 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 0x52a7e10>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x52a7e10>} iso8601.iso8601: DEBUG: Parsed 2013-10-26T06:10:52Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'52', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'26', 'minute': u'10'} with default timezone <iso8601.iso8601.Utc object at 0x192a6d0> 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'10' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'52' 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 0x52a7e10>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x52a7e10>} iso8601.iso8601: DEBUG: Parsed 2013-10-27T06:10:52+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'52', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'27', 'minute': u'10'} with default timezone <iso8601.iso8601.Utc object at 0x192a6d0> 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'27' 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'52' 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 0x52a7e10>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x52a7e10>} iso8601.iso8601: DEBUG: Parsed 2013-10-27T06:10:52-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'52', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'27', 'minute': u'10'} with default timezone <iso8601.iso8601.Utc object at 0x192a6d0> 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'27' 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'52' 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 0x52a7e10>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x52a7e10>} iso8601.iso8601: DEBUG: Parsed 2013-10-28T06:10:52Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'52', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'28', 'minute': u'10'} with default timezone <iso8601.iso8601.Utc object at 0x192a6d0> 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'28' 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'52' 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 0x52a7e10>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x52a7e10>} 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 0x52a7e10>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x52a7e10>} 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 0x192a6d0> 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 72.48 secs: [31m 16.29 TestGlanceControl.test_bouncing[0m [31m 14.73 TestScrubber.test_delayed_delete[0m [31m 14.11 TestGlanceControl.test_reload[0m [31m 13.93 TestGlanceControl.test_respawn[0m [31m 13.42 TestBinGlanceCacheManage.test_queue[0m ---------------------------------------------------------------------- Ran 932 tests in 593.163s FAILED (SKIP=89, failures=2) ERROR: InvocationError: '<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/.tox/py26/bin/nosetests'> ___________________________________ summary ____________________________________ ERROR: py26: commands failed + result=1 + echo 'Begin pip freeze output from test virtualenv:' Begin pip freeze output from test virtualenv: + echo ====================================================================== ====================================================================== + .tox/py26/bin/pip freeze Babel==1.3 Jinja2==2.7.1 MarkupSafe==0.18 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.0.8 amqp==1.3.0 anyjson==0.3.3 argparse==1.2.1 boto==2.1.1 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 glance==jenkins-periodic-glance-python26-stable-folsom-37 greenlet==0.4.1 httplib2==0.8 importlib==1.0.2 iso8601==0.1.8 jsonschema==2.2.0 kombu==3.0.0 lxml==3.2.3 mox==0.5.3 nose==1.3.0 nose-exclude==0.1.10 nosehtmloutput==0.0.5 openstack.nose-plugin==0.11 ordereddict==1.1 passlib==1.6.1 pep8==1.1 pycparser==2.10 pycrypto==2.6.1 pysendfile==2.0.0 python-swiftclient==1.8.0 pytz==2013.7 repoze.lru==0.6 requests==2.0.0 simplejson==3.3.1 sqlalchemy-migrate==0.7.2 termcolor==1.1.0 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=95 + '[' 95 -eq 0 ']' + exit 1 + rm -rf /tmp/tmp.chWmFvL8zG Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python26-stable-folsom/37/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python26-stable-folsom/centos6-4/37 [SCP] Copying console log.
See <https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/38/> ------------------------------------------ [...truncated 1293 lines...] action, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 63, in wrapped return func(self, *args, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 444, in do_request headers=headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 80, in wrapped return func(self, method, url, body, headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 574, in _do_request raise exception.Invalid(res.read()) Invalid: 400 Bad Request The server could not comply with the request since it is either malformed or otherwise incorrect. Unrecognized changes-since value 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 0x5017d50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5017d50>} 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 0x49d6890>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x49d6890>} 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 0x1c596d0> 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-379f6add-0ee6-495d-85f9-59db0e8b29c9 --------------------- >> end captured logging << --------------------- ====================================================================== FAIL: Tests that the /images/detail registry API returns list of ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/tests/unit/v1/test_api.py",> line 1409, in test_get_details_filter_changes_since self.assertEquals(res.status_int, 400) AssertionError: 200 != 400
raise self.failureException, \ (None or '%r != %r' % (200, 400))
-------------------- >> begin captured logging << -------------------- glance.store: DEBUG: Attempting to import store glance.store.filesystem.Store 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: ERROR: Could not find s3_store_host in configuration options. glance.store.base: ERROR: Failed to configure store correctly. 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: ERROR: Failed to configure store correctly. Disabling add method. glance.store: DEBUG: Registering store <class 'glance.store.swift.Store'> 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': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <class 'glance.store.swift.Store'>, '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 0x576b250>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x576b250>} 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 0x576b250>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x576b250>} iso8601.iso8601: DEBUG: Parsed 2013-10-27T06:13:38Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'38', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'27', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x1c596d0> 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'13' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'38' 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 0x576b250>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x576b250>} iso8601.iso8601: DEBUG: Parsed 2013-10-29T06:13:38Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'38', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'29', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x1c596d0> 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'13' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'38' 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 0x576b250>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x576b250>} iso8601.iso8601: DEBUG: Parsed 2013-10-30T06:13:38+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'38', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'30', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x1c596d0> 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'13' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'38' 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 0x576b250>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x576b250>} iso8601.iso8601: DEBUG: Parsed 2013-10-30T06:13:38-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'38', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'30', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x1c596d0> 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'13' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'38' 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 0x576b250>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x576b250>} iso8601.iso8601: DEBUG: Parsed 2013-10-31T06:13:38Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'38', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'31', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x1c596d0> 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'13' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'38' 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 0x576b250>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x576b250>} 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 0x576b250>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x576b250>} 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 0x1c596d0> 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 80.52 secs: [31m 17.35 TestBinGlance.test_results_filtering[0m [31m 16.44 TestBinGlanceCacheManage.test_queue[0m [31m 16.09 TestScrubber.test_delayed_delete[0m [31m 15.36 TestRootApi.test_version_configurations[0m [31m 15.28 TestGlanceControl.test_reload[0m ---------------------------------------------------------------------- Ran 932 tests in 728.813s FAILED (SKIP=89, errors=1, failures=2) ERROR: InvocationError: '<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/.tox/py26/bin/nosetests'> ___________________________________ summary ____________________________________ ERROR: py26: commands failed + result=1 + echo 'Begin pip freeze output from test virtualenv:' Begin pip freeze output from test virtualenv: + echo ====================================================================== ====================================================================== + .tox/py26/bin/pip freeze Babel==1.3 Jinja2==2.7.1 MarkupSafe==0.18 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.0.8 amqp==1.3.1 anyjson==0.3.3 argparse==1.2.1 boto==2.1.1 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 glance==jenkins-periodic-glance-python26-stable-folsom-38 greenlet==0.4.1 httplib2==0.8 importlib==1.0.2 iso8601==0.1.8 jsonschema==2.2.0 kombu==3.0.1 lxml==3.2.3 mox==0.5.3 nose==1.3.0 nose-exclude==0.1.10 nosehtmloutput==0.0.5 openstack.nose-plugin==0.11 ordereddict==1.1 passlib==1.6.1 pep8==1.1 pycparser==2.10 pycrypto==2.6.1 pysendfile==2.0.0 python-swiftclient==1.8.0 pytz==2013.7 repoze.lru==0.6 requests==2.0.1 simplejson==3.3.1 sqlalchemy-migrate==0.7.2 termcolor==1.1.0 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=94 + '[' 94 -eq 0 ']' + exit 1 + rm -rf /tmp/tmp.PPDu3IBYHy Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python26-stable-folsom/38/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python26-stable-folsom/centos6-2/38 [SCP] Copying console log.
See <https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/39/> ------------------------------------------ [...truncated 1288 lines...] action, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 63, in wrapped return func(self, *args, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 444, in do_request headers=headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 80, in wrapped return func(self, method, url, body, headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 574, in _do_request raise exception.Invalid(res.read()) Invalid: 400 Bad Request The server could not comply with the request since it is either malformed or otherwise incorrect. Unrecognized changes-since value 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 0x46ec990>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x46ec990>} 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 0x44a1f10>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x44a1f10>} 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 0x15a66d0> 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-a8545cd0-41bb-43c8-9530-383b4aefbe89 --------------------- >> end captured logging << --------------------- ====================================================================== FAIL: Tests that the /images/detail registry API returns list of ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/tests/unit/v1/test_api.py",> line 1409, in test_get_details_filter_changes_since self.assertEquals(res.status_int, 400) AssertionError: 200 != 400
raise self.failureException, \ (None or '%r != %r' % (200, 400))
-------------------- >> begin captured logging << -------------------- glance.store: DEBUG: Attempting to import store glance.store.filesystem.Store 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: ERROR: Could not find s3_store_host in configuration options. glance.store.base: ERROR: Failed to configure store correctly. 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: ERROR: Failed to configure store correctly. Disabling add method. glance.store: DEBUG: Registering store <class 'glance.store.swift.Store'> 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': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <class 'glance.store.swift.Store'>, '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 0x54eb310>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x54eb310>} 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 0x54eb310>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x54eb310>} iso8601.iso8601: DEBUG: Parsed 2013-10-29T06:11:00Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'00', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'29', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x15a66d0> 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'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'00' 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 0x54eb310>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x54eb310>} iso8601.iso8601: DEBUG: Parsed 2013-10-31T06:11:00Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'00', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'31', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x15a66d0> 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'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'00' 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 0x54eb310>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x54eb310>} iso8601.iso8601: DEBUG: Parsed 2013-11-01T06:11:00+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'00', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'01', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x15a66d0> 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'01' 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'00' 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 0x54eb310>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x54eb310>} iso8601.iso8601: DEBUG: Parsed 2013-11-01T06:11:00-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'00', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'01', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x15a66d0> 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'01' 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'00' 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 0x54eb310>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x54eb310>} iso8601.iso8601: DEBUG: Parsed 2013-11-02T06:11:00Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'00', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'02', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x15a66d0> 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'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'00' 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 0x54eb310>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x54eb310>} 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 0x54eb310>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x54eb310>} 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 0x15a66d0> 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 72.07 secs: [31m 18.87 TestGlanceControl.test_bouncing[0m [31m 14.13 TestGlanceControl.test_reload[0m [31m 13.57 TestBinGlanceCacheManage.test_queue[0m [31m 12.76 TestApi.test_unsupported_default_store[0m [31m 12.74 TestRootApi.test_version_configurations[0m ---------------------------------------------------------------------- Ran 932 tests in 586.450s FAILED (SKIP=89, failures=3) ERROR: InvocationError: '<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/.tox/py26/bin/nosetests'> ___________________________________ summary ____________________________________ ERROR: py26: commands failed + result=1 + echo 'Begin pip freeze output from test virtualenv:' Begin pip freeze output from test virtualenv: + echo ====================================================================== ====================================================================== + .tox/py26/bin/pip freeze Babel==1.3 Jinja2==2.7.1 MarkupSafe==0.18 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.0.8 amqp==1.3.2 anyjson==0.3.3 argparse==1.2.1 boto==2.1.1 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 glance==jenkins-periodic-glance-python26-stable-folsom-39 greenlet==0.4.1 httplib2==0.8 importlib==1.0.2 iso8601==0.1.8 jsonschema==2.2.0 kombu==3.0.2 lxml==3.2.3 mox==0.5.3 nose==1.3.0 nose-exclude==0.1.10 nosehtmloutput==0.0.5 openstack.nose-plugin==0.11 ordereddict==1.1 passlib==1.6.1 pep8==1.1 pycparser==2.10 pycrypto==2.6.1 pysendfile==2.0.0 python-swiftclient==1.8.0 pytz==2013.7 repoze.lru==0.6 requests==2.0.1 simplejson==3.3.1 sqlalchemy-migrate==0.7.2 termcolor==1.1.0 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=94 + '[' 94 -eq 0 ']' + exit 1 + rm -rf /tmp/tmp.bnCLIxOiNz Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python26-stable-folsom/39/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python26-stable-folsom/centos6-8/39 [SCP] Copying console log.
See <https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/40/> ------------------------------------------ [...truncated 1275 lines...] action, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 63, in wrapped return func(self, *args, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 444, in do_request headers=headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 80, in wrapped return func(self, method, url, body, headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 574, in _do_request raise exception.Invalid(res.read()) Invalid: 400 Bad Request The server could not comply with the request since it is either malformed or otherwise incorrect. Unrecognized changes-since value 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 0x5452490>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5452490>} 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 0x55d1e90>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x55d1e90>} 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 0x27d66d0> 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-3187efcf-4e4b-49f1-8280-b9fc2867d10b --------------------- >> end captured logging << --------------------- ====================================================================== FAIL: Tests that the /images/detail registry API returns list of ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/tests/unit/v1/test_api.py",> line 1409, in test_get_details_filter_changes_since self.assertEquals(res.status_int, 400) AssertionError: 200 != 400
raise self.failureException, \ (None or '%r != %r' % (200, 400))
-------------------- >> begin captured logging << -------------------- glance.store: DEBUG: Attempting to import store glance.store.filesystem.Store 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: ERROR: Could not find s3_store_host in configuration options. glance.store.base: ERROR: Failed to configure store correctly. 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: ERROR: Failed to configure store correctly. Disabling add method. glance.store: DEBUG: Registering store <class 'glance.store.swift.Store'> 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': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <class 'glance.store.swift.Store'>, '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 0x6515810>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6515810>} 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 0x6515810>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6515810>} iso8601.iso8601: DEBUG: Parsed 2013-10-30T06:11:15Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'10', 'timezone': u'Z', 'second': u'15', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'30', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x27d66d0> 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'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'15' 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 0x6515810>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6515810>} iso8601.iso8601: DEBUG: Parsed 2013-11-01T06:11:15Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'15', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'01', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x27d66d0> 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'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'15' 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 0x6515810>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6515810>} iso8601.iso8601: DEBUG: Parsed 2013-11-02T06:11:15+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'15', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'02', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x27d66d0> 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'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'15' 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 0x6515810>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6515810>} iso8601.iso8601: DEBUG: Parsed 2013-11-02T06:11:15-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'15', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'02', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x27d66d0> 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'11' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'15' 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 0x6515810>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6515810>} iso8601.iso8601: DEBUG: Parsed 2013-11-03T06:11:15Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'15', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'03', 'minute': u'11'} with default timezone <iso8601.iso8601.Utc object at 0x27d66d0> 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'15' 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 0x6515810>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6515810>} 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 0x6515810>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x6515810>} 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 0x27d66d0> 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 80.02 secs: [31m 22.99 TestGlanceControl.test_bouncing[0m [31m 14.83 TestScrubber.test_delayed_delete[0m [31m 14.31 TestGlanceControl.test_reload[0m [31m 14.02 TestGlanceControl.test_respawn[0m [31m 13.87 TestBinGlanceCacheManage.test_queue[0m ---------------------------------------------------------------------- Ran 932 tests in 618.725s FAILED (SKIP=89, failures=2) ERROR: InvocationError: '<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/.tox/py26/bin/nosetests'> ___________________________________ summary ____________________________________ ERROR: py26: commands failed + result=1 + echo 'Begin pip freeze output from test virtualenv:' Begin pip freeze output from test virtualenv: + echo ====================================================================== ====================================================================== + .tox/py26/bin/pip freeze Babel==1.3 Jinja2==2.7.1 MarkupSafe==0.18 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.0.8 amqp==1.3.2 anyjson==0.3.3 argparse==1.2.1 boto==2.1.1 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 glance==jenkins-periodic-glance-python26-stable-folsom-40 greenlet==0.4.1 httplib2==0.8 importlib==1.0.2 iso8601==0.1.8 jsonschema==2.2.0 kombu==3.0.2 lxml==3.2.3 mox==0.5.3 nose==1.3.0 nose-exclude==0.1.10 nosehtmloutput==0.0.5 openstack.nose-plugin==0.11 ordereddict==1.1 passlib==1.6.1 pep8==1.1 pycparser==2.10 pycrypto==2.6.1 pysendfile==2.0.0 python-swiftclient==1.8.0 pytz==2013.7 repoze.lru==0.6 requests==2.0.1 simplejson==3.3.1 sqlalchemy-migrate==0.7.2 termcolor==1.1.0 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=95 + '[' 95 -eq 0 ']' + exit 1 + rm -rf /tmp/tmp.0UDzmw5vog Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python26-stable-folsom/40/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python26-stable-folsom/centos6-6/40 [SCP] Copying console log.
See <https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/41/> ------------------------------------------ [...truncated 1275 lines...] action, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 63, in wrapped return func(self, *args, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 444, in do_request headers=headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 80, in wrapped return func(self, method, url, body, headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 574, in _do_request raise exception.Invalid(res.read()) Invalid: 400 Bad Request The server could not comply with the request since it is either malformed or otherwise incorrect. Unrecognized changes-since value 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 0x5452910>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5452910>} 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 0x4e25d90>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x4e25d90>} 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 0x20046d0> 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-68b42b12-c964-4f58-b52c-925cd54f9d88 --------------------- >> end captured logging << --------------------- ====================================================================== FAIL: Tests that the /images/detail registry API returns list of ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/tests/unit/v1/test_api.py",> line 1409, in test_get_details_filter_changes_since self.assertEquals(res.status_int, 400) AssertionError: 200 != 400
raise self.failureException, \ (None or '%r != %r' % (200, 400))
-------------------- >> begin captured logging << -------------------- glance.store: DEBUG: Attempting to import store glance.store.filesystem.Store 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: ERROR: Could not find s3_store_host in configuration options. glance.store.base: ERROR: Failed to configure store correctly. 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: ERROR: Failed to configure store correctly. Disabling add method. glance.store: DEBUG: Registering store <class 'glance.store.swift.Store'> 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': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <class 'glance.store.swift.Store'>, '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 0x5d78750>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5d78750>} 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 0x5d78750>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5d78750>} iso8601.iso8601: DEBUG: Parsed 2013-11-04T06:13:38Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'38', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'04', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x20046d0> iso8601.iso8601: DEBUG: Got u'2013' for 'year' with default None iso8601.iso8601: DEBUG: Got u'11' for 'month' with default None iso8601.iso8601: DEBUG: Got u'04' for 'day' with default None iso8601.iso8601: DEBUG: Got u'06' for 'hour' with default None iso8601.iso8601: DEBUG: Got u'13' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'38' 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 0x5d78750>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5d78750>} iso8601.iso8601: DEBUG: Parsed 2013-11-06T06:13:38Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'38', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'06', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x20046d0> 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'13' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'38' 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 0x5d78750>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5d78750>} iso8601.iso8601: DEBUG: Parsed 2013-11-07T06:13:38+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'38', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'07', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x20046d0> 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'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'38' 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 0x5d78750>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5d78750>} iso8601.iso8601: DEBUG: Parsed 2013-11-07T06:13:38-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'38', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'07', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x20046d0> 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'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'38' 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 0x5d78750>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5d78750>} iso8601.iso8601: DEBUG: Parsed 2013-11-08T06:13:38Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'38', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'08', 'minute': u'13'} with default timezone <iso8601.iso8601.Utc object at 0x20046d0> 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'13' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'38' 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 0x5d78750>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5d78750>} 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 0x5d78750>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5d78750>} 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 0x20046d0> 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.74 secs: [31m 31.70 TestGlanceControl.test_bouncing[0m [31m 17.36 TestBinGlanceCacheManage.test_queue[0m [31m 16.47 TestBinGlance.test_results_filtering[0m [31m 16.02 TestScrubber.test_delayed_delete[0m [31m 15.20 TestRootApi.test_version_configurations[0m ---------------------------------------------------------------------- Ran 932 tests in 748.983s FAILED (SKIP=89, failures=2) ERROR: InvocationError: '<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/.tox/py26/bin/nosetests'> ___________________________________ summary ____________________________________ ERROR: py26: commands failed + result=1 + echo 'Begin pip freeze output from test virtualenv:' Begin pip freeze output from test virtualenv: + echo ====================================================================== ====================================================================== + .tox/py26/bin/pip freeze Babel==1.3 Jinja2==2.7.1 MarkupSafe==0.18 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.0.8 amqp==1.3.2 anyjson==0.3.3 argparse==1.2.1 boto==2.1.1 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 glance==jenkins-periodic-glance-python26-stable-folsom-41 greenlet==0.4.1 httplib2==0.8 importlib==1.0.2 iso8601==0.1.8 jsonschema==2.3.0 kombu==3.0.2 lxml==3.2.3 mox==0.5.3 nose==1.3.0 nose-exclude==0.1.10 nosehtmloutput==0.0.5 openstack.nose-plugin==0.11 ordereddict==1.1 passlib==1.6.1 pep8==1.1 pycparser==2.10 pycrypto==2.6.1 pysendfile==2.0.0 python-swiftclient==1.8.0 pytz==2013.7 repoze.lru==0.6 requests==2.0.1 simplejson==3.3.1 sqlalchemy-migrate==0.7.2 termcolor==1.1.0 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=95 + '[' 95 -eq 0 ']' + exit 1 + rm -rf /tmp/tmp.7jdVMafSNP Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python26-stable-folsom/41/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python26-stable-folsom/centos6-2/41 [SCP] Copying console log.
See <https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/42/> ------------------------------------------ [...truncated 1275 lines...] action, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 63, in wrapped return func(self, *args, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 444, in do_request headers=headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 80, in wrapped return func(self, method, url, body, headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 574, in _do_request raise exception.Invalid(res.read()) Invalid: 400 Bad Request The server could not comply with the request since it is either malformed or otherwise incorrect. Unrecognized changes-since value 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 0x5662f50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5662f50>} 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 0x551f150>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x551f150>} 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 0x24a16d0> 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-366b3b1e-c760-4154-a461-7dc4301eb17d --------------------- >> end captured logging << --------------------- ====================================================================== FAIL: Tests that the /images/detail registry API returns list of ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/tests/unit/v1/test_api.py",> line 1409, in test_get_details_filter_changes_since self.assertEquals(res.status_int, 400) AssertionError: 200 != 400
raise self.failureException, \ (None or '%r != %r' % (200, 400))
-------------------- >> begin captured logging << -------------------- glance.store: DEBUG: Attempting to import store glance.store.filesystem.Store 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: ERROR: Could not find s3_store_host in configuration options. glance.store.base: ERROR: Failed to configure store correctly. 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: ERROR: Failed to configure store correctly. Disabling add method. glance.store: DEBUG: Registering store <class 'glance.store.swift.Store'> 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': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <class 'glance.store.swift.Store'>, '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 0x5efdd50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5efdd50>} 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 0x5efdd50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5efdd50>} iso8601.iso8601: DEBUG: Parsed 2013-11-10T06:12:01Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'01', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'10', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x24a16d0> 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'12' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'01' 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 0x5efdd50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5efdd50>} iso8601.iso8601: DEBUG: Parsed 2013-11-12T06:12:01Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'01', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'12', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x24a16d0> 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'12' for 'minute' with default None iso8601.iso8601: DEBUG: Got u'01' 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 0x5efdd50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5efdd50>} iso8601.iso8601: DEBUG: Parsed 2013-11-13T06:12:01+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'01', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'13', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x24a16d0> 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'13' 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'01' 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 0x5efdd50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5efdd50>} iso8601.iso8601: DEBUG: Parsed 2013-11-13T06:12:01-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'01', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'13', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x24a16d0> 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'13' 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'01' 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 0x5efdd50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5efdd50>} iso8601.iso8601: DEBUG: Parsed 2013-11-14T06:12:01Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'01', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'14', 'minute': u'12'} with default timezone <iso8601.iso8601.Utc object at 0x24a16d0> 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'14' 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'01' 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 0x5efdd50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5efdd50>} 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 0x5efdd50>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x5efdd50>} 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 0x24a16d0> 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 70.24 secs: [31m 14.76 TestScrubber.test_delayed_delete[0m [31m 14.15 TestGlanceControl.test_reload[0m [31m 13.90 TestGlanceControl.test_respawn[0m [31m 13.77 TestGlanceControl.test_bouncing[0m [31m 13.66 TestBinGlanceCacheManage.test_queue[0m ---------------------------------------------------------------------- Ran 932 tests in 597.530s FAILED (SKIP=89, failures=2) ERROR: InvocationError: '<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/.tox/py26/bin/nosetests'> ___________________________________ summary ____________________________________ ERROR: py26: commands failed + result=1 + echo 'Begin pip freeze output from test virtualenv:' Begin pip freeze output from test virtualenv: + echo ====================================================================== ====================================================================== + .tox/py26/bin/pip freeze Babel==1.3 Jinja2==2.7.1 MarkupSafe==0.18 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.0.8 amqp==1.3.2 anyjson==0.3.3 argparse==1.2.1 boto==2.1.1 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 glance==jenkins-periodic-glance-python26-stable-folsom-42 greenlet==0.4.1 httplib2==0.8 importlib==1.0.2 iso8601==0.1.8 jsonschema==2.3.0 kombu==3.0.4 lxml==3.2.4 mox==0.5.3 nose==1.3.0 nose-exclude==0.1.10 nosehtmloutput==0.0.5 openstack.nose-plugin==0.11 ordereddict==1.1 passlib==1.6.1 pep8==1.1 pycparser==2.10 pycrypto==2.6.1 pysendfile==2.0.0 python-swiftclient==1.8.0 pytz==2013.8 repoze.lru==0.6 requests==2.0.1 simplejson==3.3.1 sqlalchemy-migrate==0.7.2 termcolor==1.1.0 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=95 + '[' 95 -eq 0 ']' + exit 1 + rm -rf /tmp/tmp.ickBPBD4QK Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python26-stable-folsom/42/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python26-stable-folsom/centos6-4/42 [SCP] Copying console log.
See <https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/43/> ------------------------------------------ [...truncated 1275 lines...] action, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 63, in wrapped return func(self, *args, **kwargs) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 444, in do_request headers=headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 80, in wrapped return func(self, method, url, body, headers) File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/common/client.py",> line 574, in _do_request raise exception.Invalid(res.read()) Invalid: 400 Bad Request The server could not comply with the request since it is either malformed or otherwise incorrect. Unrecognized changes-since value 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 0x52e86d0>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x52e86d0>} 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 0x54372d0>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x54372d0>} 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 0x26046d0> 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-71a10557-2a6a-45d8-8cbf-7d34d657f1ee --------------------- >> end captured logging << --------------------- ====================================================================== FAIL: Tests that the /images/detail registry API returns list of ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/glance/tests/unit/v1/test_api.py",> line 1409, in test_get_details_filter_changes_since self.assertEquals(res.status_int, 400) AssertionError: 200 != 400
raise self.failureException, \ (None or '%r != %r' % (200, 400))
-------------------- >> begin captured logging << -------------------- glance.store: DEBUG: Attempting to import store glance.store.filesystem.Store 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: ERROR: Could not find s3_store_host in configuration options. glance.store.base: ERROR: Failed to configure store correctly. 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: ERROR: Failed to configure store correctly. Disabling add method. glance.store: DEBUG: Registering store <class 'glance.store.swift.Store'> 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': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift+http with {'store_class': <class 'glance.store.swift.Store'>, 'location_class': <class 'glance.store.swift.StoreLocation'>} glance.store.location: DEBUG: Registering scheme swift with {'store_class': <class 'glance.store.swift.Store'>, '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 0x61c4090>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x61c4090>} 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 0x61c4090>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x61c4090>} iso8601.iso8601: DEBUG: Parsed 2013-11-11T06:14:49Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'49', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'11', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x26046d0> 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'49' 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 0x61c4090>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x61c4090>} iso8601.iso8601: DEBUG: Parsed 2013-11-13T06:14:49Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'49', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'13', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x26046d0> 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'49' 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 0x61c4090>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x61c4090>} iso8601.iso8601: DEBUG: Parsed 2013-11-14T06:14:49+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'49', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'14', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x26046d0> 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'14' 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'49' 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 0x61c4090>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x61c4090>} iso8601.iso8601: DEBUG: Parsed 2013-11-14T06:14:49-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'49', 'tz_minute': u'00', 'year': u'2013', 'separator': u'T', 'day': u'14', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x26046d0> 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'14' 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'49' 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 0x61c4090>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x61c4090>} iso8601.iso8601: DEBUG: Parsed 2013-11-15T06:14:49Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'06', 'tz_hour': None, 'month': u'11', 'timezone': u'Z', 'second': u'49', 'tz_minute': None, 'year': u'2013', 'separator': u'T', 'day': u'15', 'minute': u'14'} with default timezone <iso8601.iso8601.Utc object at 0x26046d0> 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'15' 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'49' 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 0x61c4090>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x61c4090>} 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 0x61c4090>} routes.middleware: DEBUG: Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x61c4090>} 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 0x26046d0> 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 82.96 secs: [31m 17.84 TestBinGlanceCacheManage.test_queue[0m [31m 16.62 TestGlanceControl.test_bouncing[0m [31m 16.61 TestBinGlance.test_results_filtering[0m [31m 16.02 TestRootApi.test_version_configurations[0m [31m 15.87 TestScrubber.test_delayed_delete[0m ---------------------------------------------------------------------- Ran 932 tests in 745.967s FAILED (SKIP=89, failures=2) ERROR: InvocationError: '<https://jenkins02.openstack.org/job/periodic-glance-python26-stable-folsom/ws/.tox/py26/bin/nosetests'> ___________________________________ summary ____________________________________ ERROR: py26: commands failed + result=1 + echo 'Begin pip freeze output from test virtualenv:' Begin pip freeze output from test virtualenv: + echo ====================================================================== ====================================================================== + .tox/py26/bin/pip freeze Babel==1.3 Jinja2==2.7.1 MarkupSafe==0.18 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.0.8 amqp==1.3.3 anyjson==0.3.3 argparse==1.2.1 boto==2.1.1 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 glance==jenkins-periodic-glance-python26-stable-folsom-43 greenlet==0.4.1 httplib2==0.8 importlib==1.0.2 iso8601==0.1.8 jsonschema==2.3.0 kombu==3.0.4 lxml==3.2.4 mox==0.5.3 nose==1.3.0 nose-exclude==0.1.10 nosehtmloutput==0.0.5 openstack.nose-plugin==0.11 ordereddict==1.1 passlib==1.6.1 pep8==1.1 pycparser==2.10 pycrypto==2.6.1 pysendfile==2.0.0 python-swiftclient==1.8.0 pytz==2013.8 repoze.lru==0.6 requests==2.0.1 simplejson==3.3.1 sqlalchemy-migrate==0.7.2 termcolor==1.1.0 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=95 + '[' 95 -eq 0 ']' + exit 1 + rm -rf /tmp/tmp.SnebaXFcy1 Build step 'Execute shell' marked build as failure [SCP] Connecting to static.openstack.org [SCP] uploading file: '/srv/static/logs/periodic/periodic-glance-python26-stable-folsom/43/nose_results.html' [SCP] Connecting to static.openstack.org [SCP] Trying to create /srv/static/logs/periodic/periodic-glance-python26-stable-folsom/centos6-2/43 [SCP] Copying console log.
participants (1)
-
OpenStack Jenkins