gistfile1.txt

2015-08-24 14:02:30.148 DEBUG cinder.scheduler.host_manager [req-239968ab-992f-47fd-8179-2ed62ada4d56 None None] Received volume service update from storage1@hnas-iscsi: {u'filter_function': None, u'goodness_function': None, u'driver_version': u'3.3.0', u'volume_backend_name': u'hnas-iscsi', u'reserved_percentage': 0, u'pools': [{u'pool_name': u'default', u'service_label': u'default', u'QoS_support': u'False', u'hdp': u'FS-ErlonDefault', u'allocated_capacity_gb': 0, u'free_capacity_gb': 197, u'total_capacity_gb': 200, u'reserved_percentage': 0}], u'vendor_name': u'HDS', u'storage_protocol': u'iSCSI'} from (pid=1891) update_service_capabilities /opt/stack/cinder/cinder/scheduler/host_manager.py:450
2015-08-24 14:02:35.104 DEBUG oslo_service.loopingcall [req-b3e5ad64-97c8-43d8-98a1-54984f8dc43f None None] Fixed interval looping call <bound method Service.report_state of <cinder.service.Service object at 0x7f1357d0e6d0>> sleeping for 9.96 seconds from (pid=1891) _run_loop /usr/local/lib/python2.7/dist-packages/oslo_service/loopingcall.py:117
2015-08-24 14:02:45.167 DEBUG oslo_service.loopingcall [req-4e16fe5b-f552-4214-84ae-ed4cd3fa1ef8 None None] Fixed interval looping call <bound method Service.report_state of <cinder.service.Service object at 0x7f1357d0e6d0>> sleeping for 9.90 seconds from (pid=1891) _run_loop /usr/local/lib/python2.7/dist-packages/oslo_service/loopingcall.py:117
2015-08-24 14:02:55.104 DEBUG oslo_service.loopingcall [req-a1ca3927-6480-438e-8290-cd63bcf38b06 None None] Fixed interval looping call <bound method Service.report_state of <cinder.service.Service object at 0x7f1357d0e6d0>> sleeping for 9.97 seconds from (pid=1891) _run_loop /usr/local/lib/python2.7/dist-packages/oslo_service/loopingcall.py:117
2015-08-24 14:02:56.531 DEBUG cinder.scheduler.host_manager [req-94f6e267-c62c-4427-80e4-3afa6d763101 None None] Received volume service update from storage2@hnas-iscsi: {u'filter_function': None, u'goodness_function': None, u'driver_version': u'3.3.0', u'volume_backend_name': u'hnas-iscsi', u'reserved_percentage': 0, u'pools': [{u'pool_name': u'default', u'service_label': u'default', u'QoS_support': u'False', u'hdp': u'FS-ErlonDefault', u'allocated_capacity_gb': 0, u'free_capacity_gb': 197, u'total_capacity_gb': 200, u'reserved_percentage': 0}], u'vendor_name': u'HDS', u'storage_protocol': u'iSCSI'} from (pid=1891) update_service_capabilities /opt/stack/cinder/cinder/scheduler/host_manager.py:450
2015-08-24 14:02:56.729 DEBUG cinder.scheduler.host_manager [req-f12be3a9-067a-472b-9785-c8b845585e68 None None] Received volume service update from storage2@hus110: {u'filter_function': None, u'QoS_support': False, u'volume_backend_name': u'hus110', u'free_capacity_gb': 4748, u'driver_version': u'1.1.0', u'total_capacity_gb': 5725, u'reserved_percentage': 0, u'goodness_function': None, u'vendor_name': u'Hitachi', u'storage_protocol': u'iSCSI'} from (pid=1891) update_service_capabilities /opt/stack/cinder/cinder/scheduler/host_manager.py:450

