Revision history [back]

click to hide/show revision 1
initial version

2013-10-24 20:24:20 DEBUG [cinder.utils] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf vgs --noheadings --nosuffix --unit=G -o name,size,free cinder-volumes 2013-10-24 20:24:20 DEBUG [cinder.manager] Notifying Schedulers of capabilities ... 2013-10-24 20:24:20 DEBUG [cinder.openstack.common.rpc.amqp] Making asynchronous fanout cast... 2013-10-24 20:24:20 DEBUG [cinder.openstack.common.rpc.amqp] UNIQUE_ID is b22a669463ce4ad49613ab6d60b599c4. 2013-10-24 20:24:20 DEBUG [cinder.openstack.common.rpc.amqp] Pool creating new connection 2013-10-24 20:24:20 INFO [cinder.openstack.common.rpc.common] Connected to AMQP server on localhost:5672 2013-10-24 20:24:20 INFO [cinder.openstack.common.rpc.common] Connected to AMQP server on localhost:5672 2013-10-24 20:24:20 DEBUG [cinder.service] Creating Consumer connection for Service cinder-volume 2013-10-24 20:24:45 DEBUG [cinder.openstack.common.rpc.amqp] received {u'_context_roles': [u'_member_', u'Member', u'admin'], u'_context_request_id': u'req-dc624558-e49a-4e3d-b9a2-16f5bd9babfd', u'_context_quota_class': None, u'_unique_id': u'e0295e309aaa4b1d8a48c434be8a0307', u'args': {u'request_spec': {u'volume_id': u'7ebff319-838a-4f09-807b-372be8b26c13', u'volume_properties': {u'status': u'creating', u'volume_type_id': None, u'display_name': u'12', u'availability_zone': u'nova', u'size': 1, u'attach_status': u'detached', u'source_volid': None, u'volume_metadata': [], u'display_description': u'', u'snapshot_id': None, u'user_id': u'90b47b1766924e078ca9fc03e5153fd0', u'project_id': u'f822eef7155046a68d20d71f3c37ac43', u'id': u'7ebff319-838a-4f09-807b-372be8b26c13', u'metadata': {}}, u'source_volid': None, u'image_id': None, u'volume_type': {}, u'snapshot_id': None, u'resource_properties': {u'status': u'creating', u'volume_type_id': None, u'display_name': u'12', u'availability_zone': u'nova', u'attach_status': u'detached', u'source_volid': None, u'metadata': {}, u'volume_metadata': [], u'display_description': u'', u'snapshot_id': None, u'user_id': u'90b47b1766924e078ca9fc03e5153fd0', u'project_id': u'f822eef7155046a68d20d71f3c37ac43', u'id': u'7ebff319-838a-4f09-807b-372be8b26c13', u'size': 1}}, u'volume_id': u'7ebff319-838a-4f09-807b-372be8b26c13', u'allow_reschedule': True, u'filter_properties': {u'request_spec': {u'volume_id': u'7ebff319-838a-4f09-807b-372be8b26c13', u'volume_properties': {u'status': u'creating', u'volume_type_id': None, u'display_name': u'12', u'availability_zone': u'nova', u'size': 1, u'attach_status': u'detached', u'source_volid': None, u'volume_metadata': [], u'display_description': u'', u'snapshot_id': None, u'user_id': u'90b47b1766924e078ca9fc03e5153fd0', u'project_id': u'f822eef7155046a68d20d71f3c37ac43', u'id': u'7ebff319-838a-4f09-807b-372be8b26c13', u'metadata': {}}, u'source_volid': None, u'image_id': None, u'volume_type': {}, u'snapshot_id': None, u'resource_properties': {u'status': u'creating', u'volume_type_id': None, u'display_name': u'12', u'availability_zone': u'nova', u'attach_status': u'detached', u'source_volid': None, u'metadata': {}, u'volume_metadata': [], u'display_description': u'', u'snapshot_id': None, u'user_id': u'90b47b1766924e078ca9fc03e5153fd0', u'project_id': u'f822eef7155046a68d20d71f3c37ac43', u'id': u'7ebff319-838a-4f09-807b-372be8b26c13', u'size': 1}}, u'user_id': u'90b47b1766924e078ca9fc03e5153fd0', u'availability_zone': u'nova', u'volume_type': {}, u'config_options': {}, u'retry': {u'num_attempts': 1, u'hosts': [u'SDE-main-controller']}, u'size': 1, u'resource_type': {}, u'metadata': {}}, u'source_volid': None, u'image_id': None, u'snapshot_id': None}, u'_context_tenant': u'f822eef7155046a68d20d71f3c37ac43', u'_context_auth_token': '<sanitized>', u'_context_timestamp': u'2013-10-24T12:24:44.857271', u'_context_is_admin': False, u'version': u'1.4', u'_context_project_id': u'f822eef7155046a68d20d71f3c37ac43', u'_context_user': u'90b47b1766924e078ca9fc03e5153fd0', u'_context_read_deleted': u'no', u'_context_user_id': u'90b47b1766924e078ca9fc03e5153fd0', u'method': u'create_volume', u'_context_remote_address': u'9.186.91.128'} 2013-10-24 20:24:45 DEBUG [cinder.openstack.common.rpc.amqp] unpacked context: {'user_id': u'90b47b1766924e078ca9fc03e5153fd0', 'roles': [u'_member_', u'Member', u'admin'], 'timestamp': u'2013-10-24T12:24:44.857271', 'auth_token': '<sanitized>', 'remote_address': u'9.186.91.128', 'quota_class': None, 'is_admin': False, 'user': u'90b47b1766924e078ca9fc03e5153fd0', 'request_id': u'req-dc624558-e49a-4e3d-b9a2-16f5bd9babfd', 'project_id': u'f822eef7155046a68d20d71f3c37ac43', 'read_deleted': u'no', 'tenant': u'f822eef7155046a68d20d71f3c37ac43'} 2013-10-24 20:24:45 DEBUG [cinder.volume.manager] volume volume-7ebff319-838a-4f09-807b-372be8b26c13: creating lv of size 1G 2013-10-24 20:24:45 INFO [cinder.volume.manager] volume volume-7ebff319-838a-4f09-807b-372be8b26c13: creating 2013-10-24 20:24:45 DEBUG [cinder.utils] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf lvcreate -L 1G -n volume-7ebff319-838a-4f09-807b-372be8b26c13 cinder-volumes 2013-10-24 20:24:45 DEBUG [cinder.volume.manager] volume volume-7ebff319-838a-4f09-807b-372be8b26c13: creating export 2013-10-24 20:24:45 DEBUG [cinder.utils] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf ietadm --op new --tid=1 --params Name=iqn.2010-10.org.openstack:volume-7ebff319-838a-4f09-807b-372be8b26c13 2013-10-24 20:24:45 DEBUG [cinder.utils] Result was 239 2013-10-24 20:24:45 ERROR [cinder.volume.manager] volume volume-7ebff319-838a-4f09-807b-372be8b26c13: create failed 2013-10-24 20:24:45 ERROR [cinder.openstack.common.rpc.amqp] Exception during message handling Traceback (most recent call last): File "/usr/lib/python2.7/dist-packages/cinder/openstack/common/rpc/amqp.py", line 430, in _process_data rval = self.proxy.dispatch(ctxt, version, method, *args) File "/usr/lib/python2.7/dist-packages/cinder/openstack/common/rpc/dispatcher.py", line 133, in dispatch return getattr(proxyobj, method)(ctxt, *kwargs) File "/usr/lib/python2.7/dist-packages/cinder/volume/manager.py", line 288, in create_volume LOG.error(_("volume %s: create failed"), volume_ref['name']) File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ self.gen.next() File "/usr/lib/python2.7/dist-packages/cinder/volume/manager.py", line 281, in create_volume model_update = self.driver.create_export(context, volume_ref) File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/lvm.py", line 496, in create_export chap_auth) File "/usr/lib/python2.7/dist-packages/cinder/volume/iscsi.py", line 225, in create_iscsi_target self._new_target(name, tid, *kwargs) File "/usr/lib/python2.7/dist-packages/cinder/volume/iscsi.py", line 284, in _new_target *kwargs) File "/usr/lib/python2.7/dist-packages/cinder/volume/iscsi.py", line 73, in _run self._execute(self._cmd, args, run_as_root=True, *kwargs) File "/usr/lib/python2.7/dist-packages/cinder/utils.py", line 190, in execute cmd=' '.join(cmd)) ProcessExecutionError: Unexpected error while running command. Command: sudo cinder-rootwrap /etc/cinder/rootwrap.conf ietadm --op new --tid=1 --params Name=iqn.2010-10.org.openstack:volume-7ebff319-838a-4f09-807b-372be8b26c13 Exit code: 239 Stdout: '' Stderr: 'File exists.\n' 2013-10-24 20:24:46 DEBUG [cinder.manager] Running periodic task VolumeManager._publish_service_capabilities 2013-10-24 20:24:46 DEBUG [cinder.manager] Notifying Schedulers of capabilities ... 2013-10-24 20:24:46 DEBUG [cinder.openstack.common.rpc.amqp] Making asynchronous fanout cast... 2013-10-24 20:24:46 DEBUG [cinder.openstack.common.rpc.amqp] UNIQUE_ID is b07e3443f21f420faab4a0a17769f8dd. 2013-10-24 20:24:46 DEBUG [cinder.manager] Running periodic task VolumeManager._report_driver_status 2013-10-24 20:24:46 INFO [cinder.volume.manager] Updating volume status 2013-10-24 20:24:46 DEBUG [cinder.volume.drivers.lvm] Updating volume status 2013-10-24 20:24:46 DEBUG [cinder.utils] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf vgs --noheadings --nosuffix --unit=G -o name,size,free cinder-volumes 2013-10-24 20:25:46 DEBUG [cinder.manager] Running periodic task VolumeManager._publish_service_capabilities 2013-10-24 20:25:46 DEBUG [cinder.manager] Notifying Schedulers of capabilities ... 2013-10-24 20:25:46 DEBUG [cinder.openstack.common.rpc.amqp] Making asynchronous fanout cast... 2013-10-24 20:25:46 DEBUG [cinder.openstack.common.rpc.amqp] UNIQUE_ID is b38be45fefe94c8caac1514921fa5c50. 2013-10-24 20:25:46 DEBUG [cinder.manager] Running periodic task VolumeManager._report_driver_status 2013-10-24 20:25:46 INFO [cinder.volume.manager] Updating volume status 2013-10-24 20:25:46 DEBUG [cinder.volume.drivers.lvm] Updating volume status 2013-10-24 20:25:46 DEBUG [cinder.utils] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf vgs --noheadings --nosuffix --unit=G -o name,size,free cinder-volumes 2013-10-24 20:26:46 DEBUG [cinder.manager] Running periodic task VolumeManager._publish_service_capabilities 2013-10-24 20:26:46 DEBUG [cinder.manager] Notifying Schedulers of capabilities ...