Ask Your Question

Revision history [back]

click to hide/show revision 1
initial version

cinder volumes attached but not available during OS install

Hi all,

I just installed Mitaka on three nodes (controller, storage and compute) following this guide http://docs.openstack.org/mitaka/install-guide-rdo/

I stopped at Block Storage Service chapter to have just the basic features before adding complexity.

Everything seems to work fine: I'm able to run instances from ISO images, create cinder volumes, attach them to already running instances (live distros like Cirros), format and mount them, put VMs in network and so on.

The only "glitch" seems to appear when I try to install a non-live distro on a volume starting form a ISO image: both CentOS 7 and Ubuntu 16.04 fail to install for different, yet seemingly related, reasons.

  • CentOS 7 stops install process because Anaconda cannot find any block device available
  • Ubuntu 16.04 stops because, after keyboard and language selection, it says "Your installation CD-ROM cannot be mounted"

These are the logs produced during instance creation:

CONTROLLER NODE

cinder/api.log

[at instance creation]
2016-08-03 03:11:27.244 2680 INFO cinder.api.openstack.wsgi [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] POST http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes
2016-08-03 03:11:27.254 2680 INFO cinder.api.v2.volumes [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Create volume of 3 GB
2016-08-03 03:11:27.406 2680 INFO cinder.volume.api [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Availability Zones retrieved successfully.
2016-08-03 03:11:28.539 2680 INFO cinder.volume.api [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume created successfully.
2016-08-03 03:11:28.541 2680 INFO cinder.api.openstack.wsgi [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes returned with HTTP 202
2016-08-03 03:11:28.543 2680 INFO eventlet.wsgi.server [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "POST /v2/c79ea99641814764b377b47e84a40036/volumes HTTP/1.1" status: 202  len: 1066 time: 1.3087020
2016-08-03 03:11:28.555 2680 INFO cinder.api.openstack.wsgi [req-3a12c1f8-7403-448a-b030-5e29225552aa 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:11:28.887 2680 INFO cinder.volume.api [req-3a12c1f8-7403-448a-b030-5e29225552aa 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:11:28.895 2680 INFO cinder.api.openstack.wsgi [req-3a12c1f8-7403-448a-b030-5e29225552aa 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:11:28.897 2680 INFO eventlet.wsgi.server [req-3a12c1f8-7403-448a-b030-5e29225552aa 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1239 time: 0.3482981
2016-08-03 03:11:28.909 2680 INFO cinder.api.openstack.wsgi [req-f33a7e5d-0cb3-4905-a1d1-6c4be84deff1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:11:29.160 2680 INFO cinder.volume.api [req-f33a7e5d-0cb3-4905-a1d1-6c4be84deff1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:11:29.169 2680 INFO cinder.api.openstack.wsgi [req-f33a7e5d-0cb3-4905-a1d1-6c4be84deff1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:11:29.171 2680 INFO eventlet.wsgi.server [req-f33a7e5d-0cb3-4905-a1d1-6c4be84deff1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1285 time: 0.2659891
2016-08-03 03:11:39.184 2680 INFO cinder.api.openstack.wsgi [req-b5690d80-44da-49d1-9dac-5c80777ae1fe 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:11:39.423 2680 INFO cinder.volume.api [req-b5690d80-44da-49d1-9dac-5c80777ae1fe 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:11:39.433 2680 INFO cinder.api.openstack.wsgi [req-b5690d80-44da-49d1-9dac-5c80777ae1fe 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:11:39.435 2680 INFO eventlet.wsgi.server [req-b5690d80-44da-49d1-9dac-5c80777ae1fe 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1285 time: 0.2557371
2016-08-03 03:11:49.454 2680 INFO cinder.api.openstack.wsgi [req-c51cb49b-ada0-4b99-8c9d-1bda3e5c02f5 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:11:49.686 2680 INFO cinder.volume.api [req-c51cb49b-ada0-4b99-8c9d-1bda3e5c02f5 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:11:49.695 2680 INFO cinder.api.openstack.wsgi [req-c51cb49b-ada0-4b99-8c9d-1bda3e5c02f5 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:11:49.697 2680 INFO eventlet.wsgi.server [req-c51cb49b-ada0-4b99-8c9d-1bda3e5c02f5 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1285 time: 0.2486291
2016-08-03 03:11:59.714 2680 INFO cinder.api.openstack.wsgi [req-62ca7659-0965-40db-a6e7-6ab1776efc6c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:11:59.938 2680 INFO cinder.volume.api [req-62ca7659-0965-40db-a6e7-6ab1776efc6c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:11:59.947 2680 INFO cinder.api.openstack.wsgi [req-62ca7659-0965-40db-a6e7-6ab1776efc6c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:11:59.949 2680 INFO eventlet.wsgi.server [req-62ca7659-0965-40db-a6e7-6ab1776efc6c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1285 time: 0.2389159
2016-08-03 03:12:09.969 2680 INFO cinder.api.openstack.wsgi [req-69d00211-fcc1-4d4e-bf61-ab174b8715f6 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:10.216 2680 INFO cinder.volume.api [req-69d00211-fcc1-4d4e-bf61-ab174b8715f6 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:10.225 2680 INFO cinder.api.openstack.wsgi [req-69d00211-fcc1-4d4e-bf61-ab174b8715f6 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:12:10.227 2680 INFO eventlet.wsgi.server [req-69d00211-fcc1-4d4e-bf61-ab174b8715f6 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1285 time: 0.2633100
2016-08-03 03:12:20.249 2680 INFO cinder.api.openstack.wsgi [req-9603fd52-137b-4349-a72c-67a0fd9d7bc1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:20.604 2680 INFO cinder.volume.api [req-9603fd52-137b-4349-a72c-67a0fd9d7bc1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:20.613 2680 INFO cinder.api.openstack.wsgi [req-9603fd52-137b-4349-a72c-67a0fd9d7bc1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:12:20.615 2680 INFO eventlet.wsgi.server [req-9603fd52-137b-4349-a72c-67a0fd9d7bc1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1285 time: 0.3709939
2016-08-03 03:12:30.637 2680 INFO cinder.api.openstack.wsgi [req-690f37b7-8d49-4542-aa9b-d6e450af0bd2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:30.855 2680 INFO cinder.volume.api [req-690f37b7-8d49-4542-aa9b-d6e450af0bd2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:30.864 2680 INFO cinder.api.openstack.wsgi [req-690f37b7-8d49-4542-aa9b-d6e450af0bd2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:12:30.866 2680 INFO eventlet.wsgi.server [req-690f37b7-8d49-4542-aa9b-d6e450af0bd2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1288 time: 0.2331488
2016-08-03 03:12:40.881 2680 INFO cinder.api.openstack.wsgi [req-362a5a51-5800-4e89-9ea8-34ae41dd6572 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:41.086 2680 INFO cinder.volume.api [req-362a5a51-5800-4e89-9ea8-34ae41dd6572 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:41.094 2680 INFO cinder.api.openstack.wsgi [req-362a5a51-5800-4e89-9ea8-34ae41dd6572 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:12:41.096 2680 INFO eventlet.wsgi.server [req-362a5a51-5800-4e89-9ea8-34ae41dd6572 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1288 time: 0.2191319
2016-08-03 03:12:51.109 2680 INFO cinder.api.openstack.wsgi [req-605b44dd-f89f-4561-9937-e04445a93079 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:51.322 2680 INFO cinder.volume.api [req-605b44dd-f89f-4561-9937-e04445a93079 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:51.332 2680 INFO cinder.api.openstack.wsgi [req-605b44dd-f89f-4561-9937-e04445a93079 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:12:51.335 2680 INFO eventlet.wsgi.server [req-605b44dd-f89f-4561-9937-e04445a93079 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1625 time: 0.2296062
2016-08-03 03:12:51.348 2680 INFO cinder.api.openstack.wsgi [req-cb9c15a3-8724-45c8-9faf-dcf18f685107 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:51.553 2680 INFO cinder.volume.api [req-cb9c15a3-8724-45c8-9faf-dcf18f685107 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:51.563 2680 INFO cinder.api.openstack.wsgi [req-cb9c15a3-8724-45c8-9faf-dcf18f685107 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:12:51.564 2680 INFO eventlet.wsgi.server [req-cb9c15a3-8724-45c8-9faf-dcf18f685107 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1625 time: 0.2200990
2016-08-03 03:12:51.941 2680 INFO cinder.api.openstack.wsgi [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] POST http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1/action
2016-08-03 03:12:52.017 2680 INFO cinder.volume.api [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:53.523 2680 INFO cinder.volume.api [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Initialize volume connection completed successfully.
2016-08-03 03:12:53.524 2680 INFO cinder.api.openstack.wsgi [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1/action returned with HTTP 200
2016-08-03 03:12:53.526 2680 INFO eventlet.wsgi.server [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "POST /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1/action HTTP/1.1" status: 200  len: 707 time: 1.5884809
2016-08-03 03:12:53.573 2680 INFO cinder.api.openstack.wsgi [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] POST http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1/action
2016-08-03 03:12:53.644 2680 INFO cinder.volume.api [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:53.793 2680 INFO cinder.volume.api [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Update volume admin metadata completed successfully.
2016-08-03 03:12:54.701 2680 INFO cinder.volume.api [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Attach volume completed successfully.
2016-08-03 03:12:54.702 2680 INFO cinder.api.openstack.wsgi [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1/action returned with HTTP 202
2016-08-03 03:12:54.703 2680 INFO eventlet.wsgi.server [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "POST /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1/action HTTP/1.1" status: 202  len: 211 time: 1.1332991


[at instance launch]
2016-08-03 03:16:33.377 2681 INFO cinder.api.openstack.wsgi [req-1cf4d112-f08d-41ae-ab41-c77079bee672 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:16:33.596 2681 INFO cinder.volume.api [req-1cf4d112-f08d-41ae-ab41-c77079bee672 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:16:33.611 2681 INFO cinder.api.openstack.wsgi [req-1cf4d112-f08d-41ae-ab41-c77079bee672 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:16:33.613 2681 INFO eventlet.wsgi.server [req-1cf4d112-f08d-41ae-ab41-c77079bee672 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1959 time: 0.2410169
2016-08-03 03:16:42.593 2681 INFO cinder.api.openstack.wsgi [req-ab11d142-e33e-4717-bfe0-5df69c47f1a3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:16:42.807 2681 INFO cinder.volume.api [req-ab11d142-e33e-4717-bfe0-5df69c47f1a3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:16:42.823 2681 INFO cinder.api.openstack.wsgi [req-ab11d142-e33e-4717-bfe0-5df69c47f1a3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:16:42.825 2681 INFO eventlet.wsgi.server [req-ab11d142-e33e-4717-bfe0-5df69c47f1a3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1959 time: 0.2363830




nova-api.log

[at instance creation]
2016-08-03 03:11:25.833 2710 INFO nova.osapi_compute.wsgi.server [req-a3ad0b11-1f4f-4df0-a274-5b576f6abbc3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "POST /v2.1/c79ea99641814764b377b47e84a40036/os-volumes_boot HTTP/1.1" status: 202 len: 868 time: 0.7564061
2016-08-03 03:11:26.020 2710 INFO nova.osapi_compute.wsgi.server [req-f04ce17b-6a42-4c21-9427-25aa266c3d52 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1517 time: 0.1810391
2016-08-03 03:11:26.324 2711 INFO nova.osapi_compute.wsgi.server [req-4424f426-f473-4d65-a856-b2579588cbcd 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/detail?limit=21&project_id=c79ea99641814764b377b47e84a40036 HTTP/1.1" status: 200 len: 4867 time: 0.2826262
2016-08-03 03:11:26.649 2711 INFO nova.osapi_compute.wsgi.server [req-940d3238-d488-4399-a5f2-453b1e5895f3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/detail HTTP/1.1" status: 200 len: 2388 time: 0.0422449
2016-08-03 03:11:26.928 2709 INFO nova.osapi_compute.wsgi.server [req-71d68f47-fb18-4d77-b214-28f12173e000 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0728760
2016-08-03 03:11:27.007 2712 INFO nova.osapi_compute.wsgi.server [req-23226c68-672a-4727-97bc-7d6728f2232a 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/limits?reserved=1 HTTP/1.1" status: 200 len: 782 time: 0.0433600
2016-08-03 03:11:27.235 2712 INFO nova.osapi_compute.wsgi.server [req-ad3a569b-d169-4502-8bd9-1f9e51fe7634 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/limits?reserved=1 HTTP/1.1" status: 200 len: 782 time: 0.0491338
2016-08-03 03:11:28.111 2711 INFO nova.osapi_compute.wsgi.server [req-cdfe9de3-9198-495a-ba28-fcbfe5b4b02c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1649 time: 0.3409479
2016-08-03 03:11:28.162 2712 INFO nova.osapi_compute.wsgi.server [req-0845f6ef-5c48-437c-8f97-8e51f4fc05ff 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0438559
2016-08-03 03:11:28.696 2709 INFO nova.osapi_compute.wsgi.server [req-1b193571-8062-4ee1-ba73-9eb009bbe935 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0847881
2016-08-03 03:11:32.089 2709 INFO nova.osapi_compute.wsgi.server [req-d01f9985-dadc-4492-883f-b3996d5de592 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.6425500
2016-08-03 03:11:32.125 2709 INFO nova.osapi_compute.wsgi.server [req-e1763db7-c835-494c-a99c-50a7a42cde9c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0304930
2016-08-03 03:11:32.464 2709 INFO nova.osapi_compute.wsgi.server [req-e258240d-6c73-4e63-8dfd-35f5efe29e33 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0930772
2016-08-03 03:11:37.941 2711 INFO nova.osapi_compute.wsgi.server [req-346fa178-e49a-4995-ace1-c3426bac801c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.2488580
2016-08-03 03:11:37.978 2709 INFO nova.osapi_compute.wsgi.server [req-e8a00041-e7af-481a-a208-3da464f36bb5 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0306971
2016-08-03 03:11:38.305 2712 INFO nova.osapi_compute.wsgi.server [req-ed920329-d97d-4dcd-ab5e-fb359baf6589 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0837080
2016-08-03 03:11:46.296 2709 INFO nova.osapi_compute.wsgi.server [req-cedfa721-f2fe-43d6-bb30-3f23674a293d 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.2526152
2016-08-03 03:11:46.332 2709 INFO nova.osapi_compute.wsgi.server [req-2aa82264-efc5-4ef1-b334-3261aed56c7a 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0314400
2016-08-03 03:11:46.799 2710 INFO nova.osapi_compute.wsgi.server [req-4400b44d-96e6-4897-8169-c6de3a6360ec 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0861251
2016-08-03 03:11:57.297 2711 INFO nova.osapi_compute.wsgi.server [req-e7cae04b-9c32-4d40-9055-340aa2e2cef4 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.2610452
2016-08-03 03:11:57.335 2712 INFO nova.osapi_compute.wsgi.server [req-3493a7ca-ac9f-480d-bf42-d165d2e481de 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0323601
2016-08-03 03:11:57.657 2711 INFO nova.osapi_compute.wsgi.server [req-01f54f5f-7365-4360-8c96-aa51b27bf41a 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0727670
2016-08-03 03:12:10.621 2712 INFO nova.osapi_compute.wsgi.server [req-06d4fde6-9246-4fad-b882-1e8891454aeb 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.2587230
2016-08-03 03:12:10.656 2712 INFO nova.osapi_compute.wsgi.server [req-56d3bbd1-5bcc-44f2-8d55-34c8fa19c4a8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0303671
2016-08-03 03:12:10.985 2709 INFO nova.osapi_compute.wsgi.server [req-9ae156d0-c8b5-4132-91d9-e320ca53ef65 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0793281
2016-08-03 03:12:26.456 2709 INFO nova.osapi_compute.wsgi.server [req-d16298d7-0dfb-4e99-84c1-1ac765b0f053 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.2551560
2016-08-03 03:12:26.490 2712 INFO nova.osapi_compute.wsgi.server [req-397499ed-27a6-4ea5-a409-eff3f5bfa710 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0293500
2016-08-03 03:12:26.811 2712 INFO nova.osapi_compute.wsgi.server [req-3cd7b494-9d5e-47fc-8f6d-ae205196ae75 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0780919
2016-08-03 03:12:44.939 2709 INFO nova.osapi_compute.wsgi.server [req-79274a50-7363-405c-9cdb-cd892ae89a85 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.3976851
2016-08-03 03:12:44.979 2712 INFO nova.osapi_compute.wsgi.server [req-81ede09b-8467-4969-9987-b83d2234f599 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0346951
2016-08-03 03:12:45.328 2710 INFO nova.osapi_compute.wsgi.server [req-f7e17d20-0cca-4536-ad89-b478f5621ce3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0686519
2016-08-03 03:12:59.557 2712 INFO nova.api.openstack.compute.server_external_events [req-ab02cf5c-b43e-47f0-9e70-b951d317d8d7 92d68b60d0f24f3b9bcd8e63e0950a76 528ac3084464490cbb5954778ad889aa - - -] Creating event network-vif-plugged:3fed77aa-044b-407b-bb25-dea33514a830 for instance eb59f435-8af8-4da2-a84d-82b4911235a9
2016-08-03 03:12:59.563 2712 INFO nova.osapi_compute.wsgi.server [req-ab02cf5c-b43e-47f0-9e70-b951d317d8d7 92d68b60d0f24f3b9bcd8e63e0950a76 528ac3084464490cbb5954778ad889aa - - -] 10.1.7.12 "POST /v2.1/528ac3084464490cbb5954778ad889aa/os-server-external-events HTTP/1.1" status: 200 len: 451 time: 0.4265010
2016-08-03 03:13:05.763 2709 INFO nova.osapi_compute.wsgi.server [req-eae57160-51bc-4f3d-b448-aee31f2435f0 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1825 time: 0.2355390
2016-08-03 03:13:05.799 2710 INFO nova.osapi_compute.wsgi.server [req-ad82c230-38eb-4c74-a1f5-ede8e3fbcec1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0298409
2016-08-03 03:13:06.211 2712 INFO nova.osapi_compute.wsgi.server [req-b860afea-deb3-4d24-9a1f-01d2fb11fcee 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0680420


[at instance launch]

2016-08-03 03:16:33.266 2710 INFO nova.osapi_compute.wsgi.server [req-51647a15-3f18-407f-a850-5fc013320220 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1825 time: 0.5836380
2016-08-03 03:16:33.366 2712 INFO nova.osapi_compute.wsgi.server [req-72ee93fc-64b9-4bf2-a0ce-44088d6ee500 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9/os-volume_attachments HTTP/1.1" status: 200 len: 465 time: 0.0945129
2016-08-03 03:16:33.652 2712 INFO nova.osapi_compute.wsgi.server [req-7044270f-fab7-4cb1-89f9-732334f71717 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0338390
2016-08-03 03:16:34.073 2711 INFO nova.osapi_compute.wsgi.server [req-2606f8db-95d8-4458-804d-b767cf86e07d 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0734818
2016-08-03 03:16:42.479 2709 INFO nova.osapi_compute.wsgi.server [req-a69b7d75-36f9-494b-92be-22ce243d394b 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1825 time: 0.4091771
2016-08-03 03:16:42.584 2710 INFO nova.osapi_compute.wsgi.server [req-a56ae96d-05a4-4caf-9fb5-df44eafe06d6 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9/os-volume_attachments HTTP/1.1" status: 200 len: 465 time: 0.0992129
2016-08-03 03:16:42.859 2709 INFO nova.osapi_compute.wsgi.server [req-4ddf2051-bc0c-4d2e-9da0-f60a6161de6b 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0280032
2016-08-03 03:16:43.320 2709 INFO nova.osapi_compute.wsgi.server [req-f1c4a233-4ea0-472e-a44f-975ec2880e15 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "POST /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9/action HTTP/1.1" status: 200 len: 392 time: 0.1222072
2016-08-03 03:16:43.404 2710 INFO nova.osapi_compute.wsgi.server [req-e094c82d-fd0f-41c5-837e-381013359a23 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0704079

STORAGE NODE

volume.log

2016-08-03 03:11:29.294 2096 INFO cinder.volume.flows.manager.create_volume [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume b63c558f-0d3f-41eb-aacf-2706631c98b1: being created as image with specification: {'status': u'creating', 'image_location': (None, None), 'volume_size': 3, 'volume_name': 'volume-b63c558f-0d3f-41eb-aacf-2706631c98b1', 'image_id': u'974eda09-059b-4f51-9d19-80e336466131', 'image_service': <cinder.image.glance.GlanceImageService object at 0x61000d0>, 'image_meta': {'status': u'active', 'name': u'ubuntu_server_16_04_LTS', 'deleted': False, 'container_format': u'bare', 'created_at': datetime.datetime(2016, 8, 1, 8, 18, 50, tzinfo=<iso8601.Utc>), 'disk_format': u'iso', 'updated_at': datetime.datetime(2016, 8, 1, 8, 19, 5, tzinfo=<iso8601.Utc>), 'id': u'974eda09-059b-4f51-9d19-80e336466131', 'owner': u'c79ea99641814764b377b47e84a40036', 'min_ram': 0, 'checksum': u'd2d939ca0e65816790375f6826e4032f', 'min_disk': 2, 'is_public': True, 'deleted_at': None, 'properties': {u'architecture': u'x86_64', u'description': u'ubuntu_server_16_04_LTS'}, 'size': 699400192}}
2016-08-03 03:12:29.022 2096 INFO cinder.image.image_utils [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Image download 667.00 MB at 11.17 MB/s
2016-08-03 03:12:29.056 2096 WARNING oslo.service.loopingcall [req-60d58da2-1315-4659-9ab8-4494fa48bdc7 - - - - -] Function 'cinder.service.Service.report_state' run outlasted interval by 42.60 sec
2016-08-03 03:12:42.198 2096 INFO cinder.image.image_utils [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Converted 667.00 MB image at 56.16 MB/s
2016-08-03 03:12:43.097 2096 INFO cinder.volume.flows.manager.create_volume [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume volume-b63c558f-0d3f-41eb-aacf-2706631c98b1 (b63c558f-0d3f-41eb-aacf-2706631c98b1): created successfully
2016-08-03 03:12:43.104 2096 INFO cinder.volume.manager [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Created volume successfully.
2016-08-03 03:12:52.213 2096 INFO cinder.volume.targets.lio [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Creating iscsi_target for volume: volume-b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:53.524 2096 INFO cinder.volume.manager [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Initialize volume connection completed successfully.
2016-08-03 03:12:54.694 2096 INFO cinder.volume.manager [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Attach volume completed successfully.

And The volume is actually attached to the running instance

[root@osctr01 ~]# cinder list 
+--------------------------------------+--------+------+------+-------------+----------+--------------------------------------+
|                  ID                  | Status | Name | Size | Volume Type | Bootable |             Attached to              |
+--------------------------------------+--------+------+------+-------------+----------+--------------------------------------+
| b63c558f-0d3f-41eb-aacf-2706631c98b1 | in-use |      |  3   |      -      |   true   | eb59f435-8af8-4da2-a84d-82b4911235a9 |
+--------------------------------------+--------+------+------+-------------+----------+--------------------------------------+

I could not find anything wrong in the logs, so I suspect the issue related to the installer rather than to Openstack itself, but I might be missing some very obvious configuration tweak to work around this problem.

Any hint?

Thanks a lot

M

cinder volumes attached but not available during OS install

Hi all,

I just installed Mitaka on three nodes (controller, storage and compute) following this guide http://docs.openstack.org/mitaka/install-guide-rdo/

I stopped at Block Storage Service chapter to have just the basic features before adding complexity.

Everything seems to work fine: I'm able to run instances from ISO images, create cinder volumes, attach them to already running instances (live distros like Cirros), format and mount them, put VMs in network and so on.

The only "glitch" seems to appear when I try to install a non-live distro on a volume starting form a ISO image: both CentOS 7 and Ubuntu 16.04 fail to install for different, yet seemingly related, reasons.

  • CentOS 7 stops install process because Anaconda cannot find any block device available
  • Ubuntu 16.04 stops because, after keyboard and language selection, it says "Your installation CD-ROM cannot be mounted"

These are the logs produced during instance creation:

CONTROLLER NODE

cinder/api.log

[at instance creation]
2016-08-03 03:11:27.244 2680 INFO cinder.api.openstack.wsgi [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] POST http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes
2016-08-03 03:11:27.254 2680 INFO cinder.api.v2.volumes [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Create volume of 3 GB
2016-08-03 03:11:27.406 2680 INFO cinder.volume.api [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Availability Zones retrieved successfully.
2016-08-03 03:11:28.539 2680 INFO cinder.volume.api [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume created successfully.
2016-08-03 03:11:28.541 2680 INFO cinder.api.openstack.wsgi [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes returned with HTTP 202
2016-08-03 03:11:28.543 2680 INFO eventlet.wsgi.server [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "POST /v2/c79ea99641814764b377b47e84a40036/volumes HTTP/1.1" status: 202  len: 1066 time: 1.3087020
2016-08-03 03:11:28.555 2680 INFO cinder.api.openstack.wsgi [req-3a12c1f8-7403-448a-b030-5e29225552aa 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:11:28.887 2680 INFO cinder.volume.api [req-3a12c1f8-7403-448a-b030-5e29225552aa 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:11:28.895 2680 INFO cinder.api.openstack.wsgi [req-3a12c1f8-7403-448a-b030-5e29225552aa 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:11:28.897 2680 INFO eventlet.wsgi.server [req-3a12c1f8-7403-448a-b030-5e29225552aa 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1239 time: 0.3482981
2016-08-03 03:11:28.909 2680 INFO cinder.api.openstack.wsgi [req-f33a7e5d-0cb3-4905-a1d1-6c4be84deff1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:11:29.160 2680 INFO cinder.volume.api [req-f33a7e5d-0cb3-4905-a1d1-6c4be84deff1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:11:29.169 2680 INFO cinder.api.openstack.wsgi [req-f33a7e5d-0cb3-4905-a1d1-6c4be84deff1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:11:29.171 2680 INFO eventlet.wsgi.server [req-f33a7e5d-0cb3-4905-a1d1-6c4be84deff1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1285 time: 0.2659891
2016-08-03 03:11:39.184 2680 INFO cinder.api.openstack.wsgi [req-b5690d80-44da-49d1-9dac-5c80777ae1fe 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:11:39.423 2680 INFO cinder.volume.api [req-b5690d80-44da-49d1-9dac-5c80777ae1fe 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:11:39.433 2680 INFO cinder.api.openstack.wsgi [req-b5690d80-44da-49d1-9dac-5c80777ae1fe 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:11:39.435 2680 INFO eventlet.wsgi.server [req-b5690d80-44da-49d1-9dac-5c80777ae1fe 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1285 time: 0.2557371
2016-08-03 03:11:49.454 2680 INFO cinder.api.openstack.wsgi [req-c51cb49b-ada0-4b99-8c9d-1bda3e5c02f5 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:11:49.686 2680 INFO cinder.volume.api [req-c51cb49b-ada0-4b99-8c9d-1bda3e5c02f5 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:11:49.695 2680 INFO cinder.api.openstack.wsgi [req-c51cb49b-ada0-4b99-8c9d-1bda3e5c02f5 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:11:49.697 2680 INFO eventlet.wsgi.server [req-c51cb49b-ada0-4b99-8c9d-1bda3e5c02f5 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1285 time: 0.2486291
2016-08-03 03:11:59.714 2680 INFO cinder.api.openstack.wsgi [req-62ca7659-0965-40db-a6e7-6ab1776efc6c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:11:59.938 2680 INFO cinder.volume.api [req-62ca7659-0965-40db-a6e7-6ab1776efc6c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:11:59.947 2680 INFO cinder.api.openstack.wsgi [req-62ca7659-0965-40db-a6e7-6ab1776efc6c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:11:59.949 2680 INFO eventlet.wsgi.server [req-62ca7659-0965-40db-a6e7-6ab1776efc6c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1285 time: 0.2389159
2016-08-03 03:12:09.969 2680 INFO cinder.api.openstack.wsgi [req-69d00211-fcc1-4d4e-bf61-ab174b8715f6 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:10.216 2680 INFO cinder.volume.api [req-69d00211-fcc1-4d4e-bf61-ab174b8715f6 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:10.225 2680 INFO cinder.api.openstack.wsgi [req-69d00211-fcc1-4d4e-bf61-ab174b8715f6 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:12:10.227 2680 INFO eventlet.wsgi.server [req-69d00211-fcc1-4d4e-bf61-ab174b8715f6 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1285 time: 0.2633100
2016-08-03 03:12:20.249 2680 INFO cinder.api.openstack.wsgi [req-9603fd52-137b-4349-a72c-67a0fd9d7bc1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:20.604 2680 INFO cinder.volume.api [req-9603fd52-137b-4349-a72c-67a0fd9d7bc1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:20.613 2680 INFO cinder.api.openstack.wsgi [req-9603fd52-137b-4349-a72c-67a0fd9d7bc1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:12:20.615 2680 INFO eventlet.wsgi.server [req-9603fd52-137b-4349-a72c-67a0fd9d7bc1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1285 time: 0.3709939
2016-08-03 03:12:30.637 2680 INFO cinder.api.openstack.wsgi [req-690f37b7-8d49-4542-aa9b-d6e450af0bd2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:30.855 2680 INFO cinder.volume.api [req-690f37b7-8d49-4542-aa9b-d6e450af0bd2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:30.864 2680 INFO cinder.api.openstack.wsgi [req-690f37b7-8d49-4542-aa9b-d6e450af0bd2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:12:30.866 2680 INFO eventlet.wsgi.server [req-690f37b7-8d49-4542-aa9b-d6e450af0bd2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1288 time: 0.2331488
2016-08-03 03:12:40.881 2680 INFO cinder.api.openstack.wsgi [req-362a5a51-5800-4e89-9ea8-34ae41dd6572 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:41.086 2680 INFO cinder.volume.api [req-362a5a51-5800-4e89-9ea8-34ae41dd6572 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:41.094 2680 INFO cinder.api.openstack.wsgi [req-362a5a51-5800-4e89-9ea8-34ae41dd6572 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:12:41.096 2680 INFO eventlet.wsgi.server [req-362a5a51-5800-4e89-9ea8-34ae41dd6572 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1288 time: 0.2191319
2016-08-03 03:12:51.109 2680 INFO cinder.api.openstack.wsgi [req-605b44dd-f89f-4561-9937-e04445a93079 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:51.322 2680 INFO cinder.volume.api [req-605b44dd-f89f-4561-9937-e04445a93079 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:51.332 2680 INFO cinder.api.openstack.wsgi [req-605b44dd-f89f-4561-9937-e04445a93079 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:12:51.335 2680 INFO eventlet.wsgi.server [req-605b44dd-f89f-4561-9937-e04445a93079 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1625 time: 0.2296062
2016-08-03 03:12:51.348 2680 INFO cinder.api.openstack.wsgi [req-cb9c15a3-8724-45c8-9faf-dcf18f685107 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:51.553 2680 INFO cinder.volume.api [req-cb9c15a3-8724-45c8-9faf-dcf18f685107 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:51.563 2680 INFO cinder.api.openstack.wsgi [req-cb9c15a3-8724-45c8-9faf-dcf18f685107 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:12:51.564 2680 INFO eventlet.wsgi.server [req-cb9c15a3-8724-45c8-9faf-dcf18f685107 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1625 time: 0.2200990
2016-08-03 03:12:51.941 2680 INFO cinder.api.openstack.wsgi [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] POST http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1/action
2016-08-03 03:12:52.017 2680 INFO cinder.volume.api [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:53.523 2680 INFO cinder.volume.api [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Initialize volume connection completed successfully.
2016-08-03 03:12:53.524 2680 INFO cinder.api.openstack.wsgi [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1/action returned with HTTP 200
2016-08-03 03:12:53.526 2680 INFO eventlet.wsgi.server [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "POST /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1/action HTTP/1.1" status: 200  len: 707 time: 1.5884809
2016-08-03 03:12:53.573 2680 INFO cinder.api.openstack.wsgi [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] POST http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1/action
2016-08-03 03:12:53.644 2680 INFO cinder.volume.api [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:12:53.793 2680 INFO cinder.volume.api [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Update volume admin metadata completed successfully.
2016-08-03 03:12:54.701 2680 INFO cinder.volume.api [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Attach volume completed successfully.
2016-08-03 03:12:54.702 2680 INFO cinder.api.openstack.wsgi [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1/action returned with HTTP 202
2016-08-03 03:12:54.703 2680 INFO eventlet.wsgi.server [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.6.182 "POST /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1/action HTTP/1.1" status: 202  len: 211 time: 1.1332991


[at instance launch]
2016-08-03 03:16:33.377 2681 INFO cinder.api.openstack.wsgi [req-1cf4d112-f08d-41ae-ab41-c77079bee672 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:16:33.596 2681 INFO cinder.volume.api [req-1cf4d112-f08d-41ae-ab41-c77079bee672 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:16:33.611 2681 INFO cinder.api.openstack.wsgi [req-1cf4d112-f08d-41ae-ab41-c77079bee672 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:16:33.613 2681 INFO eventlet.wsgi.server [req-1cf4d112-f08d-41ae-ab41-c77079bee672 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1959 time: 0.2410169
2016-08-03 03:16:42.593 2681 INFO cinder.api.openstack.wsgi [req-ab11d142-e33e-4717-bfe0-5df69c47f1a3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] GET http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:16:42.807 2681 INFO cinder.volume.api [req-ab11d142-e33e-4717-bfe0-5df69c47f1a3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume info retrieved successfully.
2016-08-03 03:16:42.823 2681 INFO cinder.api.openstack.wsgi [req-ab11d142-e33e-4717-bfe0-5df69c47f1a3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] http://osctr01.example.com:8776/v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 returned with HTTP 200
2016-08-03 03:16:42.825 2681 INFO eventlet.wsgi.server [req-ab11d142-e33e-4717-bfe0-5df69c47f1a3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2/c79ea99641814764b377b47e84a40036/volumes/b63c558f-0d3f-41eb-aacf-2706631c98b1 HTTP/1.1" status: 200  len: 1959 time: 0.2363830




nova-api.log

[at instance creation]
2016-08-03 03:11:25.833 2710 INFO nova.osapi_compute.wsgi.server [req-a3ad0b11-1f4f-4df0-a274-5b576f6abbc3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "POST /v2.1/c79ea99641814764b377b47e84a40036/os-volumes_boot HTTP/1.1" status: 202 len: 868 time: 0.7564061
2016-08-03 03:11:26.020 2710 INFO nova.osapi_compute.wsgi.server [req-f04ce17b-6a42-4c21-9427-25aa266c3d52 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1517 time: 0.1810391
2016-08-03 03:11:26.324 2711 INFO nova.osapi_compute.wsgi.server [req-4424f426-f473-4d65-a856-b2579588cbcd 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/detail?limit=21&project_id=c79ea99641814764b377b47e84a40036 HTTP/1.1" status: 200 len: 4867 time: 0.2826262
2016-08-03 03:11:26.649 2711 INFO nova.osapi_compute.wsgi.server [req-940d3238-d488-4399-a5f2-453b1e5895f3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/detail HTTP/1.1" status: 200 len: 2388 time: 0.0422449
2016-08-03 03:11:26.928 2709 INFO nova.osapi_compute.wsgi.server [req-71d68f47-fb18-4d77-b214-28f12173e000 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0728760
2016-08-03 03:11:27.007 2712 INFO nova.osapi_compute.wsgi.server [req-23226c68-672a-4727-97bc-7d6728f2232a 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/limits?reserved=1 HTTP/1.1" status: 200 len: 782 time: 0.0433600
2016-08-03 03:11:27.235 2712 INFO nova.osapi_compute.wsgi.server [req-ad3a569b-d169-4502-8bd9-1f9e51fe7634 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/limits?reserved=1 HTTP/1.1" status: 200 len: 782 time: 0.0491338
2016-08-03 03:11:28.111 2711 INFO nova.osapi_compute.wsgi.server [req-cdfe9de3-9198-495a-ba28-fcbfe5b4b02c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1649 time: 0.3409479
2016-08-03 03:11:28.162 2712 INFO nova.osapi_compute.wsgi.server [req-0845f6ef-5c48-437c-8f97-8e51f4fc05ff 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0438559
2016-08-03 03:11:28.696 2709 INFO nova.osapi_compute.wsgi.server [req-1b193571-8062-4ee1-ba73-9eb009bbe935 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0847881
2016-08-03 03:11:32.089 2709 INFO nova.osapi_compute.wsgi.server [req-d01f9985-dadc-4492-883f-b3996d5de592 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.6425500
2016-08-03 03:11:32.125 2709 INFO nova.osapi_compute.wsgi.server [req-e1763db7-c835-494c-a99c-50a7a42cde9c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0304930
2016-08-03 03:11:32.464 2709 INFO nova.osapi_compute.wsgi.server [req-e258240d-6c73-4e63-8dfd-35f5efe29e33 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0930772
2016-08-03 03:11:37.941 2711 INFO nova.osapi_compute.wsgi.server [req-346fa178-e49a-4995-ace1-c3426bac801c 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.2488580
2016-08-03 03:11:37.978 2709 INFO nova.osapi_compute.wsgi.server [req-e8a00041-e7af-481a-a208-3da464f36bb5 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0306971
2016-08-03 03:11:38.305 2712 INFO nova.osapi_compute.wsgi.server [req-ed920329-d97d-4dcd-ab5e-fb359baf6589 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0837080
2016-08-03 03:11:46.296 2709 INFO nova.osapi_compute.wsgi.server [req-cedfa721-f2fe-43d6-bb30-3f23674a293d 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.2526152
2016-08-03 03:11:46.332 2709 INFO nova.osapi_compute.wsgi.server [req-2aa82264-efc5-4ef1-b334-3261aed56c7a 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0314400
2016-08-03 03:11:46.799 2710 INFO nova.osapi_compute.wsgi.server [req-4400b44d-96e6-4897-8169-c6de3a6360ec 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0861251
2016-08-03 03:11:57.297 2711 INFO nova.osapi_compute.wsgi.server [req-e7cae04b-9c32-4d40-9055-340aa2e2cef4 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.2610452
2016-08-03 03:11:57.335 2712 INFO nova.osapi_compute.wsgi.server [req-3493a7ca-ac9f-480d-bf42-d165d2e481de 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0323601
2016-08-03 03:11:57.657 2711 INFO nova.osapi_compute.wsgi.server [req-01f54f5f-7365-4360-8c96-aa51b27bf41a 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0727670
2016-08-03 03:12:10.621 2712 INFO nova.osapi_compute.wsgi.server [req-06d4fde6-9246-4fad-b882-1e8891454aeb 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.2587230
2016-08-03 03:12:10.656 2712 INFO nova.osapi_compute.wsgi.server [req-56d3bbd1-5bcc-44f2-8d55-34c8fa19c4a8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0303671
2016-08-03 03:12:10.985 2709 INFO nova.osapi_compute.wsgi.server [req-9ae156d0-c8b5-4132-91d9-e320ca53ef65 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0793281
2016-08-03 03:12:26.456 2709 INFO nova.osapi_compute.wsgi.server [req-d16298d7-0dfb-4e99-84c1-1ac765b0f053 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.2551560
2016-08-03 03:12:26.490 2712 INFO nova.osapi_compute.wsgi.server [req-397499ed-27a6-4ea5-a409-eff3f5bfa710 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0293500
2016-08-03 03:12:26.811 2712 INFO nova.osapi_compute.wsgi.server [req-3cd7b494-9d5e-47fc-8f6d-ae205196ae75 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0780919
2016-08-03 03:12:44.939 2709 INFO nova.osapi_compute.wsgi.server [req-79274a50-7363-405c-9cdb-cd892ae89a85 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1774 time: 0.3976851
2016-08-03 03:12:44.979 2712 INFO nova.osapi_compute.wsgi.server [req-81ede09b-8467-4969-9987-b83d2234f599 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0346951
2016-08-03 03:12:45.328 2710 INFO nova.osapi_compute.wsgi.server [req-f7e17d20-0cca-4536-ad89-b478f5621ce3 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0686519
2016-08-03 03:12:59.557 2712 INFO nova.api.openstack.compute.server_external_events [req-ab02cf5c-b43e-47f0-9e70-b951d317d8d7 92d68b60d0f24f3b9bcd8e63e0950a76 528ac3084464490cbb5954778ad889aa - - -] Creating event network-vif-plugged:3fed77aa-044b-407b-bb25-dea33514a830 for instance eb59f435-8af8-4da2-a84d-82b4911235a9
2016-08-03 03:12:59.563 2712 INFO nova.osapi_compute.wsgi.server [req-ab02cf5c-b43e-47f0-9e70-b951d317d8d7 92d68b60d0f24f3b9bcd8e63e0950a76 528ac3084464490cbb5954778ad889aa - - -] 10.1.7.12 "POST /v2.1/528ac3084464490cbb5954778ad889aa/os-server-external-events HTTP/1.1" status: 200 len: 451 time: 0.4265010
2016-08-03 03:13:05.763 2709 INFO nova.osapi_compute.wsgi.server [req-eae57160-51bc-4f3d-b448-aee31f2435f0 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1825 time: 0.2355390
2016-08-03 03:13:05.799 2710 INFO nova.osapi_compute.wsgi.server [req-ad82c230-38eb-4c74-a1f5-ede8e3fbcec1 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0298409
2016-08-03 03:13:06.211 2712 INFO nova.osapi_compute.wsgi.server [req-b860afea-deb3-4d24-9a1f-01d2fb11fcee 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0680420


[at instance launch]

2016-08-03 03:16:33.266 2710 INFO nova.osapi_compute.wsgi.server [req-51647a15-3f18-407f-a850-5fc013320220 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1825 time: 0.5836380
2016-08-03 03:16:33.366 2712 INFO nova.osapi_compute.wsgi.server [req-72ee93fc-64b9-4bf2-a0ce-44088d6ee500 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9/os-volume_attachments HTTP/1.1" status: 200 len: 465 time: 0.0945129
2016-08-03 03:16:33.652 2712 INFO nova.osapi_compute.wsgi.server [req-7044270f-fab7-4cb1-89f9-732334f71717 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0338390
2016-08-03 03:16:34.073 2711 INFO nova.osapi_compute.wsgi.server [req-2606f8db-95d8-4458-804d-b767cf86e07d 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0734818
2016-08-03 03:16:42.479 2709 INFO nova.osapi_compute.wsgi.server [req-a69b7d75-36f9-494b-92be-22ce243d394b 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9 HTTP/1.1" status: 200 len: 1825 time: 0.4091771
2016-08-03 03:16:42.584 2710 INFO nova.osapi_compute.wsgi.server [req-a56ae96d-05a4-4caf-9fb5-df44eafe06d6 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9/os-volume_attachments HTTP/1.1" status: 200 len: 465 time: 0.0992129
2016-08-03 03:16:42.859 2709 INFO nova.osapi_compute.wsgi.server [req-4ddf2051-bc0c-4d2e-9da0-f60a6161de6b 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/flavors/2 HTTP/1.1" status: 200 len: 699 time: 0.0280032
2016-08-03 03:16:43.320 2709 INFO nova.osapi_compute.wsgi.server [req-f1c4a233-4ea0-472e-a44f-975ec2880e15 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "POST /v2.1/c79ea99641814764b377b47e84a40036/servers/eb59f435-8af8-4da2-a84d-82b4911235a9/action HTTP/1.1" status: 200 len: 392 time: 0.1222072
2016-08-03 03:16:43.404 2710 INFO nova.osapi_compute.wsgi.server [req-e094c82d-fd0f-41c5-837e-381013359a23 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] 10.1.7.12 "GET /v2.1/c79ea99641814764b377b47e84a40036/extensions HTTP/1.1" status: 200 len: 21766 time: 0.0704079

STORAGE NODE

volume.log

2016-08-03 03:11:29.294 2096 INFO cinder.volume.flows.manager.create_volume [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume b63c558f-0d3f-41eb-aacf-2706631c98b1: being created as image with specification: {'status': u'creating', 'image_location': (None, None), 'volume_size': 3, 'volume_name': 'volume-b63c558f-0d3f-41eb-aacf-2706631c98b1', 'image_id': u'974eda09-059b-4f51-9d19-80e336466131', 'image_service': <cinder.image.glance.GlanceImageService object at 0x61000d0>, 'image_meta': {'status': u'active', 'name': u'ubuntu_server_16_04_LTS', 'deleted': False, 'container_format': u'bare', 'created_at': datetime.datetime(2016, 8, 1, 8, 18, 50, tzinfo=<iso8601.Utc>), 'disk_format': u'iso', 'updated_at': datetime.datetime(2016, 8, 1, 8, 19, 5, tzinfo=<iso8601.Utc>), 'id': u'974eda09-059b-4f51-9d19-80e336466131', 'owner': u'c79ea99641814764b377b47e84a40036', 'min_ram': 0, 'checksum': u'd2d939ca0e65816790375f6826e4032f', 'min_disk': 2, 'is_public': True, 'deleted_at': None, 'properties': {u'architecture': u'x86_64', u'description': u'ubuntu_server_16_04_LTS'}, 'size': 699400192}}
2016-08-03 03:12:29.022 2096 INFO cinder.image.image_utils [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Image download 667.00 MB at 11.17 MB/s
2016-08-03 03:12:29.056 2096 WARNING oslo.service.loopingcall [req-60d58da2-1315-4659-9ab8-4494fa48bdc7 - - - - -] Function 'cinder.service.Service.report_state' run outlasted interval by 42.60 sec
2016-08-03 03:12:42.198 2096 INFO cinder.image.image_utils [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Converted 667.00 MB image at 56.16 MB/s
2016-08-03 03:12:43.097 2096 INFO cinder.volume.flows.manager.create_volume [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Volume volume-b63c558f-0d3f-41eb-aacf-2706631c98b1 (b63c558f-0d3f-41eb-aacf-2706631c98b1): created successfully
2016-08-03 03:12:43.104 2096 INFO cinder.volume.manager [req-6c15c3e2-bc64-48d6-9a08-3af8402f83d8 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Created volume successfully.
2016-08-03 03:12:52.213 2096 INFO cinder.volume.targets.lio [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Creating iscsi_target for volume: volume-b63c558f-0d3f-41eb-aacf-2706631c98b1
2016-08-03 03:12:53.524 2096 INFO cinder.volume.manager [req-4ec02255-0a5b-43c0-8d23-48253a5259a2 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Initialize volume connection completed successfully.
2016-08-03 03:12:54.694 2096 INFO cinder.volume.manager [req-121c6bd9-3604-41ef-888b-897366a19240 47959e2d1da143f2bc33b216796e2f4c c79ea99641814764b377b47e84a40036 - - -] Attach volume completed successfully.

And The volume is actually attached to the running instance

[root@osctr01 ~]# cinder list 
+--------------------------------------+--------+------+------+-------------+----------+--------------------------------------+
|                  ID                  | Status | Name | Size | Volume Type | Bootable |             Attached to              |
+--------------------------------------+--------+------+------+-------------+----------+--------------------------------------+
| b63c558f-0d3f-41eb-aacf-2706631c98b1 | in-use |      |  3   |      -      |   true   | eb59f435-8af8-4da2-a84d-82b4911235a9 |
+--------------------------------------+--------+------+------+-------------+----------+--------------------------------------+

I could not find anything wrong in the logs, so I suspect the issue related to the installer rather than to Openstack itself, but I might be missing some very obvious configuration tweak to work around this problem.

Any hint?

Thanks a lot

M