Creating Volume from Image Fails with iSCSI QNAP Driver

Bug #1845821 reported by Vess Bakalov
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
Cinder
New
Undecided
Unassigned

Bug Description

Hello. I am able to make empty volumes through horizon, but whenever I try to build them based on Images, it fails. They show up as errored in Horizon I can see the volumes created on the NAS.

Here is the output - just running the command on the CLI. Here I am using a RAW image. I have also tried qcow.

root@juju-09c456-2-lxd-7:/home/ubuntu# cinder create --image-id b5386adc-2e43-44fd-a773-cf98d70b80ea 5
+--------------------------------+--------------------------------------+
| Property | Value |
+--------------------------------+--------------------------------------+
| attachments | [] |
| availability_zone | nova |
| bootable | false |
| consistencygroup_id | None |
| created_at | 2019-09-29T02:30:12.000000 |
| description | None |
| encrypted | False |
| id | f48146eb-4c5a-4336-959c-0c5942e7aa26 |
| metadata | {} |
| migration_status | None |
| multiattach | False |
| name | None |
| os-vol-host-attr:host | None |
| os-vol-mig-status-attr:migstat | None |
| os-vol-mig-status-attr:name_id | None |
| os-vol-tenant-attr:tenant_id | f4045747c3ce4c189a274204d85d9e98 |
| replication_status | None |
| size | 5 |
| snapshot_id | None |
| source_volid | None |
| status | creating |
| updated_at | None |
| user_id | d94e082fd0de4dbf81f20e33c4cd4d1d |
| volume_type | None |
+--------------------------------+--------------------------------------+

Here is the log:

