$ ./run_tests.sh -d crash just after tests starts Here is a log
# ./run_tests.sh -d No virtual environment found...create one? (Y/n) n tail: run_tests.log: file truncated Tests running... 2015-06-17 08:25:56,817 DEBUG ******************************************************************************** 2015-06-17 08:25:56,818 DEBUG Configuration options gathered from: 2015-06-17 08:25:56,818 DEBUG command line args: ['--config-file', '/tmp/tmpQS0vW7/etc/registry.conf'] 2015-06-17 08:25:56,819 DEBUG config files: ['/tmp/tmpQS0vW7/etc/registry.conf'] 2015-06-17 08:25:56,819 DEBUG ================================================================================ 2015-06-17 08:25:56,820 DEBUG admin_role = admin 2015-06-17 08:25:56,820 DEBUG allow_additional_image_properties = True 2015-06-17 08:25:56,820 DEBUG allow_anonymous_access = False 2015-06-17 08:25:56,821 DEBUG allowed_rpc_exception_modules = ['openstack.common.exception', 'glance.common.exception', 'exceptions'] 2015-06-17 08:25:56,821 DEBUG api_limit_max = 1000 2015-06-17 08:25:56,821 DEBUG backlog = 4096 2015-06-17 08:25:56,822 DEBUG bind_host = 127.0.0.1 2015-06-17 08:25:56,823 DEBUG bind_port = 45856 2015-06-17 08:25:56,823 DEBUG ca_file = None 2015-06-17 08:25:56,823 DEBUG cert_file = None 2015-06-17 08:25:56,824 DEBUG config_dir = None 2015-06-17 08:25:56,824 DEBUG config_file = ['/tmp/tmpQS0vW7/etc/registry.conf'] 2015-06-17 08:25:56,824 DEBUG data_api = glance.db.sqlalchemy.api 2015-06-17 08:25:56,825 DEBUG debug = True 2015-06-17 08:25:56,825 DEBUG default_log_levels = ['amqp=WARN', 'amqplib=WARN', 'boto=WARN', 'qpid=WARN', 'sqlalchemy=WARN', 'suds=INFO', 'oslo.messaging=INFO', 'iso8601=WARN', 'requests.packages.urllib3.connectionpool=WARN', 'urllib3.connectionpool=WARN', 'websocket=WARN', 'requests.packages.urllib3.util.retry=WARN', 'urllib3.util.retry=WARN', 'keystonemiddleware=WARN', 'routes.middleware=WARN', 'stevedore=WARN'] 2015-06-17 08:25:56,826 DEBUG default_publisher_id = image.localhost 2015-06-17 08:25:56,826 DEBUG digest_algorithm = sha1 2015-06-17 08:25:56,826 DEBUG disabled_notifications = [] 2015-06-17 08:25:56,827 DEBUG enable_v1_api = True 2015-06-17 08:25:56,827 DEBUG enable_v1_registry = True 2015-06-17 08:25:56,827 DEBUG enable_v2_api = True 2015-06-17 08:25:56,828 DEBUG enable_v2_registry = True 2015-06-17 08:25:56,828 DEBUG fatal_deprecations = False 2015-06-17 08:25:56,828 DEBUG http_keepalive = True 2015-06-17 08:25:56,829 DEBUG image_location_quota = 10 2015-06-17 08:25:56,829 DEBUG image_member_quota = 128 2015-06-17 08:25:56,830 DEBUG image_property_quota = 128 2015-06-17 08:25:56,830 DEBUG image_size_cap = 1099511627776 2015-06-17 08:25:56,830 DEBUG image_tag_quota = 128 2015-06-17 08:25:56,831 DEBUG instance_format = [instance: %(uuid)s] 2015-06-17 08:25:56,831 DEBUG instance_uuid_format = [instance: %(uuid)s] 2015-06-17 08:25:56,831 DEBUG key_file = None 2015-06-17 08:25:56,832 DEBUG limit_param_default = 25 2015-06-17 08:25:56,832 DEBUG location_strategy = location_order 2015-06-17 08:25:56,832 DEBUG log_config_append = None 2015-06-17 08:25:56,833 DEBUG log_date_format = %Y-%m-%d %H:%M:%S 2015-06-17 08:25:56,833 DEBUG log_dir = None 2015-06-17 08:25:56,834 DEBUG log_file = /tmp/tmpQS0vW7/registry.log 2015-06-17 08:25:56,834 DEBUG log_format = None 2015-06-17 08:25:56,834 DEBUG logging_context_format_string = %(asctime)s.%(msecs)03d %(process)d %(levelname)s %(name)s [%(request_id)s %(user_identity)s] %(instance)s%(message)s 2015-06-17 08:25:56,835 DEBUG logging_debug_format_suffix = %(funcName)s %(pathname)s:%(lineno)d 2015-06-17 08:25:56,835 DEBUG logging_default_format_string = %(asctime)s.%(msecs)03d %(process)d %(levelname)s %(name)s [-] %(instance)s%(message)s 2015-06-17 08:25:56,835 DEBUG logging_exception_prefix = %(asctime)s.%(msecs)03d %(process)d TRACE %(name)s %(instance)s 2015-06-17 08:25:56,836 DEBUG max_header_line = 16384 2015-06-17 08:25:56,836 DEBUG metadata_encryption_key = **** 2015-06-17 08:25:56,836 DEBUG owner_is_tenant = True 2015-06-17 08:25:56,837 DEBUG property_protection_file = None 2015-06-17 08:25:56,837 DEBUG property_protection_rule_format = roles 2015-06-17 08:25:56,837 DEBUG publish_errors = False 2015-06-17 08:25:56,838 DEBUG pydev_worker_debug_host = None 2015-06-17 08:25:56,838 DEBUG pydev_worker_debug_port = 5678 2015-06-17 08:25:56,839 DEBUG registry_host = 0.0.0.0 2015-06-17 08:25:56,839 DEBUG registry_port = 9191 2015-06-17 08:25:56,839 DEBUG show_image_direct_url = False 2015-06-17 08:25:56,840 DEBUG show_multiple_locations = False 2015-06-17 08:25:56,840 DEBUG syslog_log_facility = LOG_USER 2015-06-17 08:25:56,840 DEBUG tcp_keepidle = 600 2015-06-17 08:25:56,841 DEBUG use_stderr = True 2015-06-17 08:25:56,841 DEBUG use_syslog = False 2015-06-17 08:25:56,841 DEBUG use_syslog_rfc_format = False 2015-06-17 08:25:56,842 DEBUG user_storage_quota = 0 2015-06-17 08:25:56,842 DEBUG verbose = True 2015-06-17 08:25:56,842 DEBUG workers = 0 2015-06-17 08:25:56,843 DEBUG store_type_location_strategy.store_type_preference = [] 2015-06-17 08:25:56,843 DEBUG task.task_executor = taskflow 2015-06-17 08:25:56,843 DEBUG task.task_time_to_live = 48 2015-06-17 08:25:56,844 DEBUG task.work_dir = None 2015-06-17 08:25:56,844 DEBUG paste_deploy.config_file = None 2015-06-17 08:25:56,844 DEBUG paste_deploy.flavor = fakeauth 2015-06-17 08:25:56,845 DEBUG profiler.enabled = False 2015-06-17 08:25:56,845 DEBUG profiler.trace_sqlalchemy = False 2015-06-17 08:25:56,846 DEBUG oslo_concurrency.disable_process_locking = False 2015-06-17 08:25:56,846 DEBUG oslo_concurrency.lock_path = /tmp 2015-06-17 08:25:56,846 DEBUG image_format.container_formats = ['ami', 'ari', 'aki', 'bare', 'ovf', 'ova'] 2015-06-17 08:25:56,847 DEBUG image_format.disk_formats = ['ami', 'ari', 'aki', 'vhd', 'vmdk', 'raw', 'qcow2', 'vdi', 'iso'] 2015-06-17 08:25:56,847 DEBUG oslo_policy.policy_default_rule = default 2015-06-17 08:25:56,847 DEBUG oslo_policy.policy_dirs = ['policy.d'] 2015-06-17 08:25:56,848 DEBUG oslo_policy.policy_file = /tmp/tmpQS0vW7/etc/policy.json 2015-06-17 08:25:56,848 DEBUG ******************************************************************************** 2015-06-17 08:25:56,849 INFO Starting single process server 2015-06-17 08:25:56,849 INFO (1341) wsgi starting up on http://127.0.0.1:45856/ 2015-06-17 08:25:56,883 DEBUG Reloading cached file /tmp/tmpQS0vW7/etc/policy.json 2015-06-17 08:25:56,883 DEBUG Reloaded policy file: /tmp/tmpQS0vW7/etc/policy.json 2015-06-17 08:25:56,891 WARNING Option "sql_connection" from group "DEFAULT" is deprecated. Use option "connection" from group "database". 2015-06-17 08:25:56,891 WARNING Option "sql_idle_timeout" from group "DEFAULT" is deprecated. Use option "idle_timeout" from group "database". 2015-06-17 08:25:57,024 INFO 127.0.0.1 - - [17/Jun/2015 08:25:57] "POST /rpc HTTP/1.1" 200 1353 0.141776 2015-06-17 08:25:57,026 DEBUG Reloaded policy file: /tmp/tmpQS0vW7/etc/policy.json 2015-06-17 08:25:57,047 INFO 127.0.0.1 - - [17/Jun/2015 08:25:57] "POST /rpc HTTP/1.1" 200 782 0.021621 2015-06-17 08:25:57,048 DEBUG Reloaded policy file: /tmp/tmpQS0vW7/etc/policy.json 2015-06-17 08:25:57,117 INFO 127.0.0.1 - - [17/Jun/2015 08:25:57] "POST /rpc HTTP/1.1" 200 782 0.069991 2015-06-17 08:25:57,119 DEBUG Reloaded policy file: /tmp/tmpQS0vW7/etc/policy.json 2015-06-17 08:25:57,120 DEBUG Reloaded policy file: /tmp/tmpQS0vW7/etc/policy.json 2015-06-17 08:25:57,129 ERROR RPC Call Error: Url can't contain 4 byte unicode characters. Traceback (most recent call last): File "glance/common/rpc.py", line 180, in __call__ result = method(req.context, **kwargs) File "glance/db/sqlalchemy/api.py", line 129, in image_create return _image_update(context, values, None, purge_props=False) File "/usr/lib/python2.7/site-packages/retrying.py", line 49, in wrapped_f return Retrying(*dargs, **dkw).call(f, *args, **kw) File "/usr/lib/python2.7/site-packages/retrying.py", line 206, in call return attempt.get(self._wrap_exception) File "/usr/lib/python2.7/site-packages/retrying.py", line 247, in get six.reraise(self.value[0], self.value[1], self.value[2]) File "/usr/lib/python2.7/site-packages/retrying.py", line 200, in call attempt = Attempt(fn(*args, **kwargs), attempt_number, False) File "glance/common/utils.py", line 712, in wrapper return f(*args, **kwargs) File "glance/db/sqlalchemy/api.py", line 795, in _image_update session=session) File "glance/db/sqlalchemy/api.py", line 886, in _image_locations_set image_location_add(context, image_id, loc, session=session) File "glance/common/utils.py", line 703, in wrapper _check_dict(data_dict) File "glance/common/utils.py", line 700, in _check_dict raise exception.Invalid(msg) Invalid: Url can't contain 4 byte unicode characters.
2015-06-17 08:25:57,131 INFO 127.0.0.1 - - [17/Jun/2015 08:25:57] "POST /rpc HTTP/1.1" 200 305 0.012410
$ ./run_tests.sh -d crash just after tests starts Here is a log
# ./run_tests.sh -d ******* ******* ******* ******* ******* ******* ******* ******* ******* ******* *** /etc/registry. conf'] tmpQS0vW7/ etc/registry. conf'] ======= ======= ======= ======= ======= ======= ======= ======= ======= ======= === l_image_ properties = True _access = False rpc_exception_ modules = ['openstack. common. exception' , 'glance. common. exception' , 'exceptions'] tmpQS0vW7/ etc/registry. conf'] db.sqlalchemy. api =INFO', 'iso8601=WARN', 'requests. packages. urllib3. connectionpool= WARN', 'urllib3. connectionpool= WARN', 'websocket=WARN', 'requests. packages. urllib3. util.retry= WARN', 'urllib3. util.retry= WARN', 'keystonemiddle ware=WARN' , 'routes. middleware= WARN', 'stevedore=WARN'] publisher_ id = image.localhost notifications = [] quota = 10 quota = 128 uuid_format = [instance: %(uuid)s] registry. log context_ format_ string = %(asctime) s.%(msecs) 03d %(process)d %(levelname)s %(name)s [%(request_id)s %(user_identity)s] %(instance) s%(message) s debug_format_ suffix = %(funcName)s %(pathname) s:%(lineno) d default_ format_ string = %(asctime) s.%(msecs) 03d %(process)d %(levelname)s %(name)s [-] %(instance) s%(message) s exception_ prefix = %(asctime) s.%(msecs) 03d %(process)d TRACE %(name)s %(instance)s encryption_ key = **** protection_ file = None protection_ rule_format = roles debug_host = None debug_port = 5678 direct_ url = False locations = False rfc_format = False location_ strategy. store_type_ preference = [] time_to_ live = 48 config_ file = None trace_sqlalchem y = False y.disable_ process_ locking = False y.lock_ path = /tmp container_ formats = ['ami', 'ari', 'aki', 'bare', 'ovf', 'ova'] disk_formats = ['ami', 'ari', 'aki', 'vhd', 'vmdk', 'raw', 'qcow2', 'vdi', 'iso'] policy_ default_ rule = default policy_ dirs = ['policy.d'] policy_ file = /tmp/tmpQS0vW7/ etc/policy. json ******* ******* ******* ******* ******* ******* ******* ******* ******* ******* *** 127.0.0. 1:45856/ etc/policy. json etc/policy. json etc/policy. json etc/policy. json etc/policy. json etc/policy. json common/ rpc.py" , line 180, in __call__ db/sqlalchemy/ api.py" , line 129, in image_create update( context, values, None, purge_props=False) python2. 7/site- packages/ retrying. py", line 49, in wrapped_f python2. 7/site- packages/ retrying. py", line 206, in call get(self. _wrap_exception ) python2. 7/site- packages/ retrying. py", line 247, in get reraise( self.value[ 0], self.value[1], self.value[2]) python2. 7/site- packages/ retrying. py", line 200, in call common/ utils.py" , line 712, in wrapper db/sqlalchemy/ api.py" , line 795, in _image_update session) db/sqlalchemy/ api.py" , line 886, in _image_ locations_ set location_ add(context, image_id, loc, session=session) common/ utils.py" , line 703, in wrapper dict(data_ dict) common/ utils.py" , line 700, in _check_dict Invalid( msg)
No virtual environment found...create one? (Y/n) n
tail: run_tests.log: file truncated
Tests running...
2015-06-17 08:25:56,817 DEBUG *******
2015-06-17 08:25:56,818 DEBUG Configuration options gathered from:
2015-06-17 08:25:56,818 DEBUG command line args: ['--config-file', '/tmp/tmpQS0vW7
2015-06-17 08:25:56,819 DEBUG config files: ['/tmp/
2015-06-17 08:25:56,819 DEBUG =======
2015-06-17 08:25:56,820 DEBUG admin_role = admin
2015-06-17 08:25:56,820 DEBUG allow_additiona
2015-06-17 08:25:56,820 DEBUG allow_anonymous
2015-06-17 08:25:56,821 DEBUG allowed_
2015-06-17 08:25:56,821 DEBUG api_limit_max = 1000
2015-06-17 08:25:56,821 DEBUG backlog = 4096
2015-06-17 08:25:56,822 DEBUG bind_host = 127.0.0.1
2015-06-17 08:25:56,823 DEBUG bind_port = 45856
2015-06-17 08:25:56,823 DEBUG ca_file = None
2015-06-17 08:25:56,823 DEBUG cert_file = None
2015-06-17 08:25:56,824 DEBUG config_dir = None
2015-06-17 08:25:56,824 DEBUG config_file = ['/tmp/
2015-06-17 08:25:56,824 DEBUG data_api = glance.
2015-06-17 08:25:56,825 DEBUG debug = True
2015-06-17 08:25:56,825 DEBUG default_log_levels = ['amqp=WARN', 'amqplib=WARN', 'boto=WARN', 'qpid=WARN', 'sqlalchemy=WARN', 'suds=INFO', 'oslo.messaging
2015-06-17 08:25:56,826 DEBUG default_
2015-06-17 08:25:56,826 DEBUG digest_algorithm = sha1
2015-06-17 08:25:56,826 DEBUG disabled_
2015-06-17 08:25:56,827 DEBUG enable_v1_api = True
2015-06-17 08:25:56,827 DEBUG enable_v1_registry = True
2015-06-17 08:25:56,827 DEBUG enable_v2_api = True
2015-06-17 08:25:56,828 DEBUG enable_v2_registry = True
2015-06-17 08:25:56,828 DEBUG fatal_deprecations = False
2015-06-17 08:25:56,828 DEBUG http_keepalive = True
2015-06-17 08:25:56,829 DEBUG image_location_
2015-06-17 08:25:56,829 DEBUG image_member_quota = 128
2015-06-17 08:25:56,830 DEBUG image_property_
2015-06-17 08:25:56,830 DEBUG image_size_cap = 1099511627776
2015-06-17 08:25:56,830 DEBUG image_tag_quota = 128
2015-06-17 08:25:56,831 DEBUG instance_format = [instance: %(uuid)s]
2015-06-17 08:25:56,831 DEBUG instance_
2015-06-17 08:25:56,831 DEBUG key_file = None
2015-06-17 08:25:56,832 DEBUG limit_param_default = 25
2015-06-17 08:25:56,832 DEBUG location_strategy = location_order
2015-06-17 08:25:56,832 DEBUG log_config_append = None
2015-06-17 08:25:56,833 DEBUG log_date_format = %Y-%m-%d %H:%M:%S
2015-06-17 08:25:56,833 DEBUG log_dir = None
2015-06-17 08:25:56,834 DEBUG log_file = /tmp/tmpQS0vW7/
2015-06-17 08:25:56,834 DEBUG log_format = None
2015-06-17 08:25:56,834 DEBUG logging_
2015-06-17 08:25:56,835 DEBUG logging_
2015-06-17 08:25:56,835 DEBUG logging_
2015-06-17 08:25:56,835 DEBUG logging_
2015-06-17 08:25:56,836 DEBUG max_header_line = 16384
2015-06-17 08:25:56,836 DEBUG metadata_
2015-06-17 08:25:56,836 DEBUG owner_is_tenant = True
2015-06-17 08:25:56,837 DEBUG property_
2015-06-17 08:25:56,837 DEBUG property_
2015-06-17 08:25:56,837 DEBUG publish_errors = False
2015-06-17 08:25:56,838 DEBUG pydev_worker_
2015-06-17 08:25:56,838 DEBUG pydev_worker_
2015-06-17 08:25:56,839 DEBUG registry_host = 0.0.0.0
2015-06-17 08:25:56,839 DEBUG registry_port = 9191
2015-06-17 08:25:56,839 DEBUG show_image_
2015-06-17 08:25:56,840 DEBUG show_multiple_
2015-06-17 08:25:56,840 DEBUG syslog_log_facility = LOG_USER
2015-06-17 08:25:56,840 DEBUG tcp_keepidle = 600
2015-06-17 08:25:56,841 DEBUG use_stderr = True
2015-06-17 08:25:56,841 DEBUG use_syslog = False
2015-06-17 08:25:56,841 DEBUG use_syslog_
2015-06-17 08:25:56,842 DEBUG user_storage_quota = 0
2015-06-17 08:25:56,842 DEBUG verbose = True
2015-06-17 08:25:56,842 DEBUG workers = 0
2015-06-17 08:25:56,843 DEBUG store_type_
2015-06-17 08:25:56,843 DEBUG task.task_executor = taskflow
2015-06-17 08:25:56,843 DEBUG task.task_
2015-06-17 08:25:56,844 DEBUG task.work_dir = None
2015-06-17 08:25:56,844 DEBUG paste_deploy.
2015-06-17 08:25:56,844 DEBUG paste_deploy.flavor = fakeauth
2015-06-17 08:25:56,845 DEBUG profiler.enabled = False
2015-06-17 08:25:56,845 DEBUG profiler.
2015-06-17 08:25:56,846 DEBUG oslo_concurrenc
2015-06-17 08:25:56,846 DEBUG oslo_concurrenc
2015-06-17 08:25:56,846 DEBUG image_format.
2015-06-17 08:25:56,847 DEBUG image_format.
2015-06-17 08:25:56,847 DEBUG oslo_policy.
2015-06-17 08:25:56,847 DEBUG oslo_policy.
2015-06-17 08:25:56,848 DEBUG oslo_policy.
2015-06-17 08:25:56,848 DEBUG *******
2015-06-17 08:25:56,849 INFO Starting single process server
2015-06-17 08:25:56,849 INFO (1341) wsgi starting up on http://
2015-06-17 08:25:56,883 DEBUG Reloading cached file /tmp/tmpQS0vW7/
2015-06-17 08:25:56,883 DEBUG Reloaded policy file: /tmp/tmpQS0vW7/
2015-06-17 08:25:56,891 WARNING Option "sql_connection" from group "DEFAULT" is deprecated. Use option "connection" from group "database".
2015-06-17 08:25:56,891 WARNING Option "sql_idle_timeout" from group "DEFAULT" is deprecated. Use option "idle_timeout" from group "database".
2015-06-17 08:25:57,024 INFO 127.0.0.1 - - [17/Jun/2015 08:25:57] "POST /rpc HTTP/1.1" 200 1353 0.141776
2015-06-17 08:25:57,026 DEBUG Reloaded policy file: /tmp/tmpQS0vW7/
2015-06-17 08:25:57,047 INFO 127.0.0.1 - - [17/Jun/2015 08:25:57] "POST /rpc HTTP/1.1" 200 782 0.021621
2015-06-17 08:25:57,048 DEBUG Reloaded policy file: /tmp/tmpQS0vW7/
2015-06-17 08:25:57,117 INFO 127.0.0.1 - - [17/Jun/2015 08:25:57] "POST /rpc HTTP/1.1" 200 782 0.069991
2015-06-17 08:25:57,119 DEBUG Reloaded policy file: /tmp/tmpQS0vW7/
2015-06-17 08:25:57,120 DEBUG Reloaded policy file: /tmp/tmpQS0vW7/
2015-06-17 08:25:57,129 ERROR RPC Call Error: Url can't contain 4 byte unicode characters.
Traceback (most recent call last):
File "glance/
result = method(req.context, **kwargs)
File "glance/
return _image_
File "/usr/lib/
return Retrying(*dargs, **dkw).call(f, *args, **kw)
File "/usr/lib/
return attempt.
File "/usr/lib/
six.
File "/usr/lib/
attempt = Attempt(fn(*args, **kwargs), attempt_number, False)
File "glance/
return f(*args, **kwargs)
File "glance/
session=
File "glance/
image_
File "glance/
_check_
File "glance/
raise exception.
Invalid: Url can't contain 4 byte unicode characters.
2015-06-17 08:25:57,131 INFO 127.0.0.1 - - [17/Jun/2015 08:25:57] "POST /rpc HTTP/1.1" 200 305 0.012410