Skip to content

Instantly share code, notes, and snippets.

@a1git

a1git/gist:50707330835c430c2fff11b5209e9968 Secret

Created October 3, 2016 14:49
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 a1git/50707330835c430c2fff11b5209e9968 to your computer and use it in GitHub Desktop.
Save a1git/50707330835c430c2fff11b5209e9968 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
2016-10-03 14:46:46.945 22835 DEBUG oslo_messaging._drivers.amqpdriver [req-6d63012e-6bcd-4c2c-82b9-ecc18d935dae - - - - -] CAST unique_id: c4af5d50dbf34ab584c74ec330a6c124 FANOUT topic 'cinder-scheduler' _send /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:437
2016-10-03 14:46:46.951 22835 DEBUG oslo_service.periodic_task [req-6d63012e-6bcd-4c2c-82b9-ecc18d935dae - - - - -] Running periodic task VolumeManager._report_driver_status run_periodic_tasks /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_service/periodic_task.py:215
2016-10-03 14:46:46.952 22835 DEBUG cinder.volume.drivers.rbd [req-6d63012e-6bcd-4c2c-82b9-ecc18d935dae - - - - -] opening connection to ceph cluster (timeout=-1). _connect_to_rados /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py:328
2016-10-03 14:47:36.483 22835 DEBUG oslo_messaging._drivers.amqpdriver [-] received message with unique_id: 8e5e5253f6574e038bccaf0084c00cbe __call__ /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:196
2016-10-03 14:47:36.498 22835 DEBUG cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Volume reschedule parameters: True retry: {u'num_attempts': 1, u'hosts': [u'c14-cinder-volumes-container-9744d278@rbd#rbd']} get_flow /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py:951
2016-10-03 14:47:36.518 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Flow 'volume_create_manager' (05a85b16-cfce-4946-a895-1254e24c09ff) transitioned into state 'RUNNING' from state 'PENDING' _flow_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:140
2016-10-03 14:47:36.523 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (940e1a3b-976f-480b-9faf-2ebbbeb2cef2) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:36.597 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (940e1a3b-976f-480b-9faf-2ebbbeb2cef2) transitioned into state 'SUCCESS' from state 'RUNNING' with result 'Volume(_name_id=None,admin_metadata=<?>,attach_status='detached',availability_zone='nova',bootable=False,cluster=<?>,cluster_name=None,consistencygroup=<?>,consistencygroup_id=None,created_at=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:36Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:36Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb)' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:178
2016-10-03 14:47:36.601 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (dd42c711-748a-4f16-8343-dde2c4fd36d4) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:36.603 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (dd42c711-748a-4f16-8343-dde2c4fd36d4) transitioned into state 'SUCCESS' from state 'RUNNING' with result 'None' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:178
2016-10-03 14:47:36.605 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (030adeee-12b4-4882-9d11-d66173634b6a) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:36.608 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (030adeee-12b4-4882-9d11-d66173634b6a) transitioned into state 'SUCCESS' from state 'RUNNING' with result '{'status': u'creating', 'volume_size': 1, 'volume_name': 'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961', 'type': 'raw', 'volume_id': 'bd970df3-ca37-4dc4-a95f-f10f0e99a961'}' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:178
2016-10-03 14:47:36.610 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (0899c369-768e-40d3-a309-a30ba1e54b58) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:36.639 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (0899c369-768e-40d3-a309-a30ba1e54b58) transitioned into state 'SUCCESS' from state 'RUNNING' with result 'None' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:178
2016-10-03 14:47:36.641 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (7b34910d-d221-4c1b-836a-f1636545d98a) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:36.643 22835 INFO cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Volume bd970df3-ca37-4dc4-a95f-f10f0e99a961: being created as raw with specification: {'status': u'creating', 'volume_size': 1, 'volume_name': 'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961'}
2016-10-03 14:47:36.644 22835 DEBUG cinder.volume.drivers.rbd [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] creating volume 'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961' create_volume /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py:546
2016-10-03 14:47:36.644 22835 DEBUG cinder.volume.drivers.rbd [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] opening connection to ceph cluster (timeout=-1). _connect_to_rados /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py:328
2016-10-03 14:47:36.737 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (7b34910d-d221-4c1b-836a-f1636545d98a) 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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:36Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:36Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb), 'context': <cinder.context.RequestContext object at 0x7fa3de73d650>}, '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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:36Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:36Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb), 'request_spec': RequestSpec(CG_backend=<?>,cgsnapshot_id=None,consistencygroup_id=None,group_backend=<?>,group_id=None,image_id=None,snapshot_id=None,source_replicaid=None,source_volid=None,volume=Volume(bd970df3-ca37-4dc4-a95f-f10f0e99a961),volume_id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,volume_properties=VolumeProperties,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb)), 'context': <cinder.context.RequestContext object at 0x7fa3de73d650>}, 'provides': {'status': u'creating', 'volume_size': 1, 'volume_name': 'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961', 'type': 'raw', 'volume_id': 'bd970df3-ca37-4dc4-a95f-f10f0e99a961'}}
|__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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:36Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:36Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb), 'filter_properties': {u'config_options': {}, u'request_spec': {u'resource_properties': {u'status': u'creating', u'volume_type_id': u'57ec60b2-493e-4a4a-973c-5b4d569a07eb', u'group_id': None, u'user_id': u'4384f394f0144ddaae29185852e90289', u'availability_zone': u'nova', u'reservations': [u'e388c08e-c27d-40d2-a28d-b869cc1d65f7', u'856a36ff-ec99-4eaa-aa0b-2df141b103f6', u'79f0a3dd-f463-4d57-9e30-ab988badfc81', u'eee7b999-07d0-4f66-8b9a-4aac00c49951'], u'multiattach': False, u'attach_status': u'detached', u'display_description': u'', u'cgsnapshot_id': None, u'metadata': {}, u'qos_specs': None, u'encryption_key_id': None, u'replication_status': u'disabled', u'snapshot_id': None, u'source_volid': None, u'source_replicaid': None, u'project_id': u'1dbe871273cd4ea791e57052f17c417f', u'consistencygroup_id': None, u'size': 1, u'display_name': u'testvol'}, u'source_replicaid': None, u'volume_properties': {u'status': u'creating', u'volume_type_id': u'57ec60b2-493e-4a4a-973c-5b4d569a07eb', u'group_id': None, u'user_id': u'4384f394f0144ddaae29185852e90289', u'availability_zone': u'nova', u'reservations': [u'e388c08e-c27d-40d2-a28d-b869cc1d65f7', u'856a36ff-ec99-4eaa-aa0b-2df141b103f6', u'79f0a3dd-f463-4d57-9e30-ab988badfc81', u'eee7b999-07d0-4f66-8b9a-4aac00c49951'], u'multiattach': False, u'attach_status': u'detached', u'display_description': u'', u'cgsnapshot_id': None, u'metadata': {}, u'qos_specs': None, u'encryption_key_id': None, u'replication_status': u'disabled', u'snapshot_id': None, u'source_volid': None, u'source_replicaid': None, u'project_id': u'1dbe871273cd4ea791e57052f17c417f', u'consistencygroup_id': None, u'size': 1, u'display_name': u'testvol'}, 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': u'disabled', u'snapshot_id': None, u'ec2_id': None, u'deleted_at': None, u'id': u'bd970df3-ca37-4dc4-a95f-f10f0e99a961', u'size': 1, u'display_name': u'testvol', u'display_description': u'', u'cluster_name': None, u'name_id': u'bd970df3-ca37-4dc4-a95f-f10f0e99a961', u'volume_admin_metadata': [], u'project_id': u'1dbe871273cd4ea791e57052f17c417f', u'launched_at': None, u'scheduled_at': None, u'status': u'creating', u'volume_type_id': u'57ec60b2-493e-4a4a-973c-5b4d569a07eb', u'multiattach': False, u'deleted': False, 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-bd970df3-ca37-4dc4-a95f-f10f0e99a961', u'user_id': u'4384f394f0144ddaae29185852e90289', u'bootable': False, u'created_at': u'2016-10-03T14:47:36.000000', 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'image_id': None, u'snapshot_id': None, u'consistencygroup_id': None, u'volume_type': {u'description': None, u'deleted': False, u'created_at': u'2016-10-02T17:01:58.000000', u'updated_at': None, u'extra_specs': {u'volume_backend_name': u'rbd'}, u'is_public': True, u'deleted_at': None, u'id': u'57ec60b2-493e-4a4a-973c-5b4d569a07eb', u'projects': [], u'name': u'rbd'}, u'volume_id': u'bd970df3-ca37-4dc4-a95f-f10f0e99a961', u'group_id': None}, u'user_id': u'4384f394f0144ddaae29185852e90289', u'availability_zone': u'nova', u'volume_type': VolumeType(created_at=2016-10-02T17:01:58Z,deleted=False,deleted_at=None,description=None,extra_specs={volume_backend_name='rbd'},id=57ec60b2-493e-4a4a-973c-5b4d569a07eb,is_public=True,name='rbd',projects=[],qos_specs=<?>,updated_at=None), u'qos_specs': None, u'retry': {u'num_attempts': 1, u'hosts': [u'c14-cinder-volumes-container-9744d278@rbd#rbd']}, u'metadata': {}, u'resource_type': VolumeType(created_at=2016-10-02T17:01:58Z,deleted=False,deleted_at=None,description=None,extra_specs={volume_backend_name='rbd'},id=57ec60b2-493e-4a4a-973c-5b4d569a07eb,is_public=True,name='rbd',projects=[],qos_specs=<?>,updated_at=None), u'size': 1}, 'context': <cinder.context.RequestContext object at 0x7fa3de73d650>, 'request_spec': RequestSpec(CG_backend=<?>,cgsnapshot_id=None,consistencygroup_id=None,group_backend=<?>,group_id=None,image_id=None,snapshot_id=None,source_replicaid=None,source_volid=None,volume=Volume(bd970df3-ca37-4dc4-a95f-f10f0e99a961),volume_id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,volume_properties=VolumeProperties,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb))}, '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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:36Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:36Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb), 'context': <cinder.context.RequestContext object at 0x7fa3de73d650>}, '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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:36Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:36Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb)}
|__Flow 'volume_create_manager'
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager Traceback (most recent call last):
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/engines/action_engine/executor.py", line 53, in _execute_task
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager result = task.execute(**arguments)
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py", line 829, in execute
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager model_update = self._create_raw_volume(volume, **volume_spec)
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py", line 808, in _create_raw_volume
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager return self.driver.create_volume(volume)
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py", line 557, in create_volume
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager features=client.features)
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 186, in doit
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager result = proxy_call(self._autowrap, f, *args, **kwargs)
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 144, in proxy_call
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager rv = execute(f, *args, **kwargs)
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 125, in execute
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager six.reraise(c, e, tb)
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 83, in tworker
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager rv = meth(*args, **kwargs)
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager File "rbd.pyx", line 528, in rbd.RBD.create (/build/ceph-XmVvyr/ceph-10.2.2/src/build/rbd.c:3487)
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager FunctionNotSupported: error creating image
2016-10-03 14:47:36.737 22835 ERROR cinder.volume.manager
2016-10-03 14:47:36.743 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (7b34910d-d221-4c1b-836a-f1636545d98a) transitioned into state 'REVERTING' from state 'FAILURE' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:36.745 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (7b34910d-d221-4c1b-836a-f1636545d98a) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-10-03 14:47:36.749 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (0899c369-768e-40d3-a309-a30ba1e54b58) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:36.751 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (0899c369-768e-40d3-a309-a30ba1e54b58) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-10-03 14:47:36.753 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (030adeee-12b4-4882-9d11-d66173634b6a) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:36.755 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (030adeee-12b4-4882-9d11-d66173634b6a) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-10-03 14:47:36.757 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (dd42c711-748a-4f16-8343-dde2c4fd36d4) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:36.758 22835 DEBUG cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Updating volume bd970df3-ca37-4dc4-a95f-f10f0e99a961 with {'host': None, 'scheduled_at': datetime.datetime(2016, 10, 3, 14, 47, 36, 758623)}. _pre_reschedule /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py:114
2016-10-03 14:47:36.826 22835 DEBUG cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Volume bd970df3-ca37-4dc4-a95f-f10f0e99a961: re-scheduling SchedulerAPI.create_volume attempt 1 due to error creating image _reschedule /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py:141
2016-10-03 14:47:36.832 22835 DEBUG oslo_messaging._drivers.amqpdriver [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] CAST unique_id: 97149940737145f3bac1b9bfc8d67bcd exchange 'openstack' topic 'cinder-scheduler' _send /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:448
2016-10-03 14:47:36.836 22835 DEBUG cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Volume bd970df3-ca37-4dc4-a95f-f10f0e99a961: re-scheduled _post_reschedule /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py:155
2016-10-03 14:47:36.839 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (dd42c711-748a-4f16-8343-dde2c4fd36d4) transitioned into state 'REVERTED' from state 'REVERTING' with result 'True'
2016-10-03 14:47:36.841 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (940e1a3b-976f-480b-9faf-2ebbbeb2cef2) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:36.845 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (940e1a3b-976f-480b-9faf-2ebbbeb2cef2) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-10-03 14:47:36.849 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Flow 'volume_create_manager' (05a85b16-cfce-4946-a895-1254e24c09ff) transitioned into state 'REVERTED' from state 'RUNNING'
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Exception during message handling
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server Traceback (most recent call last):
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/rpc/server.py", line 133, in _process_incoming
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server res = self.dispatcher.dispatch(message)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/rpc/dispatcher.py", line 150, in dispatch
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server return self._do_dispatch(endpoint, method, ctxt, args)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/rpc/dispatcher.py", line 121, in _do_dispatch
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server result = func(ctxt, **new_args)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/manager.py", line 4366, in create_volume
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server allow_reschedule=allow_reschedule, volume=volume)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/manager.py", line 634, in create_volume
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server _run_flow()
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/manager.py", line 626, in _run_flow
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server flow_engine.run()
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/engines/action_engine/engine.py", line 247, in run
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server for _state in self.run_iter(timeout=timeout):
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/engines/action_engine/engine.py", line 340, in run_iter
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server failure.Failure.reraise_if_any(er_failures)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/types/failure.py", line 336, in reraise_if_any
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server failures[0].reraise()
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/types/failure.py", line 343, in reraise
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server six.reraise(*self._exc_info)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/engines/action_engine/executor.py", line 53, in _execute_task
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server result = task.execute(**arguments)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py", line 829, in execute
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server model_update = self._create_raw_volume(volume, **volume_spec)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py", line 808, in _create_raw_volume
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server return self.driver.create_volume(volume)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py", line 557, in create_volume
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server features=client.features)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 186, in doit
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server result = proxy_call(self._autowrap, f, *args, **kwargs)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 144, in proxy_call
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server rv = execute(f, *args, **kwargs)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 125, in execute
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server six.reraise(c, e, tb)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 83, in tworker
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server rv = meth(*args, **kwargs)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server File "rbd.pyx", line 528, in rbd.RBD.create (/build/ceph-XmVvyr/ceph-10.2.2/src/build/rbd.c:3487)
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server FunctionNotSupported: error creating image
2016-10-03 14:47:36.850 22835 ERROR oslo_messaging.rpc.server
2016-10-03 14:47:37.074 22835 DEBUG oslo_messaging._drivers.amqpdriver [-] received message with unique_id: d3197d18118f4db39f834bd8fd5314a5 __call__ /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:196
2016-10-03 14:47:37.083 22835 DEBUG cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Volume reschedule parameters: True retry: {u'num_attempts': 2, u'hosts': [u'c14-cinder-volumes-container-9744d278@rbd#rbd', u'c14-cinder-volumes-container-9744d278@rbd#rbd']} get_flow /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py:951
2016-10-03 14:47:37.104 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Flow 'volume_create_manager' (d694c83c-186d-45b5-abe0-49fe83af4943) transitioned into state 'RUNNING' from state 'PENDING' _flow_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:140
2016-10-03 14:47:37.107 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (a0829b4d-8eac-4b89-8bfe-73404b32977b) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.174 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (a0829b4d-8eac-4b89-8bfe-73404b32977b) transitioned into state 'SUCCESS' from state 'RUNNING' with result 'Volume(_name_id=None,admin_metadata=<?>,attach_status='detached',availability_zone='nova',bootable=False,cluster=<?>,cluster_name=None,consistencygroup=<?>,consistencygroup_id=None,created_at=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:36Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:37Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb)' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:178
2016-10-03 14:47:37.177 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (df78b8b3-a239-4186-a1e6-d599cc716989) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.179 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (df78b8b3-a239-4186-a1e6-d599cc716989) transitioned into state 'SUCCESS' from state 'RUNNING' with result 'None' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:178
2016-10-03 14:47:37.181 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (2d221cc1-276f-4a17-af9c-7cf6bdcaa610) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.183 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (2d221cc1-276f-4a17-af9c-7cf6bdcaa610) transitioned into state 'SUCCESS' from state 'RUNNING' with result '{'status': u'creating', 'volume_size': 1, 'volume_name': 'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961', 'type': 'raw', 'volume_id': 'bd970df3-ca37-4dc4-a95f-f10f0e99a961'}' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:178
2016-10-03 14:47:37.186 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (788fa479-eccf-4ef5-8d35-f94e3135673d) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.214 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (788fa479-eccf-4ef5-8d35-f94e3135673d) transitioned into state 'SUCCESS' from state 'RUNNING' with result 'None' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:178
2016-10-03 14:47:37.217 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (53575855-35ec-43e8-996c-99fc5e1e2e83) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.218 22835 INFO cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Volume bd970df3-ca37-4dc4-a95f-f10f0e99a961: being created as raw with specification: {'status': u'creating', 'volume_size': 1, 'volume_name': 'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961'}
2016-10-03 14:47:37.218 22835 DEBUG cinder.volume.drivers.rbd [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] creating volume 'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961' create_volume /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py:546
2016-10-03 14:47:37.218 22835 DEBUG cinder.volume.drivers.rbd [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] opening connection to ceph cluster (timeout=-1). _connect_to_rados /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py:328
2016-10-03 14:47:37.277 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (53575855-35ec-43e8-996c-99fc5e1e2e83) 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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:36Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:37Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb), 'context': <cinder.context.RequestContext object at 0x7fa3de73dd10>}, '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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:36Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:37Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb), 'request_spec': RequestSpec(CG_backend=<?>,cgsnapshot_id=None,consistencygroup_id=None,group_backend=<?>,group_id=None,image_id=None,snapshot_id=None,source_replicaid=None,source_volid=None,volume=Volume(bd970df3-ca37-4dc4-a95f-f10f0e99a961),volume_id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,volume_properties=VolumeProperties,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb)), 'context': <cinder.context.RequestContext object at 0x7fa3de73dd10>}, 'provides': {'status': u'creating', 'volume_size': 1, 'volume_name': 'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961', 'type': 'raw', 'volume_id': 'bd970df3-ca37-4dc4-a95f-f10f0e99a961'}}
|__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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:36Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:37Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb), 'filter_properties': {u'config_options': {}, u'request_spec': {u'resource_properties': {u'status': u'creating', u'volume_type_id': u'57ec60b2-493e-4a4a-973c-5b4d569a07eb', u'group_id': None, u'user_id': u'4384f394f0144ddaae29185852e90289', u'availability_zone': u'nova', u'reservations': [u'e388c08e-c27d-40d2-a28d-b869cc1d65f7', u'856a36ff-ec99-4eaa-aa0b-2df141b103f6', u'79f0a3dd-f463-4d57-9e30-ab988badfc81', u'eee7b999-07d0-4f66-8b9a-4aac00c49951'], u'multiattach': False, u'attach_status': u'detached', u'display_description': u'', u'cgsnapshot_id': None, u'metadata': {}, u'qos_specs': None, u'encryption_key_id': None, u'replication_status': u'disabled', u'snapshot_id': None, u'source_volid': None, u'source_replicaid': None, u'project_id': u'1dbe871273cd4ea791e57052f17c417f', u'consistencygroup_id': None, u'size': 1, u'display_name': u'testvol'}, u'source_replicaid': None, u'volume_properties': {u'status': u'creating', u'volume_type_id': u'57ec60b2-493e-4a4a-973c-5b4d569a07eb', u'group_id': None, u'user_id': u'4384f394f0144ddaae29185852e90289', u'availability_zone': u'nova', u'reservations': [u'e388c08e-c27d-40d2-a28d-b869cc1d65f7', u'856a36ff-ec99-4eaa-aa0b-2df141b103f6', u'79f0a3dd-f463-4d57-9e30-ab988badfc81', u'eee7b999-07d0-4f66-8b9a-4aac00c49951'], u'multiattach': False, u'attach_status': u'detached', u'display_description': u'', u'cgsnapshot_id': None, u'metadata': {}, u'qos_specs': None, u'encryption_key_id': None, u'replication_status': u'disabled', u'snapshot_id': None, u'source_volid': None, u'source_replicaid': None, u'project_id': u'1dbe871273cd4ea791e57052f17c417f', u'consistencygroup_id': None, u'size': 1, u'display_name': u'testvol'}, 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': u'disabled', u'snapshot_id': None, u'ec2_id': None, u'deleted_at': None, u'id': u'bd970df3-ca37-4dc4-a95f-f10f0e99a961', u'size': 1, u'display_name': u'testvol', u'display_description': u'', u'cluster_name': None, u'name_id': u'bd970df3-ca37-4dc4-a95f-f10f0e99a961', u'volume_admin_metadata': [], u'project_id': u'1dbe871273cd4ea791e57052f17c417f', u'launched_at': None, u'scheduled_at': None, u'status': u'creating', u'volume_type_id': u'57ec60b2-493e-4a4a-973c-5b4d569a07eb', u'multiattach': False, u'deleted': False, u'provider_location': None, u'volume_glance_metadata': [], u'admin_metadata': {}, u'host': None, u'glance_metadata': {}, u'consistencygroup_id': None, u'source_volid': None, u'provider_auth': None, u'previous_status': None, u'group_id': None, u'name': u'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961', u'user_id': u'4384f394f0144ddaae29185852e90289', u'bootable': False, u'created_at': u'2016-10-03T14:47:36.000000', 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'image_id': None, u'snapshot_id': None, u'consistencygroup_id': None, u'volume_type': {u'description': None, u'deleted': False, u'created_at': u'2016-10-02T17:01:58.000000', u'updated_at': None, u'extra_specs': {u'volume_backend_name': u'rbd'}, u'is_public': True, u'deleted_at': None, u'id': u'57ec60b2-493e-4a4a-973c-5b4d569a07eb', u'projects': [], u'name': u'rbd'}, u'volume_id': u'bd970df3-ca37-4dc4-a95f-f10f0e99a961', u'group_id': None}, u'user_id': u'4384f394f0144ddaae29185852e90289', u'availability_zone': u'nova', u'volume_type': VolumeType(created_at=2016-10-02T17:01:58Z,deleted=False,deleted_at=None,description=None,extra_specs={volume_backend_name='rbd'},id=57ec60b2-493e-4a4a-973c-5b4d569a07eb,is_public=True,name='rbd',projects=[],qos_specs=<?>,updated_at=None), u'qos_specs': None, u'retry': {u'num_attempts': 2, u'hosts': [u'c14-cinder-volumes-container-9744d278@rbd#rbd', u'c14-cinder-volumes-container-9744d278@rbd#rbd']}, u'metadata': {}, u'resource_type': VolumeType(created_at=2016-10-02T17:01:58Z,deleted=False,deleted_at=None,description=None,extra_specs={volume_backend_name='rbd'},id=57ec60b2-493e-4a4a-973c-5b4d569a07eb,is_public=True,name='rbd',projects=[],qos_specs=<?>,updated_at=None), u'size': 1}, 'context': <cinder.context.RequestContext object at 0x7fa3de73dd10>, 'request_spec': RequestSpec(CG_backend=<?>,cgsnapshot_id=None,consistencygroup_id=None,group_backend=<?>,group_id=None,image_id=None,snapshot_id=None,source_replicaid=None,source_volid=None,volume=Volume(bd970df3-ca37-4dc4-a95f-f10f0e99a961),volume_id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,volume_properties=VolumeProperties,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb))}, '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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:36Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:37Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb), 'context': <cinder.context.RequestContext object at 0x7fa3de73dd10>}, '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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:36Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:37Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb)}
|__Flow 'volume_create_manager'
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager Traceback (most recent call last):
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/engines/action_engine/executor.py", line 53, in _execute_task
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager result = task.execute(**arguments)
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py", line 829, in execute
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager model_update = self._create_raw_volume(volume, **volume_spec)
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py", line 808, in _create_raw_volume
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager return self.driver.create_volume(volume)
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py", line 557, in create_volume
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager features=client.features)
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 186, in doit
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager result = proxy_call(self._autowrap, f, *args, **kwargs)
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 144, in proxy_call
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager rv = execute(f, *args, **kwargs)
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 125, in execute
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager six.reraise(c, e, tb)
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 83, in tworker
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager rv = meth(*args, **kwargs)
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager File "rbd.pyx", line 528, in rbd.RBD.create (/build/ceph-XmVvyr/ceph-10.2.2/src/build/rbd.c:3487)
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager FunctionNotSupported: error creating image
2016-10-03 14:47:37.277 22835 ERROR cinder.volume.manager
2016-10-03 14:47:37.283 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (53575855-35ec-43e8-996c-99fc5e1e2e83) transitioned into state 'REVERTING' from state 'FAILURE' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.285 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (53575855-35ec-43e8-996c-99fc5e1e2e83) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-10-03 14:47:37.287 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (788fa479-eccf-4ef5-8d35-f94e3135673d) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.289 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (788fa479-eccf-4ef5-8d35-f94e3135673d) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-10-03 14:47:37.291 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (2d221cc1-276f-4a17-af9c-7cf6bdcaa610) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.293 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (2d221cc1-276f-4a17-af9c-7cf6bdcaa610) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-10-03 14:47:37.295 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (df78b8b3-a239-4186-a1e6-d599cc716989) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.296 22835 DEBUG cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Updating volume bd970df3-ca37-4dc4-a95f-f10f0e99a961 with {'host': None, 'scheduled_at': datetime.datetime(2016, 10, 3, 14, 47, 37, 296583)}. _pre_reschedule /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py:114
2016-10-03 14:47:37.374 22835 DEBUG cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Volume bd970df3-ca37-4dc4-a95f-f10f0e99a961: re-scheduling SchedulerAPI.create_volume attempt 2 due to error creating image _reschedule /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py:141
2016-10-03 14:47:37.380 22835 DEBUG oslo_messaging._drivers.amqpdriver [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] CAST unique_id: ec60f8012144411ba5e1cbe2187d2be6 exchange 'openstack' topic 'cinder-scheduler' _send /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:448
2016-10-03 14:47:37.384 22835 DEBUG cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Volume bd970df3-ca37-4dc4-a95f-f10f0e99a961: re-scheduled _post_reschedule /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py:155
2016-10-03 14:47:37.386 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (df78b8b3-a239-4186-a1e6-d599cc716989) transitioned into state 'REVERTED' from state 'REVERTING' with result 'True'
2016-10-03 14:47:37.388 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (a0829b4d-8eac-4b89-8bfe-73404b32977b) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.390 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (a0829b4d-8eac-4b89-8bfe-73404b32977b) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-10-03 14:47:37.393 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Flow 'volume_create_manager' (d694c83c-186d-45b5-abe0-49fe83af4943) transitioned into state 'REVERTED' from state 'RUNNING'
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Exception during message handling
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server Traceback (most recent call last):
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/rpc/server.py", line 133, in _process_incoming
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server res = self.dispatcher.dispatch(message)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/rpc/dispatcher.py", line 150, in dispatch
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server return self._do_dispatch(endpoint, method, ctxt, args)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/rpc/dispatcher.py", line 121, in _do_dispatch
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server result = func(ctxt, **new_args)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/manager.py", line 4366, in create_volume
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server allow_reschedule=allow_reschedule, volume=volume)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/manager.py", line 634, in create_volume
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server _run_flow()
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/manager.py", line 626, in _run_flow
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server flow_engine.run()
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/engines/action_engine/engine.py", line 247, in run
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server for _state in self.run_iter(timeout=timeout):
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/engines/action_engine/engine.py", line 340, in run_iter
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server failure.Failure.reraise_if_any(er_failures)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/types/failure.py", line 336, in reraise_if_any
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server failures[0].reraise()
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/types/failure.py", line 343, in reraise
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server six.reraise(*self._exc_info)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/engines/action_engine/executor.py", line 53, in _execute_task
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server result = task.execute(**arguments)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py", line 829, in execute
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server model_update = self._create_raw_volume(volume, **volume_spec)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py", line 808, in _create_raw_volume
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server return self.driver.create_volume(volume)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py", line 557, in create_volume
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server features=client.features)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 186, in doit
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server result = proxy_call(self._autowrap, f, *args, **kwargs)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 144, in proxy_call
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server rv = execute(f, *args, **kwargs)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 125, in execute
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server six.reraise(c, e, tb)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 83, in tworker
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server rv = meth(*args, **kwargs)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server File "rbd.pyx", line 528, in rbd.RBD.create (/build/ceph-XmVvyr/ceph-10.2.2/src/build/rbd.c:3487)
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server FunctionNotSupported: error creating image
2016-10-03 14:47:37.394 22835 ERROR oslo_messaging.rpc.server
2016-10-03 14:47:37.708 22835 DEBUG oslo_messaging._drivers.amqpdriver [-] received message with unique_id: 5cc98cf7aae44dd48ff171c99114253c __call__ /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:196
2016-10-03 14:47:37.717 22835 DEBUG cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Volume reschedule parameters: True retry: {u'num_attempts': 3, u'hosts': [u'c14-cinder-volumes-container-9744d278@rbd#rbd', u'c14-cinder-volumes-container-9744d278@rbd#rbd', u'c14-cinder-volumes-container-9744d278@rbd#rbd']} get_flow /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py:951
2016-10-03 14:47:37.736 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Flow 'volume_create_manager' (398b767b-bfc5-4013-b752-f1490588f61f) transitioned into state 'RUNNING' from state 'PENDING' _flow_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:140
2016-10-03 14:47:37.739 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (df0157f7-f129-4693-944e-60489923c3e7) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.806 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (df0157f7-f129-4693-944e-60489923c3e7) transitioned into state 'SUCCESS' from state 'RUNNING' with result 'Volume(_name_id=None,admin_metadata=<?>,attach_status='detached',availability_zone='nova',bootable=False,cluster=<?>,cluster_name=None,consistencygroup=<?>,consistencygroup_id=None,created_at=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:37Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:37Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb)' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:178
2016-10-03 14:47:37.810 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (3e3e2771-0fc9-450d-9596-483955f41717) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.812 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (3e3e2771-0fc9-450d-9596-483955f41717) transitioned into state 'SUCCESS' from state 'RUNNING' with result 'None' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:178
2016-10-03 14:47:37.814 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (0f0a3cf0-aa6c-436f-8ae3-3df875201dff) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.816 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (0f0a3cf0-aa6c-436f-8ae3-3df875201dff) transitioned into state 'SUCCESS' from state 'RUNNING' with result '{'status': u'creating', 'volume_size': 1, 'volume_name': 'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961', 'type': 'raw', 'volume_id': 'bd970df3-ca37-4dc4-a95f-f10f0e99a961'}' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:178
2016-10-03 14:47:37.818 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (bb363857-d6b5-46dd-ac1d-d2e5fdc391ee) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.842 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (bb363857-d6b5-46dd-ac1d-d2e5fdc391ee) transitioned into state 'SUCCESS' from state 'RUNNING' with result 'None' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:178
2016-10-03 14:47:37.844 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (bbd4674f-9cd9-46c9-8363-d64044ffe589) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.845 22835 INFO cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Volume bd970df3-ca37-4dc4-a95f-f10f0e99a961: being created as raw with specification: {'status': u'creating', 'volume_size': 1, 'volume_name': 'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961'}
2016-10-03 14:47:37.846 22835 DEBUG cinder.volume.drivers.rbd [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] creating volume 'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961' create_volume /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py:546
2016-10-03 14:47:37.846 22835 DEBUG cinder.volume.drivers.rbd [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] opening connection to ceph cluster (timeout=-1). _connect_to_rados /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py:328
2016-10-03 14:47:37.898 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (bbd4674f-9cd9-46c9-8363-d64044ffe589) 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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:37Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:37Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb), 'context': <cinder.context.RequestContext object at 0x7fa3dea46ed0>}, '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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:37Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:37Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb), 'request_spec': RequestSpec(CG_backend=<?>,cgsnapshot_id=None,consistencygroup_id=None,group_backend=<?>,group_id=None,image_id=None,snapshot_id=None,source_replicaid=None,source_volid=None,volume=Volume(bd970df3-ca37-4dc4-a95f-f10f0e99a961),volume_id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,volume_properties=VolumeProperties,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb)), 'context': <cinder.context.RequestContext object at 0x7fa3dea46ed0>}, 'provides': {'status': u'creating', 'volume_size': 1, 'volume_name': 'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961', 'type': 'raw', 'volume_id': 'bd970df3-ca37-4dc4-a95f-f10f0e99a961'}}
|__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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:37Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:37Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb), 'filter_properties': {u'config_options': {}, u'request_spec': {u'resource_properties': {u'status': u'creating', u'volume_type_id': u'57ec60b2-493e-4a4a-973c-5b4d569a07eb', u'group_id': None, u'user_id': u'4384f394f0144ddaae29185852e90289', u'availability_zone': u'nova', u'reservations': [u'e388c08e-c27d-40d2-a28d-b869cc1d65f7', u'856a36ff-ec99-4eaa-aa0b-2df141b103f6', u'79f0a3dd-f463-4d57-9e30-ab988badfc81', u'eee7b999-07d0-4f66-8b9a-4aac00c49951'], u'multiattach': False, u'attach_status': u'detached', u'display_description': u'', u'cgsnapshot_id': None, u'metadata': {}, u'qos_specs': None, u'encryption_key_id': None, u'replication_status': u'disabled', u'snapshot_id': None, u'source_volid': None, u'source_replicaid': None, u'project_id': u'1dbe871273cd4ea791e57052f17c417f', u'consistencygroup_id': None, u'size': 1, u'display_name': u'testvol'}, u'source_replicaid': None, u'volume_properties': {u'status': u'creating', u'volume_type_id': u'57ec60b2-493e-4a4a-973c-5b4d569a07eb', u'group_id': None, u'user_id': u'4384f394f0144ddaae29185852e90289', u'availability_zone': u'nova', u'reservations': [u'e388c08e-c27d-40d2-a28d-b869cc1d65f7', u'856a36ff-ec99-4eaa-aa0b-2df141b103f6', u'79f0a3dd-f463-4d57-9e30-ab988badfc81', u'eee7b999-07d0-4f66-8b9a-4aac00c49951'], u'multiattach': False, u'attach_status': u'detached', u'display_description': u'', u'cgsnapshot_id': None, u'metadata': {}, u'qos_specs': None, u'encryption_key_id': None, u'replication_status': u'disabled', u'snapshot_id': None, u'source_volid': None, u'source_replicaid': None, u'project_id': u'1dbe871273cd4ea791e57052f17c417f', u'consistencygroup_id': None, u'size': 1, u'display_name': u'testvol'}, 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': u'disabled', u'snapshot_id': None, u'ec2_id': None, u'deleted_at': None, u'id': u'bd970df3-ca37-4dc4-a95f-f10f0e99a961', u'size': 1, u'display_name': u'testvol', u'display_description': u'', u'cluster_name': None, u'name_id': u'bd970df3-ca37-4dc4-a95f-f10f0e99a961', u'volume_admin_metadata': [], u'project_id': u'1dbe871273cd4ea791e57052f17c417f', u'launched_at': None, u'scheduled_at': None, u'status': u'creating', u'volume_type_id': u'57ec60b2-493e-4a4a-973c-5b4d569a07eb', u'multiattach': False, u'deleted': False, u'provider_location': None, u'volume_glance_metadata': [], u'admin_metadata': {}, u'host': None, u'glance_metadata': {}, u'consistencygroup_id': None, u'source_volid': None, u'provider_auth': None, u'previous_status': None, u'group_id': None, u'name': u'volume-bd970df3-ca37-4dc4-a95f-f10f0e99a961', u'user_id': u'4384f394f0144ddaae29185852e90289', u'bootable': False, u'created_at': u'2016-10-03T14:47:36.000000', 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'image_id': None, u'snapshot_id': None, u'consistencygroup_id': None, u'volume_type': {u'description': None, u'deleted': False, u'created_at': u'2016-10-02T17:01:58.000000', u'updated_at': None, u'extra_specs': {u'volume_backend_name': u'rbd'}, u'is_public': True, u'deleted_at': None, u'id': u'57ec60b2-493e-4a4a-973c-5b4d569a07eb', u'projects': [], u'name': u'rbd'}, u'volume_id': u'bd970df3-ca37-4dc4-a95f-f10f0e99a961', u'group_id': None}, u'user_id': u'4384f394f0144ddaae29185852e90289', u'availability_zone': u'nova', u'volume_type': VolumeType(created_at=2016-10-02T17:01:58Z,deleted=False,deleted_at=None,description=None,extra_specs={volume_backend_name='rbd'},id=57ec60b2-493e-4a4a-973c-5b4d569a07eb,is_public=True,name='rbd',projects=[],qos_specs=<?>,updated_at=None), u'qos_specs': None, u'retry': {u'num_attempts': 3, u'hosts': [u'c14-cinder-volumes-container-9744d278@rbd#rbd', u'c14-cinder-volumes-container-9744d278@rbd#rbd', u'c14-cinder-volumes-container-9744d278@rbd#rbd']}, u'metadata': {}, u'resource_type': VolumeType(created_at=2016-10-02T17:01:58Z,deleted=False,deleted_at=None,description=None,extra_specs={volume_backend_name='rbd'},id=57ec60b2-493e-4a4a-973c-5b4d569a07eb,is_public=True,name='rbd',projects=[],qos_specs=<?>,updated_at=None), u'size': 1}, 'context': <cinder.context.RequestContext object at 0x7fa3dea46ed0>, 'request_spec': RequestSpec(CG_backend=<?>,cgsnapshot_id=None,consistencygroup_id=None,group_backend=<?>,group_id=None,image_id=None,snapshot_id=None,source_replicaid=None,source_volid=None,volume=Volume(bd970df3-ca37-4dc4-a95f-f10f0e99a961),volume_id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,volume_properties=VolumeProperties,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb))}, '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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:37Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:37Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb), 'context': <cinder.context.RequestContext object at 0x7fa3dea46ed0>}, '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=2016-10-03T14:47:36Z,deleted=False,deleted_at=None,display_description='',display_name='testvol',ec2_id=None,encryption_key_id=None,glance_metadata=<?>,group=<?>,group_id=None,host='c14-cinder-volumes-container-9744d278@rbd#rbd',id=bd970df3-ca37-4dc4-a95f-f10f0e99a961,launched_at=None,metadata={},migration_status=None,multiattach=False,previous_status=None,project_id='1dbe871273cd4ea791e57052f17c417f',provider_auth=None,provider_geometry=None,provider_id=None,provider_location=None,replication_driver_data=None,replication_extended_status=None,replication_status='disabled',scheduled_at=2016-10-03T14:47:37Z,size=1,snapshot_id=None,snapshots=<?>,source_volid=None,status='creating',terminated_at=None,updated_at=2016-10-03T14:47:37Z,user_id='4384f394f0144ddaae29185852e90289',volume_attachment=<?>,volume_type=VolumeType(57ec60b2-493e-4a4a-973c-5b4d569a07eb),volume_type_id=57ec60b2-493e-4a4a-973c-5b4d569a07eb)}
|__Flow 'volume_create_manager'
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager Traceback (most recent call last):
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/engines/action_engine/executor.py", line 53, in _execute_task
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager result = task.execute(**arguments)
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py", line 829, in execute
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager model_update = self._create_raw_volume(volume, **volume_spec)
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py", line 808, in _create_raw_volume
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager return self.driver.create_volume(volume)
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py", line 557, in create_volume
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager features=client.features)
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 186, in doit
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager result = proxy_call(self._autowrap, f, *args, **kwargs)
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 144, in proxy_call
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager rv = execute(f, *args, **kwargs)
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 125, in execute
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager six.reraise(c, e, tb)
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 83, in tworker
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager rv = meth(*args, **kwargs)
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager File "rbd.pyx", line 528, in rbd.RBD.create (/build/ceph-XmVvyr/ceph-10.2.2/src/build/rbd.c:3487)
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager FunctionNotSupported: error creating image
2016-10-03 14:47:37.898 22835 ERROR cinder.volume.manager
2016-10-03 14:47:37.906 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (bbd4674f-9cd9-46c9-8363-d64044ffe589) transitioned into state 'REVERTING' from state 'FAILURE' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.908 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create' (bbd4674f-9cd9-46c9-8363-d64044ffe589) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-10-03 14:47:37.911 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (bb363857-d6b5-46dd-ac1d-d2e5fdc391ee) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.913 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start' (bb363857-d6b5-46dd-ac1d-d2e5fdc391ee) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-10-03 14:47:37.915 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (0f0a3cf0-aa6c-436f-8ae3-3df875201dff) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.917 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create' (0f0a3cf0-aa6c-436f-8ae3-3df875201dff) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-10-03 14:47:37.919 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (3e3e2771-0fc9-450d-9596-483955f41717) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:37.921 22835 DEBUG cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Updating volume bd970df3-ca37-4dc4-a95f-f10f0e99a961 with {'host': None, 'scheduled_at': datetime.datetime(2016, 10, 3, 14, 47, 37, 921020)}. _pre_reschedule /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py:114
2016-10-03 14:47:38.001 22835 DEBUG cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Volume bd970df3-ca37-4dc4-a95f-f10f0e99a961: re-scheduling SchedulerAPI.create_volume attempt 3 due to error creating image _reschedule /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py:141
2016-10-03 14:47:38.007 22835 DEBUG oslo_messaging._drivers.amqpdriver [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] CAST unique_id: e72b99944654435abb701333670275b4 exchange 'openstack' topic 'cinder-scheduler' _send /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:448
2016-10-03 14:47:38.011 22835 DEBUG cinder.volume.flows.manager.create_volume [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Volume bd970df3-ca37-4dc4-a95f-f10f0e99a961: re-scheduled _post_reschedule /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py:155
2016-10-03 14:47:38.013 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create' (3e3e2771-0fc9-450d-9596-483955f41717) transitioned into state 'REVERTED' from state 'REVERTING' with result 'True'
2016-10-03 14:47:38.015 22835 DEBUG cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (df0157f7-f129-4693-944e-60489923c3e7) transitioned into state 'REVERTING' from state 'SUCCESS' _task_receiver /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/listeners/logging.py:189
2016-10-03 14:47:38.017 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Task 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create' (df0157f7-f129-4693-944e-60489923c3e7) transitioned into state 'REVERTED' from state 'REVERTING' with result 'None'
2016-10-03 14:47:38.021 22835 WARNING cinder.volume.manager [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Flow 'volume_create_manager' (398b767b-bfc5-4013-b752-f1490588f61f) transitioned into state 'REVERTED' from state 'RUNNING'
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server [req-c07cb2d2-f031-4f4e-9e74-f4e01d2fbb92 4384f394f0144ddaae29185852e90289 1dbe871273cd4ea791e57052f17c417f - default default] Exception during message handling
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server Traceback (most recent call last):
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/rpc/server.py", line 133, in _process_incoming
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server res = self.dispatcher.dispatch(message)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/rpc/dispatcher.py", line 150, in dispatch
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server return self._do_dispatch(endpoint, method, ctxt, args)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/rpc/dispatcher.py", line 121, in _do_dispatch
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server result = func(ctxt, **new_args)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/manager.py", line 4366, in create_volume
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server allow_reschedule=allow_reschedule, volume=volume)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/manager.py", line 634, in create_volume
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server _run_flow()
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/manager.py", line 626, in _run_flow
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server flow_engine.run()
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/engines/action_engine/engine.py", line 247, in run
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server for _state in self.run_iter(timeout=timeout):
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/engines/action_engine/engine.py", line 340, in run_iter
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server failure.Failure.reraise_if_any(er_failures)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/types/failure.py", line 336, in reraise_if_any
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server failures[0].reraise()
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/types/failure.py", line 343, in reraise
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server six.reraise(*self._exc_info)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/taskflow/engines/action_engine/executor.py", line 53, in _execute_task
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server result = task.execute(**arguments)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py", line 829, in execute
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server model_update = self._create_raw_volume(volume, **volume_spec)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/flows/manager/create_volume.py", line 808, in _create_raw_volume
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server return self.driver.create_volume(volume)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py", line 557, in create_volume
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server features=client.features)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 186, in doit
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server result = proxy_call(self._autowrap, f, *args, **kwargs)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 144, in proxy_call
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server rv = execute(f, *args, **kwargs)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 125, in execute
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server six.reraise(c, e, tb)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "/openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/eventlet/tpool.py", line 83, in tworker
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server rv = meth(*args, **kwargs)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server File "rbd.pyx", line 528, in rbd.RBD.create (/build/ceph-XmVvyr/ceph-10.2.2/src/build/rbd.c:3487)
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server FunctionNotSupported: error creating image
2016-10-03 14:47:38.022 22835 ERROR oslo_messaging.rpc.server
2016-10-03 14:47:46.947 22835 DEBUG oslo_service.periodic_task [req-6d63012e-6bcd-4c2c-82b9-ecc18d935dae - - - - -] Running periodic task VolumeManager._publish_service_capabilities run_periodic_tasks /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_service/periodic_task.py:215
2016-10-03 14:47:46.948 22835 DEBUG cinder.manager [req-6d63012e-6bcd-4c2c-82b9-ecc18d935dae - - - - -] Notifying Schedulers of capabilities ... _publish_service_capabilities /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/manager.py:165
2016-10-03 14:47:46.949 22835 DEBUG oslo_messaging._drivers.amqpdriver [req-6d63012e-6bcd-4c2c-82b9-ecc18d935dae - - - - -] CAST unique_id: 1baf7ce5cc204e7db04615b9ba2c32bf FANOUT topic 'cinder-scheduler' _send /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:437
2016-10-03 14:47:46.953 22835 DEBUG oslo_service.periodic_task [req-6d63012e-6bcd-4c2c-82b9-ecc18d935dae - - - - -] Running periodic task VolumeManager._report_driver_status run_periodic_tasks /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_service/periodic_task.py:215
2016-10-03 14:47:46.954 22835 DEBUG cinder.volume.drivers.rbd [req-6d63012e-6bcd-4c2c-82b9-ecc18d935dae - - - - -] opening connection to ceph cluster (timeout=-1). _connect_to_rados /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py:328
2016-10-03 14:48:46.951 22835 DEBUG oslo_service.periodic_task [req-6d63012e-6bcd-4c2c-82b9-ecc18d935dae - - - - -] Running periodic task VolumeManager._publish_service_capabilities run_periodic_tasks /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_service/periodic_task.py:215
2016-10-03 14:48:46.951 22835 DEBUG cinder.manager [req-6d63012e-6bcd-4c2c-82b9-ecc18d935dae - - - - -] Notifying Schedulers of capabilities ... _publish_service_capabilities /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/manager.py:165
2016-10-03 14:48:46.953 22835 DEBUG oslo_messaging._drivers.amqpdriver [req-6d63012e-6bcd-4c2c-82b9-ecc18d935dae - - - - -] CAST unique_id: e21921a3ab4a49f18be3211d84bb2fbf FANOUT topic 'cinder-scheduler' _send /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:437
2016-10-03 14:48:46.958 22835 DEBUG oslo_service.periodic_task [req-6d63012e-6bcd-4c2c-82b9-ecc18d935dae - - - - -] Running periodic task VolumeManager._report_driver_status run_periodic_tasks /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/oslo_service/periodic_task.py:215
2016-10-03 14:48:46.960 22835 DEBUG cinder.volume.drivers.rbd [req-6d63012e-6bcd-4c2c-82b9-ecc18d935dae - - - - -] opening connection to ceph cluster (timeout=-1). _connect_to_rados /openstack/venvs/cinder-14.0.0/lib/python2.7/site-packages/cinder/volume/drivers/rbd.py:328
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment