Create instance failed even though volume created from image

asked 2018-06-29 11:34:40 -0500

mojavezax gravatar image

Hello,

I'm working with OpenStack Pike, with Ceph 12.2.5 as the backing store.

I tried to create an instance in the GUI from a 30GB image (qcow2 image converted from vmdk), but got the following error:

Error: Failed to perform requested operation on instance "cvcfisapps", the instance has an error status: Please try again later [Error: Build of instance 28c01330-beb1-4158-b8f7-11e975389cd7 aborted: Volume a8f96b32-541f-45ec-835a-458f23bd592c did not finish being created even after we waited 188 seconds or 61 attempts. And its status is creating.].

I checked and it created a volume from the image. I deleted the failed instance, and was then able to boot from the volume successfully. My question is why creating the instance failed with the above error?

Here are the relevant log entries from /var/log/cinder/volume.log on the compute host:

2018-06-29 08:58:08.748 54792 INFO cinder.volume.flows.manager.create_volume [req-752787bb-b661-4fa3-a05e-d4cd3c4d94c7 fa9368f1ede54e9b84b3657848d0e080 3ec2bba82240472d889d565dfd9b8ff8 - default default] Volume a8f96b32-541f-45ec-835a-458f23bd592c: being created as image with specification: {'status': u'creating', 'image_location': (None, None), 'volume_size': 32, 'volume_name': 'volume-a8f96b32-541f-45ec-835a-458f23bd592c', 'image_id': 'f4352cdb-d4cc-4f6f-afb7-395b099e0795', 'image_service': <cinder.image.glance.GlanceImageService object at 0x7f0e3a79dd90>, 'image_meta': {u'status': u'active', u'name': u'cvcfisapps', u'tags': [], u'container_format': u'bare', u'created_at': datetime.datetime(2018, 6, 26, 15, 11, 51, tzinfo=<iso8601.Utc>), u'disk_format': u'qcow2', u'updated_at': datetime.datetime(2018, 6, 26, 15, 13, 49, tzinfo=<iso8601.Utc>), u'visibility': u'public', 'properties': {}, u'owner': u'c17f7f6ae0f44372a25439fe22357500', u'protected': False, u'id': u'f4352cdb-d4cc-4f6f-afb7-395b099e0795', u'file': u'/v2/images/f4352cdb-d4cc-4f6f-afb7-395b099e0795/file', u'checksum': u'54c6579afadf40e29cde2ebdebfcaffb', u'min_disk': 0, u'virtual_size': None, u'min_ram': 0, u'size': 31927369728}}
2018-06-29 08:58:23.593 54792 WARNING oslo.service.loopingcall [req-7dfe2be7-8362-4a99-8268-438f5ec6f834 - - - - -] Function 'cinder.service.Service.report_state' run outlasted interval by 0.54 sec
2018-06-29 08:59:23.716 54792 WARNING oslo.service.loopingcall [req-7dfe2be7-8362-4a99-8268-438f5ec6f834 - - - - -] Function 'cinder.service.Service.report_state' run outlasted interval by 40.12 sec
2018-06-29 09:00:02.470 54792 WARNING oslo.service.loopingcall [req-7dfe2be7-8362-4a99-8268-438f5ec6f834 - - - - -] Function 'cinder.service.Service.report_state' run outlasted interval by 28.75 sec
2018-06-29 09:00:23.750 54792 WARNING oslo.service.loopingcall [req-7dfe2be7-8362-4a99-8268-438f5ec6f834 - - - - -] Function 'cinder.service.Service.report_state' run outlasted interval by 1.28 sec
2018-06-29 09:01:23.497 54792 WARNING oslo.service.loopingcall [req-7dfe2be7-8362-4a99-8268-438f5ec6f834 - - - - -] Function 'cinder.service.Service.report_state' run outlasted interval by 49.75 sec
2018-06-29 09:01:51.354 54792 WARNING oslo.service.loopingcall [req-7dfe2be7-8362-4a99-8268-438f5ec6f834 - - - - -] Function 'cinder.service.Service.report_state' run outlasted interval by 7.86 sec
2018-06-29 09:02:42.669 54792 INFO cinder.image.image_utils [req-752787bb-b661-4fa3-a05e-d4cd3c4d94c7 fa9368f1ede54e9b84b3657848d0e080 3ec2bba82240472d889d565dfd9b8ff8 - default default] Image download 30448.00 MB at 111.16 MB/s
2018-06-29 09:02:42.678 54792 WARNING oslo.service.loopingcall [req-7dfe2be7-8362-4a99-8268-438f5ec6f834 - - - - -] Function 'cinder.service.Service.report_state' run outlasted interval by 31.33 sec
2018-06-29 09:03:06.201 54792 INFO cinder.image.image_utils [req-752787bb-b661-4fa3-a05e-d4cd3c4d94c7 fa9368f1ede54e9b84b3657848d0e080 3ec2bba82240472d889d565dfd9b8ff8 - default default] Converted 30720.00 MB image at 1449.66 MB/s
2018-06-29 09:07:50.629 54792 INFO cinder.volume.flows.manager.create_volume [req-752787bb-b661-4fa3-a05e-d4cd3c4d94c7 fa9368f1ede54e9b84b3657848d0e080 3ec2bba82240472d889d565dfd9b8ff8 - default default] Volume volume-a8f96b32-541f-45ec-835a-458f23bd592c (a8f96b32-541f-45ec-835a-458f23bd592c): created successfully ...
(more)
edit retag flag offensive close merge delete