Comment 6 for bug 1465188

ranjan kumar (ranjankumar188) wrote :

 $ ./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