2019-09-29 02:44:12.088 27956 INFO cinder.rpc [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Automatically selected cinder-backup objects version 1.35 as minimum service version.
2019-09-29 02:44:12.094 27956 INFO cinder.rpc [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Automatically selected cinder-backup RPC version 2.1 as minimum service version.
2019-09-29 02:44:12.103 27956 INFO cinder.rpc [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Automatically selected cinder-volume objects version 1.35 as minimum service version.
2019-09-29 02:44:12.110 27956 INFO cinder.rpc [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Automatically selected cinder-volume RPC version 3.15 as minimum service version.
2019-09-29 02:44:12.366 27956 INFO cinder.volume.flows.manager.create_volume [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Volume 8d7719f5-7cbd-49b2-ab8e-cf35920f0090: being created as image with specification: {'status': u'creating', 'image_location': (None, None), 'volume_size': 5, 'volume_name': u'volume-8d7719f5-7cbd-49b2-ab8e-cf35920f0090', 'image_id': u'b5386adc-2e43-44fd-a773-cf98d70b80ea', 'image_service': <cinder.image.glance.GlanceImageService object at 0x7fee2f7c5850>, 'image_meta': {u'status': u'active', u'name': u'debian_10_RAW', u'tags': [], u'container_format': u'bare', u'created_at': datetime.datetime(2019, 9, 27, 20, 59, 6, tzinfo=<iso8601.Utc>), u'disk_format': u'raw', u'updated_at': datetime.datetime(2019, 9, 27, 20, 59, 26, tzinfo=<iso8601.Utc>), u'visibility': u'public', 'properties': {u'architecture': u'amd64'}, u'owner': u'f4045747c3ce4c189a274204d85d9e98', u'protected': False, u'id': u'b5386adc-2e43-44fd-a773-cf98d70b80ea', u'file': u'/v2/images/b5386adc-2e43-44fd-a773-cf98d70b80ea/file', u'checksum': u'0a419c0af9fd44ab2b61d3280473bb3a', u'min_disk': 1, u'virtual_size': None, u'min_ram': 256, u'size': 2147483648}}
2019-09-29 02:44:29.548 27956 INFO cinder.image.image_utils [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Image download 2048.00 MB at 119.23 MB/s
2019-09-29 02:44:42.950 27956 INFO oslo.privsep.daemon [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Running privsep helper: ['sudo', 'cinder-rootwrap', '/etc/cinder/rootwrap.conf', 'privsep-helper', '--config-file', '/etc/cinder/cinder.conf', '--privsep_context', 'os_brick.privileged.default', '--privsep_sock_path', '/tmp/tmpChfbNz/privsep.sock']
2019-09-29 02:44:43.709 27956 INFO oslo.privsep.daemon [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Spawned new privsep daemon via rootwrap
2019-09-29 02:44:43.653 28008 INFO oslo.privsep.daemon [-] privsep daemon starting
2019-09-29 02:44:43.656 28008 INFO oslo.privsep.daemon [-] privsep process running with uid/gid: 0/0
2019-09-29 02:44:43.660 28008 INFO oslo.privsep.daemon [-] privsep process running with capabilities (eff/prm/inh): CAP_SYS_ADMIN/CAP_SYS_ADMIN/none
2019-09-29 02:44:43.660 28008 INFO oslo.privsep.daemon [-] privsep daemon running as pid 28008
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Failed to copy image b5386adc-2e43-44fd-a773-cf98d70b80ea to volume: 8d7719f5-7cbd-49b2-ab8e-cf35920f0090: KeyError: 'initiator'
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume Traceback (most recent call last):
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume File "/usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py", line 528, in _copy_image_to_volume
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume context, volume, image_service, image_id)
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume File "/usr/lib/python2.7/dist-packages/cinder/volume/driver.py", line 800, in copy_image_to_volume
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume context, volume, image_service, image_id, encrypted=False)
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume File "/usr/lib/python2.7/dist-packages/cinder/volume/driver.py", line 820, in _copy_image_data_to_volume
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume attach_info, volume = self._attach_volume(context, volume, properties)
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume File "/usr/lib/python2.7/dist-packages/oslo_concurrency/lockutils.py", line 277, in inner
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume return f(*args, **kwargs)
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/qnap.py", line 1148, in _attach_volume
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume properties, remote)
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume File "/usr/lib/python2.7/dist-packages/cinder/volume/driver.py", line 972, in _attach_volume
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume model_update = self.create_export(context, volume, properties)
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume File "/usr/lib/python2.7/dist-packages/oslo_concurrency/lockutils.py", line 277, in inner
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume return f(*args, **kwargs)
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/qnap.py", line 892, in create_export
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume LOG.debug('connector: %s', connector['initiator'])
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume KeyError: 'initiator'
2019-09-29 02:44:44.106 27956 ERROR cinder.volume.flows.manager.create_volume
2019-09-29 02:44:44.414 27956 WARNING cinder.volume.manager [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (00bdc385-0d11-4b99-b41f-0ba623f540f5) transitioned into state 'FAILURE' from state 'RUNNING'
5 predecessors (most recent first):
  Atom 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' {'intention': 'EXECUTE', 'state': 'SUCCESS', 'requires': {'volume': Volume(_name_id=None,admin_metadata={},attach_status='detached',availability_zone='nova',bootable=False,cluster=<?>,cluster_name=None,consistencygroup=<?>,consistencygroup_id=None,created_at=2019-09-29T02:44:12Z,deleted=False,deleted_at=None,display_description=None,display_name=None,ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='juju-09c456-2-lxd-7@QCinderDriver1#1',id=8d7719f5-7cbd-49b2-ab8e-cf35920f0090,launched_at=None,metadata={LUNIndex='5',LUNNAA='6e843b6e4d97cf1d4fa1d4d38d977ed8',LunName='cinder-20190929024430093727'},migration_status=None,multiattach=False,previous_status=None,project_id='f4045747c3ce4c189a274204d85d9e98',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status=None,scheduled_at=2019-09-29T02:44:12Z,service_uuid=None,shared_targets=True,size=5,snapshot_id=None,snapshots=<?>,source_volid=None,status='downloading',terminated_at=None,updated_at=2019-09-29T02:44:12Z,user_id='d94e082fd0de4dbf81f20e33c4cd4d1d',volume_attachment=<?>,volume_type=None,volume_type_id=None), 'context': <cinder.context.RequestContext object at 0x7fee2f9da090>}, 'provides': None}
  |__Atom 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' {'intention': 'EXECUTE', 'state': 'SUCCESS', 'requires': {'volume': Volume(_name_id=None,admin_metadata={},attach_status='detached',availability_zone='nova',bootable=False,cluster=<?>,cluster_name=None,consistencygroup=<?>,consistencygroup_id=None,created_at=2019-09-29T02:44:12Z,deleted=False,deleted_at=None,display_description=None,display_name=None,ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='juju-09c456-2-lxd-7@QCinderDriver1#1',id=8d7719f5-7cbd-49b2-ab8e-cf35920f0090,launched_at=None,metadata={LUNIndex='5',LUNNAA='6e843b6e4d97cf1d4fa1d4d38d977ed8',LunName='cinder-20190929024430093727'},migration_status=None,multiattach=False,previous_status=None,project_id='f4045747c3ce4c189a274204d85d9e98',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status=None,scheduled_at=2019-09-29T02:44:12Z,service_uuid=None,shared_targets=True,size=5,snapshot_id=None,snapshots=<?>,source_volid=None,status='downloading',terminated_at=None,updated_at=2019-09-29T02:44:12Z,user_id='d94e082fd0de4dbf81f20e33c4cd4d1d',volume_attachment=<?>,volume_type=None,volume_type_id=None), 'request_spec': RequestSpec(CG_backend=<?>,backup_id=None,cgsnapshot_id=None,consistencygroup_id=None,group_backend=<?>,group_id=None,image_id=b5386adc-2e43-44fd-a773-cf98d70b80ea,resource_backend=<?>,snapshot_id=None,source_replicaid=<?>,source_volid=None,volume=Volume(8d7719f5-7cbd-49b2-ab8e-cf35920f0090),volume_id=8d7719f5-7cbd-49b2-ab8e-cf35920f0090,volume_properties=VolumeProperties,volume_type=None), 'context': <cinder.context.RequestContext object at 0x7fee2f9da090>}, 'provides': {'status': u'creating', 'image_location': (None, None), 'image_id': u'b5386adc-2e43-44fd-a773-cf98d70b80ea', 'volume_id': u'8d7719f5-7cbd-49b2-ab8e-cf35920f0090', 'volume_size': 5, 'image_service': <cinder.image.glance.GlanceImageService object at 0x7fee2f7c5850>, 'image_meta': {u'status': u'active', u'name': u'debian_10_RAW', u'tags': [], u'container_format': u'bare', u'created_at': datetime.datetime(2019, 9, 27, 20, 59, 6, tzinfo=<iso8601.Utc>), u'disk_format': u'raw', u'updated_at': datetime.datetime(2019, 9, 27, 20, 59, 26, tzinfo=<iso8601.Utc>), u'visibility': u'public', 'properties': {u'architecture': u'amd64'}, u'owner': u'f4045747c3ce4c189a274204d85d9e98', u'protected': False, u'id': u'b5386adc-2e43-44fd-a773-cf98d70b80ea', u'file': u'/v2/images/b5386adc-2e43-44fd-a773-cf98d70b80ea/file', u'checksum': u'0a419c0af9fd44ab2b61d3280473bb3a', u'min_disk': 1, u'virtual_size': None, u'min_ram': 256, u'size': 2147483648}, 'volume_name': u'volume-8d7719f5-7cbd-49b2-ab8e-cf35920f0090', 'type': 'image'}}
     |__Atom 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' {'intention': 'EXECUTE', 'state': 'SUCCESS', 'requires': {'volume': Volume(_name_id=None,admin_metadata={},attach_status='detached',availability_zone='nova',bootable=False,cluster=<?>,cluster_name=None,consistencygroup=<?>,consistencygroup_id=None,created_at=2019-09-29T02:44:12Z,deleted=False,deleted_at=None,display_description=None,display_name=None,ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='juju-09c456-2-lxd-7@QCinderDriver1#1',id=8d7719f5-7cbd-49b2-ab8e-cf35920f0090,launched_at=None,metadata={LUNIndex='5',LUNNAA='6e843b6e4d97cf1d4fa1d4d38d977ed8',LunName='cinder-20190929024430093727'},migration_status=None,multiattach=False,previous_status=None,project_id='f4045747c3ce4c189a274204d85d9e98',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status=None,scheduled_at=2019-09-29T02:44:12Z,service_uuid=None,shared_targets=True,size=5,snapshot_id=None,snapshots=<?>,source_volid=None,status='downloading',terminated_at=None,updated_at=2019-09-29T02:44:12Z,user_id='d94e082fd0de4dbf81f20e33c4cd4d1d',volume_attachment=<?>,volume_type=None,volume_type_id=None), 'filter_properties': {u'config_options': {}, u'request_spec': {u'backup_id': None, u'volume_properties': {u'status': u'creating', u'volume_type_id': None, u'project_id': u'f4045747c3ce4c189a274204d85d9e98', u'user_id': u'd94e082fd0de4dbf81f20e33c4cd4d1d', u'availability_zone': u'nova', u'reservations': [u'037246cc-b433-4a67-ba04-30b42ae667fa', u'ffae6a7c-25c0-4082-a600-828857734532'], u'multiattach': False, u'attach_status': u'detached', u'source_volid': None, u'cgsnapshot_id': None, u'metadata': {}, u'qos_specs': None, u'encryption_key_id': None, u'display_description': None, u'snapshot_id': None, u'display_name': None, u'group_id': None, u'consistencygroup_id': None, u'size': 5}, u'source_volid': None, u'cgsnapshot_id': None, u'volume': {u'migration_status': None, u'provider_id': None, u'availability_zone': u'nova', u'terminated_at': None, u'updated_at': None, u'provider_geometry': None, u'replication_extended_status': None, u'replication_status': None, u'snapshot_id': None, u'ec2_id': None, u'deleted_at': None, u'id': u'8d7719f5-7cbd-49b2-ab8e-cf35920f0090', u'size': 5, u'display_name': None, u'display_description': None, u'cluster_name': None, u'name_id': u'8d7719f5-7cbd-49b2-ab8e-cf35920f0090', u'volume_admin_metadata': [], u'project_id': u'f4045747c3ce4c189a274204d85d9e98', u'launched_at': None, u'scheduled_at': None, u'status': u'creating', u'volume_type_id': None, u'multiattach': False, u'deleted': False, u'service_uuid': None, u'provider_location': None, u'volume_glance_metadata': [], u'host': None, u'consistencygroup_id': None, u'source_volid': None, u'provider_auth': None, u'previous_status': None, u'group_id': None, u'name': u'volume-8d7719f5-7cbd-49b2-ab8e-cf35920f0090', u'user_id': u'd94e082fd0de4dbf81f20e33c4cd4d1d', u'bootable': False, u'shared_targets': True, u'attach_status': u'detached', u'volume_metadata': [], u'_name_id': None, u'encryption_key_id': None, u'replication_driver_data': None, u'metadata': {}, u'created_at': u'2019-09-29T02:44:11.000000'}, u'image_id': u'b5386adc-2e43-44fd-a773-cf98d70b80ea', u'snapshot_id': None, u'consistencygroup_id': None, u'volume_type': None, u'volume_id': u'8d7719f5-7cbd-49b2-ab8e-cf35920f0090', u'resource_properties': {u'status': u'creating', u'volume_type_id': None, u'project_id': u'f4045747c3ce4c189a274204d85d9e98', u'user_id': u'd94e082fd0de4dbf81f20e33c4cd4d1d', u'availability_zone': u'nova', u'reservations': [u'037246cc-b433-4a67-ba04-30b42ae667fa', u'ffae6a7c-25c0-4082-a600-828857734532'], u'multiattach': False, u'attach_status': u'detached', u'source_volid': None, u'cgsnapshot_id': None, u'metadata': {}, u'qos_specs': None, u'encryption_key_id': None, u'display_description': None, u'snapshot_id': None, u'display_name': None, u'group_id': None, u'consistencygroup_id': None, u'size': 5}, u'group_id': None}, u'user_id': u'd94e082fd0de4dbf81f20e33c4cd4d1d', u'availability_zone': u'nova', u'volume_type': None, u'qos_specs': None, u'retry': {u'num_attempts': 1, u'backends': [u'juju-09c456-2-lxd-7@QCinderDriver1#1'], u'hosts': [u'juju-09c456-2-lxd-7@QCinderDriver1#1']}, u'metadata': {}, u'resource_type': {}, u'size': 5}, 'context': <cinder.context.RequestContext object at 0x7fee2f9da090>, 'request_spec': RequestSpec(CG_backend=<?>,backup_id=None,cgsnapshot_id=None,consistencygroup_id=None,group_backend=<?>,group_id=None,image_id=b5386adc-2e43-44fd-a773-cf98d70b80ea,resource_backend=<?>,snapshot_id=None,source_replicaid=<?>,source_volid=None,volume=Volume(8d7719f5-7cbd-49b2-ab8e-cf35920f0090),volume_id=8d7719f5-7cbd-49b2-ab8e-cf35920f0090,volume_properties=VolumeProperties,volume_type=None)}, 'provides': None}
        |__Atom 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' {'intention': 'EXECUTE', 'state': 'SUCCESS', 'requires': {'volume': Volume(_name_id=None,admin_metadata={},attach_status='detached',availability_zone='nova',bootable=False,cluster=<?>,cluster_name=None,consistencygroup=<?>,consistencygroup_id=None,created_at=2019-09-29T02:44:12Z,deleted=False,deleted_at=None,display_description=None,display_name=None,ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='juju-09c456-2-lxd-7@QCinderDriver1#1',id=8d7719f5-7cbd-49b2-ab8e-cf35920f0090,launched_at=None,metadata={LUNIndex='5',LUNNAA='6e843b6e4d97cf1d4fa1d4d38d977ed8',LunName='cinder-20190929024430093727'},migration_status=None,multiattach=False,previous_status=None,project_id='f4045747c3ce4c189a274204d85d9e98',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status=None,scheduled_at=2019-09-29T02:44:12Z,service_uuid=None,shared_targets=True,size=5,snapshot_id=None,snapshots=<?>,source_volid=None,status='downloading',terminated_at=None,updated_at=2019-09-29T02:44:12Z,user_id='d94e082fd0de4dbf81f20e33c4cd4d1d',volume_attachment=<?>,volume_type=None,volume_type_id=None), 'context': <cinder.context.RequestContext object at 0x7fee2f9da090>}, 'provides': Volume(_name_id=None,admin_metadata={},attach_status='detached',availability_zone='nova',bootable=False,cluster=<?>,cluster_name=None,consistencygroup=<?>,consistencygroup_id=None,created_at=2019-09-29T02:44:12Z,deleted=False,deleted_at=None,display_description=None,display_name=None,ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='juju-09c456-2-lxd-7@QCinderDriver1#1',id=8d7719f5-7cbd-49b2-ab8e-cf35920f0090,launched_at=None,metadata={LUNIndex='5',LUNNAA='6e843b6e4d97cf1d4fa1d4d38d977ed8',LunName='cinder-20190929024430093727'},migration_status=None,multiattach=False,previous_status=None,project_id='f4045747c3ce4c189a274204d85d9e98',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status=None,scheduled_at=2019-09-29T02:44:12Z,service_uuid=None,shared_targets=True,size=5,snapshot_id=None,snapshots=<?>,source_volid=None,status='downloading',terminated_at=None,updated_at=2019-09-29T02:44:12Z,user_id='d94e082fd0de4dbf81f20e33c4cd4d1d',volume_attachment=<?>,volume_type=None,volume_type_id=None)}
           |__Flow 'volume_create_manager': ImageCopyFailure: Failed to copy image to volume: 'initiator'
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager Traceback (most recent call last):
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager File "/usr/lib/python2.7/dist-packages/taskflow/engines/action_engine/executor.py", line 53, in _execute_task
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager result = task.execute(**arguments)
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager File "/usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py", line 1020, in execute
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager **volume_spec)
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager File "/usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py", line 929, in _create_from_image
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager image_service)
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager File "/usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py", line 830, in _create_from_image_cache_or_download
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager image_service
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager File "/usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py", line 671, in _create_from_image_download
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager image_location, image_service)
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager File "/usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py", line 548, in _copy_image_to_volume
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager raise exception.ImageCopyFailure(reason=ex)
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager ImageCopyFailure: Failed to copy image to volume: 'initiator'
2019-09-29 02:44:44.414 27956 ERROR cinder.volume.manager
2019-09-29 02:44:44.420 27956 WARNING cinder.volume.manager [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (00bdc385-0d11-4b99-b41f-0ba623f540f5) transitioned into state 'REVERTED' from state 'REVERTING'
2019-09-29 02:44:44.424 27956 WARNING cinder.volume.manager [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (c192426a-5d54-4698-a2ed-18937d459709) transitioned into state 'REVERTED' from state 'REVERTING'
2019-09-29 02:44:44.427 27956 WARNING cinder.volume.manager [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (f9c61408-32cc-41e0-b4c4-dd7ec96d8426) transitioned into state 'REVERTED' from state 'REVERTING'
2019-09-29 02:44:44.450 27956 ERROR cinder.volume.flows.manager.create_volume [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Volume 8d7719f5-7cbd-49b2-ab8e-cf35920f0090: create failed
2019-09-29 02:44:44.452 27956 WARNING cinder.volume.manager [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (3245666a-6293-41c1-bd46-c58dd47f0dcd) transitioned into state 'REVERTED' from state 'REVERTING'
2019-09-29 02:44:44.455 27956 WARNING cinder.volume.manager [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (bd6ec1f8-25d0-4dca-a78d-fcd0e8beba37) transitioned into state 'REVERTED' from state 'REVERTING'
2019-09-29 02:44:44.458 27956 WARNING cinder.volume.manager [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Flow 'volume_create_manager' (71a763e8-6507-4b2c-b40f-f52e6bc0dc86) transitioned into state 'REVERTED' from state 'RUNNING'
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server [req-821d94fd-7ba2-4bca-8c44-d419cbe67c62 d94e082fd0de4dbf81f20e33c4cd4d1d f4045747c3ce4c189a274204d85d9e98 - 52d1dfcbf1774a2889bfd9c1f16c5d15 52d1dfcbf1774a2889bfd9c1f16c5d15] Exception during message handling: ImageCopyFailure: Failed to copy image to volume: 'initiator'
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server Traceback (most recent call last):
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/oslo_messaging/rpc/server.py", line 163, in _process_incoming
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server res = self.dispatcher.dispatch(message)
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/oslo_messaging/rpc/dispatcher.py", line 220, in dispatch
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server return self._do_dispatch(endpoint, method, ctxt, args)
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/oslo_messaging/rpc/dispatcher.py", line 190, in _do_dispatch
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server result = func(ctxt, **new_args)
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "<decorator-gen-239>", line 2, in create_volume
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/cinder/objects/cleanable.py", line 207, in wrapper
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server result = f(*args, **kwargs)
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/cinder/volume/manager.py", line 690, in create_volume
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server _run_flow()
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/cinder/volume/manager.py", line 682, in _run_flow
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server flow_engine.run()
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/taskflow/engines/action_engine/engine.py", line 247, in run
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server for _state in self.run_iter(timeout=timeout):
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/taskflow/engines/action_engine/engine.py", line 340, in run_iter
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server failure.Failure.reraise_if_any(er_failures)
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/taskflow/types/failure.py", line 336, in reraise_if_any
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server failures[0].reraise()
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/taskflow/types/failure.py", line 343, in reraise
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server six.reraise(*self._exc_info)
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/taskflow/engines/action_engine/executor.py", line 53, in _execute_task
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server result = task.execute(**arguments)
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py", line 1020, in execute
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server **volume_spec)
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py", line 929, in _create_from_image
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server image_service)
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py", line 830, in _create_from_image_cache_or_download
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server image_service
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py", line 671, in _create_from_image_download
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server image_location, image_service)
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server File "/usr/lib/python2.7/dist-packages/cinder/volume/flows/manager/create_volume.py", line 548, in _copy_image_to_volume
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server raise exception.ImageCopyFailure(reason=ex)
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server ImageCopyFailure: Failed to copy image to volume: 'initiator'
2019-09-29 02:44:44.493 27956 ERROR oslo_messaging.rpc.server

Here is my cinder config:

[DEFAULT]
rootwrap_config = /etc/cinder/rootwrap.conf
api_paste_confg = /etc/cinder/api-paste.ini
iscsi_helper = tgtadm
verbose = True
debug = False
use_syslog = False
auth_strategy = keystone
state_path = /var/lib/cinder
osapi_volume_workers = 4
transport_url = rabbit://cinder:htzKdthNYVHKXJ5zdrZTf5cnhtwV8y6PhmWhGCnF66MPPVKW7zkCGMGPRg7rzBGj@192.168.22.82:5672/openstack
osapi_volume_listen = 0.0.0.0
osapi_volume_listen_port = 8766
glance_api_servers = http://192.168.22.87:9292
glance_api_version = 2
os_region_name = RegionOne
volume_usage_audit_period = month

enabled_backends = QCinderDriver1

[keystone_authtoken]
auth_type = password
auth_uri = http://192.168.22.81:5000/v3
auth_url = http://192.168.22.81:35357/v3
project_domain_name = service_domain
user_domain_name = service_domain
project_name = services
username = cinderv2_cinderv3
password = xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
signing_dir = /var/cache/cinder
memcached_servers = inet6:[::1]:11211

[database]
connection = mysql+pymysql://cinder:dnnXCMB2STTM4r4YkSB6YYPXhVzBRhxS@192.168.22.80/cinder

[QCinderDriver1]
volume_backend_name=QCinderDriver1
volume_driver=cinder.volume.drivers.qnap.QnapISCSIDriver
qnap_management_url = http://192.168.22.11:8080
# Since Queens release, replace option iscsi_ip_address with target_ip_address
#iscsi_ip_address=192.168.22.11
target_ip_address=192.168.22.11
qnap_storage_protocol=iscsi
san_login=admin
san_password=xxxxxxxxxxx
qnap_poolname=Storage Pool 1
use_chap_auth=false
qnap_thin_provision = False

[oslo_messaging_rabbit]
[oslo_messaging_notifications]
driver = messagingv2
transport_url = rabbit://cinder:htzKdthNYVHKXJ5zdrZTf5cnhtwV8y6PhmWhGCnF66MPPVKW7zkCGMGPRg7rzBGj@192.168.22.82:5672/openstack

[oslo_concurrency]
lock_path = /var/lock/cinder

[keymgr]
# XXX: hack to work around http://pad.lv/1516085
# will be superseded by SRU to cinder package
encryption_auth_url = http://192.168.22.81:5000/v3

[oslo_middleware]
# Bug #1758675
enable_proxy_headers_parsing = true

Vess Bakalov (codr1)
description: updated
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.