Open Stack

Hi Thomas & all,


Thomas,
Thank you for your proposal. In this time, I bring my full log of 'cinder-volume.log'.


Indeed, In log file have a large number of same information. The following section (Seems to be error for me) from cinder-volume.log and attach this log file to you.


------>


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


regards,
Thanks.











-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.openstack.org/pipermail/openstack-dev/attachments/20131025/97c5e7fb/attachment-0001.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: cinder-volume.log.1
Type: application/octet-stream
Size: 552065 bytes
Desc: not available
URL: <http://lists.openstack.org/pipermail/openstack-dev/attachments/20131025/97c5e7fb/attachment-0001.obj>