2015-08-24 14:03:05.152 DEBUG oslo_service.loopingcall [req-f8ec31bd-584a-4b98-bd9b-ec5b900e25c7 None None] Fixed interval looping call <bound method Service.report_state of <cinder.service.Service object at 0x7f1357d0e6d0>> sleeping for 9.92 seconds from (pid=1891) _run_loop /usr/local/lib/python2.7/dist-packages/oslo_service/loopingcall.py:117
2015-08-24 14:03:06.032 DEBUG cinder.scheduler.manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] Flow 'volume_create_scheduler' (c14bcad4-f5c7-4124-896e-9b79789a4679) transitioned into state 'RUNNING' from state 'PENDING' from (pid=1891) _flow_receiver /usr/local/lib/python2.7/dist-packages/taskflow/listeners/logging.py:151
2015-08-24 14:03:06.034 DEBUG cinder.scheduler.manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] Task 'cinder.scheduler.flows.create_volume.ExtractSchedulerSpecTask;volume:create' (f66a9066-5929-461b-9e5b-8d4de40b7f4c) transitioned into state 'RUNNING' from state 'PENDING' from (pid=1891) _task_receiver /usr/local/lib/python2.7/dist-packages/taskflow/listeners/logging.py:190
2015-08-24 14:03:06.036 DEBUG cinder.scheduler.manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] Task 'cinder.scheduler.flows.create_volume.ExtractSchedulerSpecTask;volume:create' (f66a9066-5929-461b-9e5b-8d4de40b7f4c) transitioned into state 'SUCCESS' from state 'RUNNING' with result '{'request_spec': {u'source_replicaid': None, u'volume_properties': {u'volume_metadata': [], u'availability_zone': u'nova', u'reservations': [u'661d4148-0790-45c2-a7c0-5663d8c8cd5d', u'bd4764a6-cee4-43d4-9344-15048124bc9b', u'8dbf235d-c989-4ba4-bab9-31c281cb9e58', u'ea8cddf1-7860-439c-abdb-0179ca2c8fca'], u'replication_status': u'disabled', u'snapshot_id': None, u'id': u'4d5f49f6-d19b-4363-accc-590d651d863a', u'size': 1, u'display_name': u'v', u'source_replicaid': None, u'display_description': u'', u'volume_admin_metadata': [], u'project_id': u'8076c18994b44689b70746e09bf15b20', u'metadata': {}, u'status': u'creating', u'volume_type_id': u'2410af91-b377-4e07-b15f-68df320f9674', u'multiattach': False, u'source_volid': None, u'consistencygroup_id': None, u'user_id': u'9d13f6debb7f48c0a94e7b5772f9e3bc', u'attach_status': u'detached', u'cgsnapshot_id': None, u'encryption_key_id': None}, u'source_volid': None, u'cgsnapshot_id': None, u'image_id': None, u'snapshot_id': None, u'consistencygroup_id': None, u'volume_type': {u'name': u'hus110', u'qos_specs_id': None, u'deleted': False, u'created_at': u'2015-08-24T16:35:12.000000', u'updated_at': None, u'extra_specs': {u'volume_backend_name': u'hus110'}, u'is_public': True, u'deleted_at': None, u'id': u'2410af91-b377-4e07-b15f-68df320f9674', u'description': None}, u'volume_id': u'4d5f49f6-d19b-4363-accc-590d651d863a'}}' from (pid=1891) _task_receiver /usr/local/lib/python2.7/dist-packages/taskflow/listeners/logging.py:178
2015-08-24 14:03:06.038 DEBUG cinder.scheduler.manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] Task 'cinder.scheduler.flows.create_volume.ScheduleCreateVolumeTask;volume:create' (15b6c194-15cf-40c7-8826-d60b4122781b) transitioned into state 'RUNNING' from state 'PENDING' from (pid=1891) _task_receiver /usr/local/lib/python2.7/dist-packages/taskflow/listeners/logging.py:190
2015-08-24 14:03:06.046 WARNING cinder.scheduler.host_manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] volume service is down. (host: storage1@lvmdriver-1)
2015-08-24 14:03:06.046 WARNING cinder.scheduler.host_manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] volume service is down. (host: storage2@lvmdriver-1)
2015-08-24 14:03:06.047 WARNING cinder.scheduler.host_manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] volume service is down. (host: controller@lvmdriver-1)
2015-08-24 14:03:06.047 WARNING cinder.scheduler.host_manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] volume service is down. (host: controller@hnas-iscsi)
2015-08-24 14:03:06.048 WARNING cinder.scheduler.host_manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] volume service is down. (host: storage1@hnas-iscsi)
2015-08-24 14:03:06.048 WARNING cinder.scheduler.host_manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] volume service is down. (host: storage2@hnas-iscsi)
2015-08-24 14:03:06.049 WARNING cinder.scheduler.host_manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] volume service is down. (host: storage1@hus110)
2015-08-24 14:03:06.049 WARNING cinder.scheduler.host_manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] volume service is down. (host: storage2@hus110)
2015-08-24 14:03:06.049 DEBUG cinder.openstack.common.scheduler.base_filter [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] Starting with 0 host(s) from (pid=1891) get_filtered_objects /opt/stack/cinder/cinder/openstack/common/scheduler/base_filter.py:77
2015-08-24 14:03:06.050 INFO cinder.openstack.common.scheduler.base_filter [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] Filter AvailabilityZoneFilter returned 0 host(s)
2015-08-24 14:03:06.050 WARNING cinder.scheduler.filter_scheduler [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] No weighed hosts found for volume with properties: {u'name': u'hus110', u'qos_specs_id': None, u'deleted': False, u'created_at': u'2015-08-24T16:35:12.000000', u'updated_at': None, u'extra_specs': {u'volume_backend_name': u'hus110'}, u'is_public': True, u'deleted_at': None, u'id': u'2410af91-b377-4e07-b15f-68df320f9674', u'description': None}
2015-08-24 14:03:06.053 ERROR cinder.scheduler.flows.create_volume [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] Failed to run task cinder.scheduler.flows.create_volume.ScheduleCreateVolumeTask;volume:create: No valid host was found. No weighed hosts available
2015-08-24 14:03:06.054 DEBUG cinder.volume.flows.common [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] Updating volume: 4d5f49f6-d19b-4363-accc-590d651d863a with {'status': 'error'} due to: No valid host was found. No weighed hosts available from (pid=1891) error_out_volume /opt/stack/cinder/cinder/volume/flows/common.py:89
2015-08-24 14:03:06.186 DEBUG cinder.scheduler.manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] Task 'cinder.scheduler.flows.create_volume.ScheduleCreateVolumeTask;volume:create' (15b6c194-15cf-40c7-8826-d60b4122781b) transitioned into state 'SUCCESS' from state 'RUNNING' with result 'None' from (pid=1891) _task_receiver /usr/local/lib/python2.7/dist-packages/taskflow/listeners/logging.py:178
2015-08-24 14:03:06.189 DEBUG cinder.scheduler.manager [req-83f1df08-8965-46a4-9ba6-1111da290509 9d13f6debb7f48c0a94e7b5772f9e3bc 8076c18994b44689b70746e09bf15b20] Flow 'volume_create_scheduler' (c14bcad4-f5c7-4124-896e-9b79789a4679) transitioned into state 'SUCCESS' from state 'RUNNING' from (pid=1891) _flow_receiver /usr/local/lib/python2.7/dist-packages/taskflow/listeners/logging.py:151
2015-08-24 14:03:08.030 DEBUG oslo_service.loopingcall [req-10c48707-ed3d-4b50-93f2-9eaaa4c710f6 None None] Fixed interval looping call <bound method Service.periodic_tasks of <cinder.service.Service object at 0x7f1357d0e6d0>> sleeping for 60.00 seconds from (pid=1891) _run_loop /usr/local/lib/python2.7/dist-packages/oslo_service/loopingcall.py:117
2015-08-24 14:03:15.098 DEBUG oslo_service.loopingcall [req-8e2c76d0-31df-40ef-acc4-e0c73c030d2f None None] Fixed interval looping call <bound method Service.report_state of <cinder.service.Service object at 0x7f1357d0e6d0>> sleeping for 9.97 seconds from (pid=1891) _run_loop /usr/local/lib/python2.7/dist-packages/oslo_service/loopingcall.py:117
2015-08-24 14:03:15.527 DEBUG cinder.scheduler.host_manager [req-27d2f327-35eb-44bc-bbc6-e58c0483a576 None None] Received volume service update from storage1@hus110: {u'filter_function': None, u'QoS_support': False, u'volume_backend_name': u'hus110', u'free_capacity_gb': 4747, u'driver_version': u'1.1.0', u'total_capacity_gb': 5725, u'reserved_percentage': 0, u'goodness_function': None, u'vendor_name': u'Hitachi', u'storage_protocol': u'iSCSI'} from (pid=1891) update_service_capabilities /opt/stack/cinder/cinder/scheduler/host_manager.py:450