Skip to content

Instantly share code, notes, and snippets.

/Debug Secret

Created January 6, 2016 18:48
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save anonymous/8368a01de3b21af275f1 to your computer and use it in GitHub Desktop.
Save anonymous/8368a01de3b21af275f1 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
Debug
2016-01-06 09:48:28.438 23517 INFO cinder.volume.drivers.nimble [req-65acb7a1-f171-455c-8da3-29b226881c7a 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Setting volume volume-90c176f6-f1de-4bb7-b7ed-0a6e8a7eeed9 to online_flag False
2016-01-06 09:48:28.553 23517 INFO cinder.volume.drivers.nimble [req-65acb7a1-f171-455c-8da3-29b226881c7a 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Dissociating volume volume-90c176f6-f1de-4bb7-b7ed-0a6e8a7eeed9
2016-01-06 09:48:28.631 23517 INFO cinder.volume.drivers.nimble [req-65acb7a1-f171-455c-8da3-29b226881c7a 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Deleting volume volume-90c176f6-f1de-4bb7-b7ed-0a6e8a7eeed9
2016-01-06 09:52:45.907 23517 INFO cinder.volume.flows.manager.create_volume [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8: being created as image with specification: {'status': u'creating', 'image_location': (None, None), 'volume_size': 40, 'volume_name': u'volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8', 'image_id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'image_service': <cinder.image.glance.GlanceImageService object at 0x7efd7b2f58d0>, 'image_meta': {'status': u'active', 'name': u'trusty', 'deleted': False, 'container_format': u'bare', 'created_at': datetime.datetime(2015, 11, 7, 0, 19, 19, tzinfo=<iso8601.iso8601.Utc object at 0x7efd7b149ed0>), 'disk_format': u'qcow2', 'updated_at': datetime.datetime(2015, 11, 7, 0, 19, 20, tzinfo=<iso8601.iso8601.Utc object at 0x7efd7b149ed0>), 'id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'owner': u'158397de288a45bc8adbd97f908ef5fa', 'min_ram': 0, 'checksum': u'4263306b9755eade84578f8ca27f8164', 'min_disk': 0, 'is_public': True, 'deleted_at': None, 'properties': {}, 'size': 258671104}}
2016-01-06 09:52:45.908 23517 WARNING cinder.context [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Unable to get internal tenant context: Missing required config parameters.
2016-01-06 09:52:45.909 23517 WARNING cinder.volume.flows.manager.create_volume [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Unable to get Cinder internal context, will not use image-volume cache.
2016-01-06 09:52:47.755 23517 INFO cinder.image.image_utils [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Image download 246.00 MB at 133.34 MB/s
2016-01-06 09:52:48.092 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully create volume volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8
2016-01-06 09:52:48.095 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting volume information for vol_name=volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8
2016-01-06 09:52:48.155 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully got volume information for volume volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8
2016-01-06 09:52:48.191 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Management
2016-01-06 09:52:48.192 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Subnet-172.16.43.0
2016-01-06 09:52:48.193 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Discovery ip 172.16.43.220 is used on data subnet Subnet-172.16.43.0
2016-01-06 09:52:48.193 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] vol_name=volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8 provider_location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8-v5870296ff3605485.00000013.ab107dea 0
2016-01-06 09:52:49.815 23517 WARNING os_brick.initiator.connector [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Could not find the iSCSI Initiator File /etc/iscsi/initiatorname.iscsi
2016-01-06 09:52:49.882 23517 WARNING os_brick.initiator.linuxfc [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] systool is not installed
2016-01-06 09:52:49.936 23517 WARNING os_brick.initiator.linuxfc [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] systool is not installed
2016-01-06 09:52:49.937 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting volume information for vol_name=volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8
2016-01-06 09:52:49.996 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully got volume information for volume volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8
2016-01-06 09:52:50.028 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Management
2016-01-06 09:52:50.028 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Subnet-172.16.43.0
2016-01-06 09:52:50.029 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Discovery ip 172.16.43.220 is used on data subnet Subnet-172.16.43.0
2016-01-06 09:52:50.031 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] vol_name=volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8 provider_location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8-v5870296ff3605485.00000013.ab107dea 0
2016-01-06 09:52:50.082 23517 INFO cinder.volume.drivers.nimble [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Entering initialize_connection volume=<cinder.db.sqlalchemy.models.Volume object at 0x7efd7b230790> connector={'ip': '172.20.0.41', 'host': 'os-controller1', 'multipath': False, 'os_type': 'linux2', 'platform': 'x86_64'} location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8-v5870296ff3605485.00000013.ab107dea 0
2016-01-06 09:52:50.087 23517 ERROR cinder.volume.driver [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Unable to fetch connection information from backend: 'initiator'
2016-01-06 09:52:50.088 23517 ERROR cinder.volume.flows.manager.create_volume [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Failed to copy image 9fbef3f3-5172-44e4-a753-9c8c5d684fd5 to volume: ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8
2016-01-06 09:52:50.129 23517 WARNING cinder.volume.manager [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (2056b51f-2438-4e1c-b604-3c4251e72170) transitioned into state 'FAILURE' from state 'RUNNING'
2016-01-06 09:52:50.136 23517 WARNING cinder.volume.manager [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (2056b51f-2438-4e1c-b604-3c4251e72170) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 09:52:50.139 23517 WARNING cinder.volume.manager [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (048c8bcd-b8fb-4041-9533-91c2e232e842) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 09:52:50.142 23517 WARNING cinder.volume.manager [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (15e5e402-5f10-41f9-85cf-37c6e57f92ed) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 09:52:50.198 23517 ERROR cinder.volume.flows.manager.create_volume [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8: create failed
2016-01-06 09:52:50.199 23517 WARNING cinder.volume.manager [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (fd682893-8d18-4298-8565-8a0e2e2e132a) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 09:52:50.203 23517 WARNING cinder.volume.manager [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (dcd937ac-f921-4812-9f34-1b3918255533) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 09:52:50.205 23517 WARNING cinder.volume.manager [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Flow 'volume_create_manager' (4ff46b37-2eb1-4b3a-92a4-de3cddc52e47) transitioned into state 'REVERTED' from state 'RUNNING'
2016-01-06 09:52:50.249 23517 ERROR oslo_messaging.rpc.dispatcher [req-50e4c878-bd7a-465f-bdcc-fffada83f9ad 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exception during message handling: Failed to copy image to volume: Bad or unexpected response from the storage volume backend API: Unable to fetch connection information from backend: 'initiator'
2016-01-06 09:54:28.607 23517 INFO cinder.volume.drivers.nimble [req-37c6f342-a344-439c-84f8-d7301fa1afd3 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Setting volume volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8 to online_flag False
2016-01-06 09:54:28.710 23517 INFO cinder.volume.drivers.nimble [req-37c6f342-a344-439c-84f8-d7301fa1afd3 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Dissociating volume volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8
2016-01-06 09:54:28.775 23517 INFO cinder.volume.drivers.nimble [req-37c6f342-a344-439c-84f8-d7301fa1afd3 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Deleting volume volume-ede9cdf3-cf0d-4afb-8b47-ebcdb37345d8
2016-01-06 10:01:50.059 23517 INFO cinder.volume.flows.manager.create_volume [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume 46e31c8c-86ad-46ca-9056-ec7ab739efee: being created as image with specification: {'status': u'creating', 'image_location': (None, None), 'volume_size': 40, 'volume_name': u'volume-46e31c8c-86ad-46ca-9056-ec7ab739efee', 'image_id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'image_service': <cinder.image.glance.GlanceImageService object at 0x7efd7aee2090>, 'image_meta': {'status': u'active', 'name': u'trusty', 'deleted': False, 'container_format': u'bare', 'created_at': datetime.datetime(2015, 11, 7, 0, 19, 19, tzinfo=<iso8601.iso8601.Utc object at 0x7efd7b33d950>), 'disk_format': u'qcow2', 'updated_at': datetime.datetime(2015, 11, 7, 0, 19, 20, tzinfo=<iso8601.iso8601.Utc object at 0x7efd7b33d950>), 'id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'owner': u'158397de288a45bc8adbd97f908ef5fa', 'min_ram': 0, 'checksum': u'4263306b9755eade84578f8ca27f8164', 'min_disk': 0, 'is_public': True, 'deleted_at': None, 'properties': {}, 'size': 258671104}}
2016-01-06 10:01:50.060 23517 WARNING cinder.context [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Unable to get internal tenant context: Missing required config parameters.
2016-01-06 10:01:50.061 23517 WARNING cinder.volume.flows.manager.create_volume [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Unable to get Cinder internal context, will not use image-volume cache.
2016-01-06 10:01:51.597 23517 INFO cinder.image.image_utils [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Image download 246.00 MB at 160.26 MB/s
2016-01-06 10:01:51.889 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully create volume volume-46e31c8c-86ad-46ca-9056-ec7ab739efee
2016-01-06 10:01:51.891 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting volume information for vol_name=volume-46e31c8c-86ad-46ca-9056-ec7ab739efee
2016-01-06 10:01:51.947 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully got volume information for volume volume-46e31c8c-86ad-46ca-9056-ec7ab739efee
2016-01-06 10:01:51.985 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Management
2016-01-06 10:01:51.986 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Subnet-172.16.43.0
2016-01-06 10:01:51.986 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Discovery ip 172.16.43.220 is used on data subnet Subnet-172.16.43.0
2016-01-06 10:01:51.987 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] vol_name=volume-46e31c8c-86ad-46ca-9056-ec7ab739efee provider_location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-46e31c8c-86ad-46ca-9056-ec7ab739efee-v5870296ff3605485.00000014.ab107dea 0
2016-01-06 10:01:53.194 23517 WARNING os_brick.initiator.linuxfc [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] systool is not installed
2016-01-06 10:01:53.254 23517 WARNING os_brick.initiator.linuxfc [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] systool is not installed
2016-01-06 10:01:53.255 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting volume information for vol_name=volume-46e31c8c-86ad-46ca-9056-ec7ab739efee
2016-01-06 10:01:53.314 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully got volume information for volume volume-46e31c8c-86ad-46ca-9056-ec7ab739efee
2016-01-06 10:01:53.346 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Management
2016-01-06 10:01:53.348 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Subnet-172.16.43.0
2016-01-06 10:01:53.348 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Discovery ip 172.16.43.220 is used on data subnet Subnet-172.16.43.0
2016-01-06 10:01:53.349 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] vol_name=volume-46e31c8c-86ad-46ca-9056-ec7ab739efee provider_location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-46e31c8c-86ad-46ca-9056-ec7ab739efee-v5870296ff3605485.00000014.ab107dea 0
2016-01-06 10:01:53.407 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Entering initialize_connection volume=<cinder.db.sqlalchemy.models.Volume object at 0x7efd7b2cb0d0> connector={'initiator': 'iqn.1993-08.org.debian:01:1cd8a3f8d569', 'ip': '172.20.0.41', 'platform': 'x86_64', 'host': 'os-controller1', 'os_type': 'linux2', 'multipath': False} location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-46e31c8c-86ad-46ca-9056-ec7ab739efee-v5870296ff3605485.00000014.ab107dea 0
2016-01-06 10:01:53.410 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting getInitiatorGrpList
2016-01-06 10:01:53.460 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully retrieved InitiatorGrpList
2016-01-06 10:01:53.461 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] No igroup found for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:01:53.462 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Creating initiator group openstack-sfhaykqdtocz with initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:01:53.462 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Creating initiator group openstack-sfhaykqdtocz with one initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:01:53.523 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Initiator group name is openstack-sfhaykqdtocz for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:01:53.525 23517 INFO cinder.volume.drivers.nimble [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Adding ACL to volume=volume-46e31c8c-86ad-46ca-9056-ec7ab739efee with initiator group name openstack-sfhaykqdtocz
2016-01-06 10:03:55.023 23517 WARNING os_brick.initiator.connector [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Failed to login iSCSI target iqn.2007-11.com.nimblestorage:volume-46e31c8c-86ad-46ca-9056-ec7ab739efee-v5870296ff3605485.00000014.ab107dea on portal 172.16.43.220:3260 (exit code 8).
2016-01-06 10:03:55.024 23517 WARNING os_brick.initiator.connector [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Failed to connect to iSCSI portal 172.16.43.220:3260.
2016-01-06 10:03:55.025 23517 WARNING os_brick.initiator.connector [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] ISCSI volume not yet found at: [u'/dev/disk/by-path/ip-172.16.43.220:3260-iscsi-iqn.2007-11.com.nimblestorage:volume-46e31c8c-86ad-46ca-9056-ec7ab739efee-v5870296ff3605485.00000014.ab107dea-lun-0']. Will rescan & retry. Try number: 0.
2016-01-06 10:03:55.097 23517 ERROR cinder.volume.flows.manager.create_volume [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Failed to copy image 9fbef3f3-5172-44e4-a753-9c8c5d684fd5 to volume: 46e31c8c-86ad-46ca-9056-ec7ab739efee
2016-01-06 10:03:55.166 23517 WARNING cinder.volume.manager [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (65dd65d4-e022-4da8-b8b3-dcaf170634f6) transitioned into state 'FAILURE' from state 'RUNNING'
2016-01-06 10:03:55.178 23517 WARNING cinder.volume.manager [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (65dd65d4-e022-4da8-b8b3-dcaf170634f6) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:03:55.182 23517 WARNING cinder.volume.manager [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (1e9b7692-946b-4067-bb66-d3439d0eb7fc) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:03:55.185 23517 WARNING cinder.volume.manager [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (2d20a29c-20ff-4579-aa16-dd06d446a562) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:03:55.263 23517 ERROR cinder.volume.flows.manager.create_volume [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume 46e31c8c-86ad-46ca-9056-ec7ab739efee: create failed
2016-01-06 10:03:55.264 23517 WARNING cinder.volume.manager [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (4ed11590-a8dd-43d0-8201-31ce1962b9c0) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:03:55.267 23517 WARNING cinder.volume.manager [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (e61d52f3-f76d-40d5-9479-42a815a8c0ce) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:03:55.270 23517 WARNING cinder.volume.manager [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Flow 'volume_create_manager' (c4f5ecd3-ff89-4b03-b941-16e679f5d38c) transitioned into state 'REVERTED' from state 'RUNNING'
2016-01-06 10:03:55.318 23517 ERROR oslo_messaging.rpc.dispatcher [req-690acd13-4f3b-462f-ae1e-938d399bb098 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exception during message handling: Failed to copy image to volume: iscsiadm: No session found.
2016-01-06 10:03:58.374 23517 INFO cinder.volume.drivers.nimble [req-261148a9-3113-4712-a35c-3fc118874b34 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Setting volume volume-46e31c8c-86ad-46ca-9056-ec7ab739efee to online_flag False
2016-01-06 10:03:58.456 23517 INFO cinder.volume.drivers.nimble [req-261148a9-3113-4712-a35c-3fc118874b34 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Dissociating volume volume-46e31c8c-86ad-46ca-9056-ec7ab739efee
2016-01-06 10:03:58.522 23517 INFO cinder.volume.drivers.nimble [req-261148a9-3113-4712-a35c-3fc118874b34 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Deleting volume volume-46e31c8c-86ad-46ca-9056-ec7ab739efee
2016-01-06 10:13:32.453 23517 INFO cinder.volume.flows.manager.create_volume [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume cd2bf271-e32d-4927-9382-e00c27664b5a: being created as image with specification: {'status': u'creating', 'image_location': (None, None), 'volume_size': 40, 'volume_name': u'volume-cd2bf271-e32d-4927-9382-e00c27664b5a', 'image_id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'image_service': <cinder.image.glance.GlanceImageService object at 0x7efd7b2cbed0>, 'image_meta': {'status': u'active', 'name': u'trusty', 'deleted': False, 'container_format': u'bare', 'created_at': datetime.datetime(2015, 11, 7, 0, 19, 19, tzinfo=<iso8601.iso8601.Utc object at 0x7efd7b115ad0>), 'disk_format': u'qcow2', 'updated_at': datetime.datetime(2015, 11, 7, 0, 19, 20, tzinfo=<iso8601.iso8601.Utc object at 0x7efd7b115ad0>), 'id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'owner': u'158397de288a45bc8adbd97f908ef5fa', 'min_ram': 0, 'checksum': u'4263306b9755eade84578f8ca27f8164', 'min_disk': 0, 'is_public': True, 'deleted_at': None, 'properties': {}, 'size': 258671104}}
2016-01-06 10:13:32.455 23517 WARNING cinder.context [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Unable to get internal tenant context: Missing required config parameters.
2016-01-06 10:13:32.455 23517 WARNING cinder.volume.flows.manager.create_volume [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Unable to get Cinder internal context, will not use image-volume cache.
2016-01-06 10:13:34.015 23517 INFO cinder.image.image_utils [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Image download 246.00 MB at 157.84 MB/s
2016-01-06 10:13:34.331 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully create volume volume-cd2bf271-e32d-4927-9382-e00c27664b5a
2016-01-06 10:13:34.333 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting volume information for vol_name=volume-cd2bf271-e32d-4927-9382-e00c27664b5a
2016-01-06 10:13:34.392 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully got volume information for volume volume-cd2bf271-e32d-4927-9382-e00c27664b5a
2016-01-06 10:13:34.436 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Management
2016-01-06 10:13:34.437 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Subnet-172.16.43.0
2016-01-06 10:13:34.438 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Discovery ip 172.16.43.220 is used on data subnet Subnet-172.16.43.0
2016-01-06 10:13:34.439 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] vol_name=volume-cd2bf271-e32d-4927-9382-e00c27664b5a provider_location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-cd2bf271-e32d-4927-9382-e00c27664b5a-v5870296ff3605485.00000015.ab107dea 0
2016-01-06 10:13:35.961 23517 WARNING os_brick.initiator.linuxfc [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] systool is not installed
2016-01-06 10:13:36.014 23517 WARNING os_brick.initiator.linuxfc [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] systool is not installed
2016-01-06 10:13:36.016 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting volume information for vol_name=volume-cd2bf271-e32d-4927-9382-e00c27664b5a
2016-01-06 10:13:36.074 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully got volume information for volume volume-cd2bf271-e32d-4927-9382-e00c27664b5a
2016-01-06 10:13:36.111 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Management
2016-01-06 10:13:36.111 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Subnet-172.16.43.0
2016-01-06 10:13:36.112 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Discovery ip 172.16.43.220 is used on data subnet Subnet-172.16.43.0
2016-01-06 10:13:36.113 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] vol_name=volume-cd2bf271-e32d-4927-9382-e00c27664b5a provider_location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-cd2bf271-e32d-4927-9382-e00c27664b5a-v5870296ff3605485.00000015.ab107dea 0
2016-01-06 10:13:36.176 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Entering initialize_connection volume=<cinder.db.sqlalchemy.models.Volume object at 0x7efd7b460b90> connector={'initiator': 'iqn.1993-08.org.debian:01:1cd8a3f8d569', 'ip': '172.20.0.41', 'platform': 'x86_64', 'host': 'os-controller1', 'os_type': 'linux2', 'multipath': False} location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-cd2bf271-e32d-4927-9382-e00c27664b5a-v5870296ff3605485.00000015.ab107dea 0
2016-01-06 10:13:36.179 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting getInitiatorGrpList
2016-01-06 10:13:36.226 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully retrieved InitiatorGrpList
2016-01-06 10:13:36.227 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] igroup openstack-sfhaykqdtocz found for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:13:36.227 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Initiator group name is openstack-sfhaykqdtocz for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:13:36.228 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Adding ACL to volume=volume-cd2bf271-e32d-4927-9382-e00c27664b5a with initiator group name openstack-sfhaykqdtocz
2016-01-06 10:13:39.204 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Entering terminate_connection volume=<cinder.db.sqlalchemy.models.Volume object at 0x7efd7b11e6d0> connector={'initiator': 'iqn.1993-08.org.debian:01:1cd8a3f8d569', 'ip': '172.20.0.41', 'platform': 'x86_64', 'host': 'os-controller1', 'os_type': 'linux2', 'multipath': False} location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-cd2bf271-e32d-4927-9382-e00c27664b5a-v5870296ff3605485.00000015.ab107dea 0.
2016-01-06 10:13:39.208 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting getInitiatorGrpList
2016-01-06 10:13:39.256 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully retrieved InitiatorGrpList
2016-01-06 10:13:39.256 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] igroup openstack-sfhaykqdtocz found for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:13:39.257 23517 INFO cinder.volume.drivers.nimble [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Removing ACL from volume=volume-cd2bf271-e32d-4927-9382-e00c27664b5a for initiator group openstack-sfhaykqdtocz
2016-01-06 10:13:39.332 23517 ERROR cinder.volume.flows.manager.create_volume [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Failed to copy image 9fbef3f3-5172-44e4-a753-9c8c5d684fd5 to volume: cd2bf271-e32d-4927-9382-e00c27664b5a
2016-01-06 10:13:39.384 23517 WARNING cinder.volume.manager [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (9fac5e22-a0b5-4122-accf-71c35b4b424d) transitioned into state 'FAILURE' from state 'RUNNING'
2016-01-06 10:13:39.392 23517 WARNING cinder.volume.manager [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (9fac5e22-a0b5-4122-accf-71c35b4b424d) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:13:39.394 23517 WARNING cinder.volume.manager [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (1eaeb302-3b0a-41b9-8a5c-0194d526850e) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:13:39.398 23517 WARNING cinder.volume.manager [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (3cae0e34-f9a6-4982-a697-19c4d881dae6) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:13:39.464 23517 ERROR cinder.volume.flows.manager.create_volume [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume cd2bf271-e32d-4927-9382-e00c27664b5a: create failed
2016-01-06 10:13:39.467 23517 WARNING cinder.volume.manager [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (a644f938-c341-436e-9e96-4e7ce46676df) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:13:39.473 23517 WARNING cinder.volume.manager [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (2f63ebe3-07a9-4808-9f30-13b2b3a4f7b8) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:13:39.476 23517 WARNING cinder.volume.manager [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Flow 'volume_create_manager' (c883654a-96ba-47ed-b00d-fc6cd6ae05b1) transitioned into state 'REVERTED' from state 'RUNNING'
2016-01-06 10:13:39.524 23517 ERROR oslo_messaging.rpc.dispatcher [req-c6533f65-43eb-4be8-9644-dddc781082f1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exception during message handling: Failed to copy image to volume: qemu-img: error writing zeroes at sector 0: Input/output error
2016-01-06 10:13:42.970 23517 INFO cinder.volume.drivers.nimble [req-f5788c95-0eeb-4329-84c1-9cadd8e78f97 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Setting volume volume-cd2bf271-e32d-4927-9382-e00c27664b5a to online_flag False
2016-01-06 10:13:43.052 23517 INFO cinder.volume.drivers.nimble [req-f5788c95-0eeb-4329-84c1-9cadd8e78f97 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Dissociating volume volume-cd2bf271-e32d-4927-9382-e00c27664b5a
2016-01-06 10:13:43.205 23517 INFO cinder.volume.drivers.nimble [req-f5788c95-0eeb-4329-84c1-9cadd8e78f97 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Deleting volume volume-cd2bf271-e32d-4927-9382-e00c27664b5a
2016-01-06 10:18:31.517 3026 INFO cinder.volume.flows.manager.create_volume [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume 26e5b6a0-c577-4bf0-87f6-3a84e8b3c906: being created as image with specification: {'status': u'creating', 'image_location': (None, None), 'volume_size': 50, 'volume_name': u'volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906', 'image_id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'image_service': <cinder.image.glance.GlanceImageService object at 0x7f88b511a2d0>, 'image_meta': {'status': u'active', 'name': u'trusty', 'deleted': False, 'container_format': u'bare', 'created_at': datetime.datetime(2015, 11, 7, 0, 19, 19, tzinfo=<iso8601.iso8601.Utc object at 0x7f88b5b37e10>), 'disk_format': u'qcow2', 'updated_at': datetime.datetime(2015, 11, 7, 0, 19, 20, tzinfo=<iso8601.iso8601.Utc object at 0x7f88b5b37e10>), 'id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'owner': u'158397de288a45bc8adbd97f908ef5fa', 'min_ram': 0, 'checksum': u'4263306b9755eade84578f8ca27f8164', 'min_disk': 0, 'is_public': True, 'deleted_at': None, 'properties': {}, 'size': 258671104}}
2016-01-06 10:18:31.518 3026 WARNING cinder.context [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Unable to get internal tenant context: Missing required config parameters.
2016-01-06 10:18:31.519 3026 WARNING cinder.volume.flows.manager.create_volume [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Unable to get Cinder internal context, will not use image-volume cache.
2016-01-06 10:18:33.258 3026 INFO cinder.image.image_utils [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Image download 246.00 MB at 141.83 MB/s
2016-01-06 10:18:33.612 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully create volume volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906
2016-01-06 10:18:33.616 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting volume information for vol_name=volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906
2016-01-06 10:18:33.679 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully got volume information for volume volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906
2016-01-06 10:18:33.712 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Management
2016-01-06 10:18:33.713 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Subnet-172.16.43.0
2016-01-06 10:18:33.714 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Discovery ip 172.16.43.220 is used on data subnet Subnet-172.16.43.0
2016-01-06 10:18:33.716 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] vol_name=volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906 provider_location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906-v5870296ff3605485.00000016.ab107dea 0
2016-01-06 10:18:34.877 3026 WARNING os_brick.initiator.linuxfc [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] systool is not installed
2016-01-06 10:18:34.932 3026 WARNING os_brick.initiator.linuxfc [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] systool is not installed
2016-01-06 10:18:34.934 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting volume information for vol_name=volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906
2016-01-06 10:18:34.990 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully got volume information for volume volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906
2016-01-06 10:18:35.024 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Management
2016-01-06 10:18:35.024 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Subnet-172.16.43.0
2016-01-06 10:18:35.025 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Discovery ip 172.16.43.220 is used on data subnet Subnet-172.16.43.0
2016-01-06 10:18:35.026 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] vol_name=volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906 provider_location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906-v5870296ff3605485.00000016.ab107dea 0
2016-01-06 10:18:35.077 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Entering initialize_connection volume=<cinder.db.sqlalchemy.models.Volume object at 0x7f88b4ff4190> connector={'initiator': 'iqn.1993-08.org.debian:01:1cd8a3f8d569', 'ip': '172.20.0.41', 'platform': 'x86_64', 'host': 'os-controller1', 'os_type': 'linux2', 'multipath': False} location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906-v5870296ff3605485.00000016.ab107dea 0
2016-01-06 10:18:35.081 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting getInitiatorGrpList
2016-01-06 10:18:35.128 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully retrieved InitiatorGrpList
2016-01-06 10:18:35.129 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] igroup openstack-sfhaykqdtocz found for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:18:35.129 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Initiator group name is openstack-sfhaykqdtocz for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:18:35.130 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Adding ACL to volume=volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906 with initiator group name openstack-sfhaykqdtocz
2016-01-06 10:18:38.033 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Entering terminate_connection volume=<cinder.db.sqlalchemy.models.Volume object at 0x7f88b4fff150> connector={'initiator': 'iqn.1993-08.org.debian:01:1cd8a3f8d569', 'ip': '172.20.0.41', 'platform': 'x86_64', 'host': 'os-controller1', 'os_type': 'linux2', 'multipath': False} location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906-v5870296ff3605485.00000016.ab107dea 0.
2016-01-06 10:18:38.038 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting getInitiatorGrpList
2016-01-06 10:18:38.088 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully retrieved InitiatorGrpList
2016-01-06 10:18:38.089 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] igroup openstack-sfhaykqdtocz found for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:18:38.090 3026 INFO cinder.volume.drivers.nimble [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Removing ACL from volume=volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906 for initiator group openstack-sfhaykqdtocz
2016-01-06 10:18:38.140 3026 ERROR cinder.volume.flows.manager.create_volume [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Failed to copy image 9fbef3f3-5172-44e4-a753-9c8c5d684fd5 to volume: 26e5b6a0-c577-4bf0-87f6-3a84e8b3c906
2016-01-06 10:18:38.187 3026 WARNING cinder.volume.manager [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (03e0128b-14f6-48ce-b0a1-8d846c9eb2c9) transitioned into state 'FAILURE' from state 'RUNNING'
2016-01-06 10:18:38.194 3026 WARNING cinder.volume.manager [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (03e0128b-14f6-48ce-b0a1-8d846c9eb2c9) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:18:38.196 3026 WARNING cinder.volume.manager [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (048c0553-c991-4257-aede-24e690f51391) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:18:38.199 3026 WARNING cinder.volume.manager [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (8467ee43-65f6-4d77-91fb-e2240520bc51) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:18:38.260 3026 ERROR cinder.volume.flows.manager.create_volume [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume 26e5b6a0-c577-4bf0-87f6-3a84e8b3c906: create failed
2016-01-06 10:18:38.262 3026 WARNING cinder.volume.manager [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (084d4f66-2f49-4a1f-acde-acfd57245d19) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:18:38.265 3026 WARNING cinder.volume.manager [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (be844ca0-eadb-4b86-b582-f4f479c0c4be) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:18:38.267 3026 WARNING cinder.volume.manager [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Flow 'volume_create_manager' (88decbf8-99cc-4421-88a3-544c3d11ddb7) transitioned into state 'REVERTED' from state 'RUNNING'
2016-01-06 10:18:38.313 3026 ERROR oslo_messaging.rpc.dispatcher [req-2c7b6129-7dbd-47b3-ad85-9f93a5e544b1 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exception during message handling: Failed to copy image to volume: qemu-img: error writing zeroes at sector 0: Input/output error
2016-01-06 10:24:53.146 4039 INFO cinder.volume.flows.manager.create_volume [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume 7ddf10c9-266e-4bc1-aa3f-add3be35a946: being created as image with specification: {'status': u'creating', 'image_location': (None, None), 'volume_size': 50, 'volume_name': u'volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946', 'image_id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'image_service': <cinder.image.glance.GlanceImageService object at 0x7fbd6e87b6d0>, 'image_meta': {'status': u'active', 'name': u'trusty', 'deleted': False, 'container_format': u'bare', 'created_at': datetime.datetime(2015, 11, 7, 0, 19, 19, tzinfo=<iso8601.iso8601.Utc object at 0x7fbd6e713f90>), 'disk_format': u'qcow2', 'updated_at': datetime.datetime(2015, 11, 7, 0, 19, 20, tzinfo=<iso8601.iso8601.Utc object at 0x7fbd6e713f90>), 'id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'owner': u'158397de288a45bc8adbd97f908ef5fa', 'min_ram': 0, 'checksum': u'4263306b9755eade84578f8ca27f8164', 'min_disk': 0, 'is_public': True, 'deleted_at': None, 'properties': {}, 'size': 258671104}}
2016-01-06 10:24:53.149 4039 WARNING cinder.context [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Unable to get internal tenant context: Missing required config parameters.
2016-01-06 10:24:53.149 4039 WARNING cinder.volume.flows.manager.create_volume [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Unable to get Cinder internal context, will not use image-volume cache.
2016-01-06 10:24:54.496 4039 INFO cinder.image.image_utils [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Image download 246.00 MB at 182.88 MB/s
2016-01-06 10:24:54.776 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully create volume volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946
2016-01-06 10:24:54.777 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting volume information for vol_name=volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946
2016-01-06 10:24:54.839 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully got volume information for volume volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946
2016-01-06 10:24:54.876 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Management
2016-01-06 10:24:54.877 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Subnet-172.16.43.0
2016-01-06 10:24:54.877 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Discovery ip 172.16.43.220 is used on data subnet Subnet-172.16.43.0
2016-01-06 10:24:54.878 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] vol_name=volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946 provider_location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946-v5870296ff3605485.00000017.ab107dea 0
2016-01-06 10:24:56.078 4039 WARNING os_brick.initiator.linuxfc [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] systool is not installed
2016-01-06 10:24:56.126 4039 WARNING os_brick.initiator.linuxfc [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] systool is not installed
2016-01-06 10:24:56.128 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting volume information for vol_name=volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946
2016-01-06 10:24:56.183 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully got volume information for volume volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946
2016-01-06 10:24:56.217 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Management
2016-01-06 10:24:56.218 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Subnet-172.16.43.0
2016-01-06 10:24:56.220 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Discovery ip 172.16.43.220 is used on data subnet Subnet-172.16.43.0
2016-01-06 10:24:56.220 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] vol_name=volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946 provider_location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946-v5870296ff3605485.00000017.ab107dea 0
2016-01-06 10:24:56.269 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Entering initialize_connection volume=<cinder.db.sqlalchemy.models.Volume object at 0x7fbd6e60c210> connector={'initiator': 'iqn.1993-08.org.debian:01:1cd8a3f8d569', 'ip': '172.20.0.41', 'platform': 'x86_64', 'host': 'os-controller1', 'os_type': 'linux2', 'multipath': False} location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946-v5870296ff3605485.00000017.ab107dea 0
2016-01-06 10:24:56.272 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting getInitiatorGrpList
2016-01-06 10:24:56.319 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully retrieved InitiatorGrpList
2016-01-06 10:24:56.320 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] igroup openstack-sfhaykqdtocz found for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:24:56.321 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Initiator group name is openstack-sfhaykqdtocz for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:24:56.321 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Adding ACL to volume=volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946 with initiator group name openstack-sfhaykqdtocz
2016-01-06 10:24:59.116 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Entering terminate_connection volume=<cinder.db.sqlalchemy.models.Volume object at 0x7fbd6e5d02d0> connector={'initiator': 'iqn.1993-08.org.debian:01:1cd8a3f8d569', 'ip': '172.20.0.41', 'platform': 'x86_64', 'host': 'os-controller1', 'os_type': 'linux2', 'multipath': False} location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946-v5870296ff3605485.00000017.ab107dea 0.
2016-01-06 10:24:59.121 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting getInitiatorGrpList
2016-01-06 10:24:59.173 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully retrieved InitiatorGrpList
2016-01-06 10:24:59.174 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] igroup openstack-sfhaykqdtocz found for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:24:59.175 4039 INFO cinder.volume.drivers.nimble [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Removing ACL from volume=volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946 for initiator group openstack-sfhaykqdtocz
2016-01-06 10:24:59.237 4039 ERROR cinder.volume.flows.manager.create_volume [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Failed to copy image 9fbef3f3-5172-44e4-a753-9c8c5d684fd5 to volume: 7ddf10c9-266e-4bc1-aa3f-add3be35a946
2016-01-06 10:24:59.278 4039 WARNING cinder.volume.manager [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (0bb42dc1-5936-4045-a2d8-780778ec9a2c) transitioned into state 'FAILURE' from state 'RUNNING'
2016-01-06 10:24:59.287 4039 WARNING cinder.volume.manager [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (0bb42dc1-5936-4045-a2d8-780778ec9a2c) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:24:59.289 4039 WARNING cinder.volume.manager [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (d8ee258f-0993-4b14-91a1-62e8a0ebf5a5) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:24:59.293 4039 WARNING cinder.volume.manager [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (5862f03e-f4a1-4677-a458-a0768076e256) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:24:59.344 4039 ERROR cinder.volume.flows.manager.create_volume [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume 7ddf10c9-266e-4bc1-aa3f-add3be35a946: create failed
2016-01-06 10:24:59.346 4039 WARNING cinder.volume.manager [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (c538d44e-0ffe-4d88-b317-da9287ab2b68) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:24:59.349 4039 WARNING cinder.volume.manager [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (9d143fc3-ba75-41d6-ad95-909e9a0b0a7e) transitioned into state 'REVERTED' from state 'REVERTING'
2016-01-06 10:24:59.351 4039 WARNING cinder.volume.manager [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Flow 'volume_create_manager' (bb417ebc-e881-410b-8af3-7c86c46e4ca0) transitioned into state 'REVERTED' from state 'RUNNING'
2016-01-06 10:24:59.401 4039 ERROR oslo_messaging.rpc.dispatcher [req-73f0c67a-3bec-4db5-bcd5-772ee368d864 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exception during message handling: Failed to copy image to volume: qemu-img: error writing zeroes at sector 0: Input/output error
2016-01-06 10:25:03.017 4039 INFO cinder.volume.drivers.nimble [req-5754eb02-1e5f-441b-8077-04e0332a63dc 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Setting volume volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946 to online_flag False
2016-01-06 10:25:03.118 4039 INFO cinder.volume.drivers.nimble [req-5754eb02-1e5f-441b-8077-04e0332a63dc 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Dissociating volume volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946
2016-01-06 10:25:03.182 4039 INFO cinder.volume.drivers.nimble [req-5754eb02-1e5f-441b-8077-04e0332a63dc 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Deleting volume volume-7ddf10c9-266e-4bc1-aa3f-add3be35a946
2016-01-06 10:32:41.951 4326 DEBUG oslo_concurrency.lockutils [req-8a095dd8-cd40-4f0f-b020-733d10edbbdf 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Lock "26e5b6a0-c577-4bf0-87f6-3a84e8b3c906-delete_volume" acquired by "cinder.volume.manager.lvo_inner2" :: waited 0.001s inner /usr/lib/python2.7/dist-packages/oslo_concurrency/lockutils.py:253
2016-01-06 10:32:42.106 4326 INFO cinder.volume.drivers.nimble [req-8a095dd8-cd40-4f0f-b020-733d10edbbdf 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Setting volume volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906 to online_flag False
2016-01-06 10:32:42.183 4326 INFO cinder.volume.drivers.nimble [req-8a095dd8-cd40-4f0f-b020-733d10edbbdf 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Dissociating volume volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906
2016-01-06 10:32:42.250 4326 INFO cinder.volume.drivers.nimble [req-8a095dd8-cd40-4f0f-b020-733d10edbbdf 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Deleting volume volume-26e5b6a0-c577-4bf0-87f6-3a84e8b3c906
2016-01-06 10:34:14.589 4326 DEBUG cinder.volume.flows.manager.create_volume [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume reschedule parameters: True retry: {u'num_attempts': 1, u'hosts': [u'os-controller1@nimble-phx#Nimble-PHX']} get_flow /usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py:945
2016-01-06 10:34:14.671 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Flow 'volume_create_manager' (7fc66b20-40f0-4812-a79f-08218f6bfbda) transitioned into state 'RUNNING' from state 'PENDING' _flow_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:151
2016-01-06 10:34:14.674 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (86616fc3-6185-465f-9b60-b3179d66fcae) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:190
2016-01-06 10:34:14.724 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (86616fc3-6185-465f-9b60-b3179d66fcae) transitioned into state 'SUCCESS' from state 'RUNNING' with result '<cinder.db.sqlalchemy.models.Volume object at 0x7f31280bfd10>' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:178
2016-01-06 10:34:14.725 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (90bcd66e-2d59-467b-bad9-5edc9c2001ca) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:190
2016-01-06 10:34:14.727 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (90bcd66e-2d59-467b-bad9-5edc9c2001ca) transitioned into state 'SUCCESS' from state 'RUNNING' with result 'None' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:178
2016-01-06 10:34:14.730 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (1ccd1737-c86a-4af1-8222-f1095f3d61e4) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:190
2016-01-06 10:34:14.745 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] curl -g -i -X HEAD -H 'Accept-Encoding: gzip, deflate' -H 'Accept: */*' -H 'User-Agent: python-glanceclient' -H 'Connection: keep-alive' -H 'X-Auth-Token: {SHA1}6e5b33aa7b659269ed978a382a188167b0d84ee8' -H 'Content-Type: application/octet-stream' http://os-controller1:9292/v1/images/9fbef3f3-5172-44e4-a753-9c8c5d684fd5 log_curl_request /usr/lib/python2.7/dist-packages/glanceclient/common/http.py:189
2016-01-06 10:34:14.780 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -]
2016-01-06 10:34:14.781 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] curl -g -i -X HEAD -H 'Accept-Encoding: gzip, deflate' -H 'Accept: */*' -H 'User-Agent: python-glanceclient' -H 'Connection: keep-alive' -H 'X-Auth-Token: {SHA1}6e5b33aa7b659269ed978a382a188167b0d84ee8' -H 'Content-Type: application/octet-stream' http://os-controller1:9292/v1/images/9fbef3f3-5172-44e4-a753-9c8c5d684fd5 log_curl_request /usr/lib/python2.7/dist-packages/glanceclient/common/http.py:189
2016-01-06 10:34:14.807 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -]
2016-01-06 10:34:14.809 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (1ccd1737-c86a-4af1-8222-f1095f3d61e4) transitioned into state 'SUCCESS' from state 'RUNNING' with result '{'status': u'creating', 'image_location': (None, None), 'image_id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'volume_id': u'272a1c36-3e97-4215-83d6-681c1bb1e2b8', 'volume_size': 50, 'image_service': <cinder.image.glance.GlanceImageService object at 0x7f312818ed10>, 'image_meta': {'status': u'active', 'name': u'trusty', 'deleted': False, 'container_format': u'bare', 'created_at': datetime.datetime(2015, 11, 7, 0, 19, 19, tzinfo=<iso8601.iso8601.Utc object at 0x7f31280ef890>), 'disk_format': u'qcow2', 'updated_at': datetime.datetime(2015, 11, 7, 0, 19, 20, tzinfo=<iso8601.iso8601.Utc object at 0x7f31280ef890>), 'id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'owner': u'158397de288a45bc8adbd97f908ef5fa', 'min_ram': 0, 'checksum': u'4263306b9755eade84578f8ca27f8164', 'min_disk': 0, 'is_public': True, 'deleted_at': None, 'properties': {}, 'size': 258671104}, 'volume_name': u'volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8', 'type': 'image'}' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:178
2016-01-06 10:34:14.810 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (18beeb56-e226-4b59-87c3-751a808555a3) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:190
2016-01-06 10:34:14.907 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (18beeb56-e226-4b59-87c3-751a808555a3) transitioned into state 'SUCCESS' from state 'RUNNING' with result 'None' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:178
2016-01-06 10:34:14.909 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (a45f2a9c-1173-4891-adfc-0dd259d9d31b) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:190
2016-01-06 10:34:14.910 4326 INFO cinder.volume.flows.manager.create_volume [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume 272a1c36-3e97-4215-83d6-681c1bb1e2b8: being created as image with specification: {'status': u'creating', 'image_location': (None, None), 'volume_size': 50, 'volume_name': u'volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8', 'image_id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'image_service': <cinder.image.glance.GlanceImageService object at 0x7f312818ed10>, 'image_meta': {'status': u'active', 'name': u'trusty', 'deleted': False, 'container_format': u'bare', 'created_at': datetime.datetime(2015, 11, 7, 0, 19, 19, tzinfo=<iso8601.iso8601.Utc object at 0x7f31280ef890>), 'disk_format': u'qcow2', 'updated_at': datetime.datetime(2015, 11, 7, 0, 19, 20, tzinfo=<iso8601.iso8601.Utc object at 0x7f31280ef890>), 'id': u'9fbef3f3-5172-44e4-a753-9c8c5d684fd5', 'owner': u'158397de288a45bc8adbd97f908ef5fa', 'min_ram': 0, 'checksum': u'4263306b9755eade84578f8ca27f8164', 'min_disk': 0, 'is_public': True, 'deleted_at': None, 'properties': {}, 'size': 258671104}}
2016-01-06 10:34:14.910 4326 DEBUG cinder.volume.flows.manager.create_volume [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Cloning 272a1c36-3e97-4215-83d6-681c1bb1e2b8 from image 9fbef3f3-5172-44e4-a753-9c8c5d684fd5 at location (None, None). _create_from_image /usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py:691
2016-01-06 10:34:14.911 4326 WARNING cinder.context [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Unable to get internal tenant context: Missing required config parameters.
2016-01-06 10:34:14.911 4326 WARNING cinder.volume.flows.manager.create_volume [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Unable to get Cinder internal context, will not use image-volume cache.
2016-01-06 10:34:14.913 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] curl -g -i -X GET -H 'Accept-Encoding: gzip, deflate' -H 'Accept: */*' -H 'User-Agent: python-glanceclient' -H 'Connection: keep-alive' -H 'X-Auth-Token: {SHA1}6e5b33aa7b659269ed978a382a188167b0d84ee8' -H 'Content-Type: application/octet-stream' http://os-controller1:9292/v1/images/9fbef3f3-5172-44e4-a753-9c8c5d684fd5 log_curl_request /usr/lib/python2.7/dist-packages/glanceclient/common/http.py:189
2016-01-06 10:34:14.940 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -]
2016-01-06 10:34:16.488 4326 DEBUG cinder.image.image_utils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Image fetch details: dest /var/lib/cinder/conversion/tmpNdMIMe, size 246.00 MB, duration 1.58 sec fetch /usr/lib/python2.7/dist-packages/cinder/image/image_utils.py:177
2016-01-06 10:34:16.489 4326 INFO cinder.image.image_utils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Image download 246.00 MB at 156.16 MB/s
2016-01-06 10:34:16.492 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf env LC_ALL=C qemu-img info /var/lib/cinder/conversion/tmpNdMIMe execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:16.632 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf env LC_ALL=C qemu-img info /var/lib/cinder/conversion/tmpNdMIMe" returned: 0 in 0.141s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:16.634 4326 DEBUG cinder.image.image_utils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Temporary image 9fbef3f3-5172-44e4-a753-9c8c5d684fd5 is fetched for user 437f69f2cee64d4bbd886eba8f8daaf9. fetch /usr/lib/python2.7/dist-packages/cinder/image/image_utils.py:528
2016-01-06 10:34:16.635 4326 DEBUG cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Creating a new volume=volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8 size=53687091200 reserve=False in pool=default description= with Extra Specs perfpol-name=default encryption=no cipher=3 agent-type=5 _execute_create_vol /usr/lib/python2.7/dist-packages/cinder/volume/drivers/nimble.py:657
2016-01-06 10:34:16.811 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully create volume volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8
2016-01-06 10:34:16.812 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting volume information for vol_name=volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8
2016-01-06 10:34:16.868 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully got volume information for volume volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8
2016-01-06 10:34:16.903 4326 DEBUG cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] subnet_label used *, netconfig (NetConfiguration){
2016-01-06 10:34:16.906 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Management
2016-01-06 10:34:16.907 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Subnet-172.16.43.0
2016-01-06 10:34:16.907 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Discovery ip 172.16.43.220 is used on data subnet Subnet-172.16.43.0
2016-01-06 10:34:16.908 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] vol_name=volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8 provider_location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea 0
2016-01-06 10:34:18.340 4326 DEBUG cinder.volume.flows.manager.create_volume [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Attempting download of 9fbef3f3-5172-44e4-a753-9c8c5d684fd5 ((None, None)) to volume 272a1c36-3e97-4215-83d6-681c1bb1e2b8. _copy_image_to_volume /usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py:517
2016-01-06 10:34:18.341 4326 DEBUG cinder.volume.driver [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] copy_image_to_volume volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8. copy_image_to_volume /usr/lib/python2.7/dist-packages/cinder/volume/driver.py:816
2016-01-06 10:34:18.342 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf cat /etc/iscsi/initiatorname.iscsi execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:18.416 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf cat /etc/iscsi/initiatorname.iscsi" returned: 0 in 0.074s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:18.418 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:18.477 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v" returned: 96 in 0.060s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:18.479 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] u'sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v' failed. Not Retrying. execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:308
2016-01-06 10:34:18.479 4326 WARNING os_brick.initiator.linuxfc [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] systool is not installed
2016-01-06 10:34:18.480 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:18.546 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v" returned: 96 in 0.066s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:18.548 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] u'sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v' failed. Not Retrying. execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:308
2016-01-06 10:34:18.549 4326 WARNING os_brick.initiator.linuxfc [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] systool is not installed
2016-01-06 10:34:18.550 4326 DEBUG cinder.volume.driver [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume 272a1c36-3e97-4215-83d6-681c1bb1e2b8: creating export _attach_volume /usr/lib/python2.7/dist-packages/cinder/volume/driver.py:935
2016-01-06 10:34:18.551 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting volume information for vol_name=volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8
2016-01-06 10:34:18.601 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully got volume information for volume volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8
2016-01-06 10:34:18.634 4326 DEBUG cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] subnet_label used *, netconfig (NetConfiguration){
2016-01-06 10:34:18.637 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Management
2016-01-06 10:34:18.638 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exploring array subnet label Subnet-172.16.43.0
2016-01-06 10:34:18.638 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Discovery ip 172.16.43.220 is used on data subnet Subnet-172.16.43.0
2016-01-06 10:34:18.639 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] vol_name=volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8 provider_location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea 0
2016-01-06 10:34:18.687 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Entering initialize_connection volume=<cinder.db.sqlalchemy.models.Volume object at 0x7f3127f11390> connector={'initiator': 'iqn.1993-08.org.debian:01:1cd8a3f8d569', 'ip': '172.20.0.41', 'platform': 'x86_64', 'host': 'os-controller1', 'os_type': 'linux2', 'multipath': False} location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea 0
2016-01-06 10:34:18.690 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting getInitiatorGrpList
2016-01-06 10:34:18.738 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully retrieved InitiatorGrpList
2016-01-06 10:34:18.739 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] igroup openstack-sfhaykqdtocz found for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:34:18.739 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Initiator group name is openstack-sfhaykqdtocz for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:34:18.740 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Adding ACL to volume=volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8 with initiator group name openstack-sfhaykqdtocz
2016-01-06 10:34:18.799 4326 DEBUG os_brick.initiator.connector [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Factory for iscsi on x86_64 factory /usr/lib/python2.7/dist-packages/os_brick/initiator/connector.py:161
2016-01-06 10:34:18.800 4326 DEBUG oslo_concurrency.lockutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Lock "connect_volume" acquired by "os_brick.initiator.connector.connect_volume" :: waited 0.000s inner /usr/lib/python2.7/dist-packages/oslo_concurrency/lockutils.py:253
2016-01-06 10:34:18.800 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260 execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:18.860 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260" returned: 21 in 0.060s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:18.861 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] u'sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260' failed. Not Retrying. execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:308
2016-01-06 10:34:18.862 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260 --interface default --op new execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:18.920 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260 --interface default --op new" returned: 0 in 0.059s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:18.921 4326 DEBUG os_brick.initiator.connector [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] ('iscsiadm %(iscsi_command)s: stdout=%(out)s stderr=%(err)s', {'iscsi_command': ('--interface', 'default', '--op', 'new'), 'err': '', 'out': 'New iSCSI node [tcp:[hw=,ip=,net_if=,iscsi_if=default] 172.16.43.220,3260,-1 iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea] added\n'}) _run_iscsiadm /usr/lib/python2.7/dist-packages/os_brick/initiator/connector.py:658
2016-01-06 10:34:18.922 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m session execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:18.977 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m session" returned: 21 in 0.055s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:18.978 4326 DEBUG os_brick.initiator.connector [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] iscsiadm ['-m', 'session']: stdout= stderr=iscsiadm: No active sessions.
2016-01-06 10:34:18.979 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260 --login execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:20.072 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260 --login" returned: 0 in 1.094s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:20.075 4326 DEBUG os_brick.initiator.connector [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] ('iscsiadm %(iscsi_command)s: stdout=%(out)s stderr=%(err)s', {'iscsi_command': ('--login',), 'err': '', 'out': 'Logging in to [iface: default, target: iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea, portal: 172.16.43.220,3260] (multiple)\nLogin to [iface: default, target: iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea, portal: 172.16.43.220,3260] successful.\n'}) _run_iscsiadm /usr/lib/python2.7/dist-packages/os_brick/initiator/connector.py:658
2016-01-06 10:34:20.076 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260 --op update -n node.startup -v automatic execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:20.140 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260 --op update -n node.startup -v automatic" returned: 0 in 0.065s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:20.141 4326 DEBUG os_brick.initiator.connector [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] ('iscsiadm %(iscsi_command)s: stdout=%(out)s stderr=%(err)s', {'iscsi_command': ('--op', 'update', '-n', 'node.startup', '-v', 'automatic'), 'err': '', 'out': ''}) _run_iscsiadm /usr/lib/python2.7/dist-packages/os_brick/initiator/connector.py:658
2016-01-06 10:34:20.142 4326 DEBUG oslo_concurrency.lockutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Lock "connect_volume" released by "os_brick.initiator.connector.connect_volume" :: held 1.342s inner /usr/lib/python2.7/dist-packages/oslo_concurrency/lockutils.py:265
2016-01-06 10:34:20.143 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf dd if=/dev/disk/by-path/ip-172.16.43.220:3260-iscsi-iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea-lun-0 of=/dev/null count=1 execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:20.211 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf dd if=/dev/disk/by-path/ip-172.16.43.220:3260-iscsi-iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea-lun-0 of=/dev/null count=1" returned: 0 in 0.068s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:20.214 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] curl -g -i -X HEAD -H 'Accept-Encoding: gzip, deflate' -H 'Accept: */*' -H 'User-Agent: python-glanceclient' -H 'Connection: keep-alive' -H 'X-Auth-Token: {SHA1}6e5b33aa7b659269ed978a382a188167b0d84ee8' -H 'Content-Type: application/octet-stream' http://os-controller1:9292/v1/images/9fbef3f3-5172-44e4-a753-9c8c5d684fd5 log_curl_request /usr/lib/python2.7/dist-packages/glanceclient/common/http.py:189
2016-01-06 10:34:20.324 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -]
2016-01-06 10:34:20.325 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] curl -g -i -X HEAD -H 'Accept-Encoding: gzip, deflate' -H 'Accept: */*' -H 'User-Agent: python-glanceclient' -H 'Connection: keep-alive' -H 'X-Auth-Token: {SHA1}6e5b33aa7b659269ed978a382a188167b0d84ee8' -H 'Content-Type: application/octet-stream' http://os-controller1:9292/v1/images/9fbef3f3-5172-44e4-a753-9c8c5d684fd5 log_curl_request /usr/lib/python2.7/dist-packages/glanceclient/common/http.py:189
2016-01-06 10:34:20.351 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -]
2016-01-06 10:34:20.353 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf env LC_ALL=C qemu-img info /var/lib/cinder/conversion/tmptSzfnM execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:20.471 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf env LC_ALL=C qemu-img info /var/lib/cinder/conversion/tmptSzfnM" returned: 0 in 0.118s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:20.474 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] curl -g -i -X HEAD -H 'Accept-Encoding: gzip, deflate' -H 'Accept: */*' -H 'User-Agent: python-glanceclient' -H 'Connection: keep-alive' -H 'X-Auth-Token: {SHA1}6e5b33aa7b659269ed978a382a188167b0d84ee8' -H 'Content-Type: application/octet-stream' http://os-controller1:9292/v1/images/9fbef3f3-5172-44e4-a753-9c8c5d684fd5 log_curl_request /usr/lib/python2.7/dist-packages/glanceclient/common/http.py:189
2016-01-06 10:34:20.504 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -]
2016-01-06 10:34:20.505 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] curl -g -i -X HEAD -H 'Accept-Encoding: gzip, deflate' -H 'Accept: */*' -H 'User-Agent: python-glanceclient' -H 'Connection: keep-alive' -H 'X-Auth-Token: {SHA1}6e5b33aa7b659269ed978a382a188167b0d84ee8' -H 'Content-Type: application/octet-stream' http://os-controller1:9292/v1/images/9fbef3f3-5172-44e4-a753-9c8c5d684fd5 log_curl_request /usr/lib/python2.7/dist-packages/glanceclient/common/http.py:189
2016-01-06 10:34:20.529 4326 DEBUG glanceclient.common.http [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -]
2016-01-06 10:34:20.531 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf env LC_ALL=C qemu-img info /var/lib/cinder/conversion/tmpNdMIMe execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:20.651 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf env LC_ALL=C qemu-img info /var/lib/cinder/conversion/tmpNdMIMe" returned: 0 in 0.120s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:20.653 4326 DEBUG cinder.image.image_utils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] 9fbef3f3-5172-44e4-a753-9c8c5d684fd5 was qcow2, converting to raw fetch_to_volume_format /usr/lib/python2.7/dist-packages/cinder/image/image_utils.py:320
2016-01-06 10:34:20.655 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf dd count=0 if=/var/lib/cinder/conversion/tmpNdMIMe of=/dev/disk/by-path/ip-172.16.43.220:3260-iscsi-iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea-lun-0 oflag=direct execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:20.715 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf dd count=0 if=/var/lib/cinder/conversion/tmpNdMIMe of=/dev/disk/by-path/ip-172.16.43.220:3260-iscsi-iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea-lun-0 oflag=direct" returned: 0 in 0.060s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:20.718 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf qemu-img convert -t none -O raw /var/lib/cinder/conversion/tmpNdMIMe /dev/disk/by-path/ip-172.16.43.220:3260-iscsi-iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea-lun-0 execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:20.839 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf qemu-img convert -t none -O raw /var/lib/cinder/conversion/tmpNdMIMe /dev/disk/by-path/ip-172.16.43.220:3260-iscsi-iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea-lun-0" returned: 1 in 0.121s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:20.840 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] u'sudo cinder-rootwrap /etc/cinder/rootwrap.conf qemu-img convert -t none -O raw /var/lib/cinder/conversion/tmpNdMIMe /dev/disk/by-path/ip-172.16.43.220:3260-iscsi-iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea-lun-0' failed. Not Retrying. execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:308
2016-01-06 10:34:20.841 4326 DEBUG oslo_concurrency.lockutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Lock "connect_volume" acquired by "os_brick.initiator.connector.disconnect_volume" :: waited 0.000s inner /usr/lib/python2.7/dist-packages/oslo_concurrency/lockutils.py:253
2016-01-06 10:34:20.843 4326 DEBUG os_brick.initiator.linuxscsi [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Flushing IO for device /dev/sda flush_device_io /usr/lib/python2.7/dist-packages/os_brick/initiator/linuxscsi.py:131
2016-01-06 10:34:20.844 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf blockdev --flushbufs /dev/sda execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:20.907 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf blockdev --flushbufs /dev/sda" returned: 0 in 0.064s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:20.909 4326 DEBUG os_brick.initiator.linuxscsi [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Remove SCSI device /dev/sda with /sys/block/sda/device/delete remove_scsi_device /usr/lib/python2.7/dist-packages/os_brick/initiator/linuxscsi.py:70
2016-01-06 10:34:20.912 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf tee -a /sys/block/sda/device/delete execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:20.999 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf tee -a /sys/block/sda/device/delete" returned: 0 in 0.087s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:21.000 4326 DEBUG os_brick.initiator.linuxscsi [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Checking to see if SCSI volume /dev/disk/by-path/ip-172.16.43.220:3260-iscsi-iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea-lun-0 has been removed. wait_for_volume_removal /usr/lib/python2.7/dist-packages/os_brick/initiator/linuxscsi.py:78
2016-01-06 10:34:21.001 4326 DEBUG os_brick.initiator.linuxscsi [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] SCSI volume /dev/disk/by-path/ip-172.16.43.220:3260-iscsi-iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea-lun-0 has been removed. wait_for_volume_removal /usr/lib/python2.7/dist-packages/os_brick/initiator/linuxscsi.py:84
2016-01-06 10:34:21.002 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260 --op update -n node.startup -v manual execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:21.071 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260 --op update -n node.startup -v manual" returned: 0 in 0.069s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:21.071 4326 DEBUG os_brick.initiator.connector [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] ('iscsiadm %(iscsi_command)s: stdout=%(out)s stderr=%(err)s', {'iscsi_command': ('--op', 'update', '-n', 'node.startup', '-v', 'manual'), 'err': '', 'out': ''}) _run_iscsiadm /usr/lib/python2.7/dist-packages/os_brick/initiator/connector.py:658
2016-01-06 10:34:21.072 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260 --logout execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:21.628 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260 --logout" returned: 0 in 0.556s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:21.629 4326 DEBUG os_brick.initiator.connector [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] ('iscsiadm %(iscsi_command)s: stdout=%(out)s stderr=%(err)s', {'iscsi_command': ('--logout',), 'err': '', 'out': 'Logging out of session [sid: 3, target: iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea, portal: 172.16.43.220,3260]\nLogout of [sid: 3, target: iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea, portal: 172.16.43.220,3260] successful.\n'}) _run_iscsiadm /usr/lib/python2.7/dist-packages/os_brick/initiator/connector.py:658
2016-01-06 10:34:21.630 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260 --op delete execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:230
2016-01-06 10:34:21.692 4326 DEBUG oslo_concurrency.processutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf iscsiadm -m node -T iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea -p 172.16.43.220:3260 --op delete" returned: 0 in 0.062s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:260
2016-01-06 10:34:21.693 4326 DEBUG os_brick.initiator.connector [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] ('iscsiadm %(iscsi_command)s: stdout=%(out)s stderr=%(err)s', {'iscsi_command': ('--op', 'delete'), 'err': '', 'out': ''}) _run_iscsiadm /usr/lib/python2.7/dist-packages/os_brick/initiator/connector.py:658
2016-01-06 10:34:21.693 4326 DEBUG oslo_concurrency.lockutils [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Lock "connect_volume" released by "os_brick.initiator.connector.disconnect_volume" :: held 0.852s inner /usr/lib/python2.7/dist-packages/oslo_concurrency/lockutils.py:265
2016-01-06 10:34:21.695 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Entering terminate_connection volume=<cinder.db.sqlalchemy.models.Volume object at 0x7f3127f4d090> connector={'initiator': 'iqn.1993-08.org.debian:01:1cd8a3f8d569', 'ip': '172.20.0.41', 'platform': 'x86_64', 'host': 'os-controller1', 'os_type': 'linux2', 'multipath': False} location=172.16.43.220:3260 iqn.2007-11.com.nimblestorage:volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8-v5870296ff3605485.00000018.ab107dea 0.
2016-01-06 10:34:21.699 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Getting getInitiatorGrpList
2016-01-06 10:34:21.751 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Successfully retrieved InitiatorGrpList
2016-01-06 10:34:21.752 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] igroup openstack-sfhaykqdtocz found for initiator iqn.1993-08.org.debian:01:1cd8a3f8d569
2016-01-06 10:34:21.753 4326 INFO cinder.volume.drivers.nimble [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Removing ACL from volume=volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8 for initiator group openstack-sfhaykqdtocz
2016-01-06 10:34:21.803 4326 DEBUG cinder.volume.driver [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] volume 272a1c36-3e97-4215-83d6-681c1bb1e2b8: removing export _detach_volume /usr/lib/python2.7/dist-packages/cinder/volume/driver.py:404
2016-01-06 10:34:21.804 4326 ERROR cinder.volume.flows.manager.create_volume [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Failed to copy image 9fbef3f3-5172-44e4-a753-9c8c5d684fd5 to volume: 272a1c36-3e97-4215-83d6-681c1bb1e2b8
2016-01-06 10:34:21.842 4326 WARNING cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (a45f2a9c-1173-4891-adfc-0dd259d9d31b) transitioned into state 'FAILURE' from state 'RUNNING'
2016-01-06 10:34:21.848 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (a45f2a9c-1173-4891-adfc-0dd259d9d31b) transitioned into state 'REVERTING' from state 'FAILURE' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:190
2016-01-06 10:34:21.850 4326 WARNING cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (a45f2a9c-1173-4891-adfc-0dd259d9d31b) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-01-06 10:34:21.851 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (18beeb56-e226-4b59-87c3-751a808555a3) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:190
2016-01-06 10:34:21.853 4326 WARNING cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (18beeb56-e226-4b59-87c3-751a808555a3) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-01-06 10:34:21.854 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (1ccd1737-c86a-4af1-8222-f1095f3d61e4) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:190
2016-01-06 10:34:21.857 4326 WARNING cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (1ccd1737-c86a-4af1-8222-f1095f3d61e4) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-01-06 10:34:21.862 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (90bcd66e-2d59-467b-bad9-5edc9c2001ca) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:190
2016-01-06 10:34:21.863 4326 DEBUG cinder.volume.flows.common [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Updating volume: 272a1c36-3e97-4215-83d6-681c1bb1e2b8 with {'status': 'error'} due to: ??? _update_object /usr/lib/python2.7/dist-packages/cinder/volume/flows/common.py:87
2016-01-06 10:34:21.918 4326 ERROR cinder.volume.flows.manager.create_volume [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Volume 272a1c36-3e97-4215-83d6-681c1bb1e2b8: create failed
2016-01-06 10:34:21.919 4326 WARNING cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (90bcd66e-2d59-467b-bad9-5edc9c2001ca) transitioned into state 'REVERTED' from state 'REVERTING' with result 'False'
2016-01-06 10:34:21.921 4326 DEBUG cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (86616fc3-6185-465f-9b60-b3179d66fcae) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /usr/lib/python2.7/dist-packages/taskflow/listeners/logging.py:190
2016-01-06 10:34:21.923 4326 WARNING cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (86616fc3-6185-465f-9b60-b3179d66fcae) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-01-06 10:34:21.925 4326 WARNING cinder.volume.manager [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Flow 'volume_create_manager' (7fc66b20-40f0-4812-a79f-08218f6bfbda) transitioned into state 'REVERTED' from state 'RUNNING'
2016-01-06 10:34:21.971 4326 ERROR oslo_messaging.rpc.dispatcher [req-13d962bd-e5f6-471d-90c4-2bd2d855fe2c 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Exception during message handling: Failed to copy image to volume: qemu-img: error writing zeroes at sector 0: Input/output error
2016-01-06 10:34:24.821 4326 DEBUG oslo_concurrency.lockutils [req-c1d7dbb7-7e07-431d-bd13-c56ae4a9e030 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Lock "272a1c36-3e97-4215-83d6-681c1bb1e2b8-delete_volume" acquired by "cinder.volume.manager.lvo_inner2" :: waited 0.001s inner /usr/lib/python2.7/dist-packages/oslo_concurrency/lockutils.py:253
2016-01-06 10:34:24.965 4326 INFO cinder.volume.drivers.nimble [req-c1d7dbb7-7e07-431d-bd13-c56ae4a9e030 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Setting volume volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8 to online_flag False
2016-01-06 10:34:25.043 4326 INFO cinder.volume.drivers.nimble [req-c1d7dbb7-7e07-431d-bd13-c56ae4a9e030 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Dissociating volume volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8
2016-01-06 10:34:25.123 4326 INFO cinder.volume.drivers.nimble [req-c1d7dbb7-7e07-431d-bd13-c56ae4a9e030 437f69f2cee64d4bbd886eba8f8daaf9 158397de288a45bc8adbd97f908ef5fa - - -] Deleting volume volume-272a1c36-3e97-4215-83d6-681c1bb1e2b8
root@os-controller1:~#
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment