openstack team mailing list archive

Thread
Date
Maintenance mode not working - NotImplementedError

To: Openstack <openstack@xxxxxxxxxxxxxxxxxxx>
From: Andrew Holway <a.holway@xxxxxxxxxxxx>
Date: Fri, 14 Dec 2012 11:09:29 +0100
Hi,

Can someone help me with this? I guess I have not set something up correctly. 

Availability zones? Aggregates?

Thanks,

Andrew

[root@blade02 ~]# nova host-update blade04 --maintenance enable
ERROR: The server has either erred or is incapable of performing the requested operation. (HTTP 500) (Request-ID: req-5064df6f-b9ca-4ced-933b-8c6e731f1b47)


[root@blade02 ~]# grep 10:58 /var/log/nova/api.log
2012-12-13 10:58:38 INFO nova.api.openstack.wsgi [req-45b82541-77e5-4108-816d-3abed565d9d9 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8774/v2/88fe447d408d418baad31f681330a648/flavors/detail
2012-12-13 10:58:38 DEBUG nova.api.openstack.wsgi [req-45b82541-77e5-4108-816d-3abed565d9d9 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3820) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:38 INFO nova.api.openstack.wsgi [req-45b82541-77e5-4108-816d-3abed565d9d9 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8774/v2/88fe447d408d418baad31f681330a648/flavors/detail returned with HTTP 200
2012-12-13 10:58:38 INFO nova.osapi_compute.wsgi.server [req-45b82541-77e5-4108-816d-3abed565d9d9 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:38] "GET /v2/88fe447d408d418baad31f681330a648/flavors/detail HTTP/1.1" 200 2243 0.053425
2012-12-13 10:58:38 INFO nova.api.openstack.wsgi [req-dfe40c5f-b5a9-422c-8b24-e40293c139d2 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8774/v2/88fe447d408d418baad31f681330a648/servers/detail?project_id=88fe447d408d418baad31f681330a648
2012-12-13 10:58:38 DEBUG nova.api.openstack.wsgi [req-dfe40c5f-b5a9-422c-8b24-e40293c139d2 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3820) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:38 DEBUG nova.compute.api [req-dfe40c5f-b5a9-422c-8b24-e40293c139d2 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] Searching by: {'deleted': False, 'project_id': u'88fe447d408d418baad31f681330a648'} from (pid=3820) get_all /usr/lib/python2.6/site-packages/nova/compute/api.py:1055
2012-12-13 10:58:38 INFO nova.api.openstack.wsgi [req-dfe40c5f-b5a9-422c-8b24-e40293c139d2 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8774/v2/88fe447d408d418baad31f681330a648/servers/detail?project_id=88fe447d408d418baad31f681330a648 returned with HTTP 200
2012-12-13 10:58:38 INFO nova.osapi_compute.wsgi.server [req-dfe40c5f-b5a9-422c-8b24-e40293c139d2 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:38] "GET /v2/88fe447d408d418baad31f681330a648/servers/detail?project_id=88fe447d408d418baad31f681330a648 HTTP/1.1" 200 187 0.078589
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-ce88fce8-9356-43f8-a4db-b1f767df35e3 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-floating-ips
2012-12-13 10:58:39 DEBUG nova.api.openstack.wsgi [req-ce88fce8-9356-43f8-a4db-b1f767df35e3 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3820) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:39 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... from (pid=3820) multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:351
2012-12-13 10:58:39 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is a8a41c7e8c9540e29a16e37380502586 from (pid=3820) multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:354
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-ce88fce8-9356-43f8-a4db-b1f767df35e3 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-floating-ips returned with HTTP 200
2012-12-13 10:58:39 INFO nova.osapi_compute.wsgi.server [req-ce88fce8-9356-43f8-a4db-b1f767df35e3 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:39] "GET /v2/88fe447d408d418baad31f681330a648/os-floating-ips HTTP/1.1" 200 192 0.104925
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-1c54f129-8841-475e-a893-9f074849ba54 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-quota-sets/88fe447d408d418baad31f681330a648
2012-12-13 10:58:39 DEBUG nova.api.openstack.wsgi [req-1c54f129-8841-475e-a893-9f074849ba54 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3820) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-1c54f129-8841-475e-a893-9f074849ba54 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-quota-sets/88fe447d408d418baad31f681330a648 returned with HTTP 200
2012-12-13 10:58:39 INFO nova.osapi_compute.wsgi.server [req-1c54f129-8841-475e-a893-9f074849ba54 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:39] "GET /v2/88fe447d408d418baad31f681330a648/os-quota-sets/88fe447d408d418baad31f681330a648 HTTP/1.1" 200 519 0.049900
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-529b7633-fd0a-4962-9f83-a4a4b6a9f426 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8776/v1/88fe447d408d418baad31f681330a648/volumes/detail
2012-12-13 10:58:39 DEBUG nova.api.openstack.wsgi [req-529b7633-fd0a-4962-9f83-a4a4b6a9f426 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3821) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-529b7633-fd0a-4962-9f83-a4a4b6a9f426 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8776/v1/88fe447d408d418baad31f681330a648/volumes/detail returned with HTTP 200
2012-12-13 10:58:39 INFO nova.osapi_volume.wsgi.server [req-529b7633-fd0a-4962-9f83-a4a4b6a9f426 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:39] "GET /v1/88fe447d408d418baad31f681330a648/volumes/detail HTTP/1.1" 200 187 0.111237
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-ca92d5ed-db26-4837-a5e3-e676b75de0ba 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-keypairs
2012-12-13 10:58:39 DEBUG nova.api.openstack.wsgi [req-ca92d5ed-db26-4837-a5e3-e676b75de0ba 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3820) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-ca92d5ed-db26-4837-a5e3-e676b75de0ba 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-keypairs returned with HTTP 200
2012-12-13 10:58:39 INFO nova.osapi_compute.wsgi.server [req-ca92d5ed-db26-4837-a5e3-e676b75de0ba 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:39] "GET /v2/88fe447d408d418baad31f681330a648/os-keypairs HTTP/1.1" 200 188 0.045994
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-70bd98f3-521b-48d8-b59a-a9f6a4dcd066 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-security-groups
2012-12-13 10:58:39 DEBUG nova.api.openstack.wsgi [req-70bd98f3-521b-48d8-b59a-a9f6a4dcd066 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3820) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-70bd98f3-521b-48d8-b59a-a9f6a4dcd066 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-security-groups returned with HTTP 200
2012-12-13 10:58:39 INFO nova.osapi_compute.wsgi.server [req-70bd98f3-521b-48d8-b59a-a9f6a4dcd066 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:39] "GET /v2/88fe447d408d418baad31f681330a648/os-security-groups HTTP/1.1" 200 312 0.052847
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-5d90febd-8908-40cf-a1ff-8c2fbb4013f5 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8776/v1/88fe447d408d418baad31f681330a648/volumes/detail
2012-12-13 10:58:39 DEBUG nova.api.openstack.wsgi [req-5d90febd-8908-40cf-a1ff-8c2fbb4013f5 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3821) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-5d90febd-8908-40cf-a1ff-8c2fbb4013f5 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8776/v1/88fe447d408d418baad31f681330a648/volumes/detail returned with HTTP 200
2012-12-13 10:58:39 INFO nova.osapi_volume.wsgi.server [req-5d90febd-8908-40cf-a1ff-8c2fbb4013f5 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:39] "GET /v1/88fe447d408d418baad31f681330a648/volumes/detail HTTP/1.1" 200 187 0.050028
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-25663c1a-6dfa-4abc-80ee-b251c049be1d 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8776/v1/88fe447d408d418baad31f681330a648/snapshots/detail
2012-12-13 10:58:39 DEBUG nova.api.openstack.wsgi [req-25663c1a-6dfa-4abc-80ee-b251c049be1d 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3821) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:39 INFO nova.api.openstack.wsgi [req-25663c1a-6dfa-4abc-80ee-b251c049be1d 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8776/v1/88fe447d408d418baad31f681330a648/snapshots/detail returned with HTTP 200
2012-12-13 10:58:39 INFO nova.osapi_volume.wsgi.server [req-25663c1a-6dfa-4abc-80ee-b251c049be1d 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:39] "GET /v1/88fe447d408d418baad31f681330a648/snapshots/detail HTTP/1.1" 200 189 0.048329
2012-12-13 10:58:52 INFO nova.api.openstack.wsgi [req-c741a694-793a-43a8-af61-434fcc024aed 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8774/v2/88fe447d408d418baad31f681330a648/flavors/detail
2012-12-13 10:58:52 DEBUG nova.api.openstack.wsgi [req-c741a694-793a-43a8-af61-434fcc024aed 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3820) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:52 INFO nova.api.openstack.wsgi [req-c741a694-793a-43a8-af61-434fcc024aed 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8774/v2/88fe447d408d418baad31f681330a648/flavors/detail returned with HTTP 200
2012-12-13 10:58:52 INFO nova.osapi_compute.wsgi.server [req-c741a694-793a-43a8-af61-434fcc024aed 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:52] "GET /v2/88fe447d408d418baad31f681330a648/flavors/detail HTTP/1.1" 200 2243 0.052477
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-8f0db85b-3de8-439b-9279-be0963b814f5 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-keypairs
2012-12-13 10:58:53 DEBUG nova.api.openstack.wsgi [req-8f0db85b-3de8-439b-9279-be0963b814f5 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3820) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-8f0db85b-3de8-439b-9279-be0963b814f5 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-keypairs returned with HTTP 200
2012-12-13 10:58:53 INFO nova.osapi_compute.wsgi.server [req-8f0db85b-3de8-439b-9279-be0963b814f5 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:53] "GET /v2/88fe447d408d418baad31f681330a648/os-keypairs HTTP/1.1" 200 188 0.045713
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-5982f348-7b08-4db5-a828-a9f05a02a4d2 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-security-groups
2012-12-13 10:58:53 DEBUG nova.api.openstack.wsgi [req-5982f348-7b08-4db5-a828-a9f05a02a4d2 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3820) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-5982f348-7b08-4db5-a828-a9f05a02a4d2 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-security-groups returned with HTTP 200
2012-12-13 10:58:53 INFO nova.osapi_compute.wsgi.server [req-5982f348-7b08-4db5-a828-a9f05a02a4d2 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:53] "GET /v2/88fe447d408d418baad31f681330a648/os-security-groups HTTP/1.1" 200 312 0.052340
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-aafaddb2-7171-40f8-bb47-c97497f8f7ae 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8776/v1/88fe447d408d418baad31f681330a648/volumes/detail
2012-12-13 10:58:53 DEBUG nova.api.openstack.wsgi [req-aafaddb2-7171-40f8-bb47-c97497f8f7ae 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3821) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-aafaddb2-7171-40f8-bb47-c97497f8f7ae 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8776/v1/88fe447d408d418baad31f681330a648/volumes/detail returned with HTTP 200
2012-12-13 10:58:53 INFO nova.osapi_volume.wsgi.server [req-aafaddb2-7171-40f8-bb47-c97497f8f7ae 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:53] "GET /v1/88fe447d408d418baad31f681330a648/volumes/detail HTTP/1.1" 200 187 0.050342
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-32f17023-61ac-4856-a46c-54051a0f09be 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8776/v1/88fe447d408d418baad31f681330a648/snapshots/detail
2012-12-13 10:58:53 DEBUG nova.api.openstack.wsgi [req-32f17023-61ac-4856-a46c-54051a0f09be 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3821) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-32f17023-61ac-4856-a46c-54051a0f09be 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8776/v1/88fe447d408d418baad31f681330a648/snapshots/detail returned with HTTP 200
2012-12-13 10:58:53 INFO nova.osapi_volume.wsgi.server [req-32f17023-61ac-4856-a46c-54051a0f09be 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:53] "GET /v1/88fe447d408d418baad31f681330a648/snapshots/detail HTTP/1.1" 200 189 0.167588
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-8cdce5b1-bdad-4939-85d8-c3f59d06e1ac 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8774/v2/88fe447d408d418baad31f681330a648/servers/detail?project_id=88fe447d408d418baad31f681330a648
2012-12-13 10:58:53 DEBUG nova.api.openstack.wsgi [req-8cdce5b1-bdad-4939-85d8-c3f59d06e1ac 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3820) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:53 DEBUG nova.compute.api [req-8cdce5b1-bdad-4939-85d8-c3f59d06e1ac 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] Searching by: {'deleted': False, 'project_id': u'88fe447d408d418baad31f681330a648'} from (pid=3820) get_all /usr/lib/python2.6/site-packages/nova/compute/api.py:1055
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-8cdce5b1-bdad-4939-85d8-c3f59d06e1ac 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8774/v2/88fe447d408d418baad31f681330a648/servers/detail?project_id=88fe447d408d418baad31f681330a648 returned with HTTP 200
2012-12-13 10:58:53 INFO nova.osapi_compute.wsgi.server [req-8cdce5b1-bdad-4939-85d8-c3f59d06e1ac 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:53] "GET /v2/88fe447d408d418baad31f681330a648/servers/detail?project_id=88fe447d408d418baad31f681330a648 HTTP/1.1" 200 187 0.085209
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-8ae803e8-1d8a-4bc3-aa69-8c3f809f15b8 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-floating-ips
2012-12-13 10:58:53 DEBUG nova.api.openstack.wsgi [req-8ae803e8-1d8a-4bc3-aa69-8c3f809f15b8 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3820) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:53 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... from (pid=3820) multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:351
2012-12-13 10:58:53 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is ae43551bd28642d093ac52f9195dda32 from (pid=3820) multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:354
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-8ae803e8-1d8a-4bc3-aa69-8c3f809f15b8 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-floating-ips returned with HTTP 200
2012-12-13 10:58:53 INFO nova.osapi_compute.wsgi.server [req-8ae803e8-1d8a-4bc3-aa69-8c3f809f15b8 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:53] "GET /v2/88fe447d408d418baad31f681330a648/os-floating-ips HTTP/1.1" 200 192 0.104889
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-97c52db3-ae4e-4e3c-8d99-053bfaef1c90 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-quota-sets/88fe447d408d418baad31f681330a648
2012-12-13 10:58:53 DEBUG nova.api.openstack.wsgi [req-97c52db3-ae4e-4e3c-8d99-053bfaef1c90 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3820) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-97c52db3-ae4e-4e3c-8d99-053bfaef1c90 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8774/v2/88fe447d408d418baad31f681330a648/os-quota-sets/88fe447d408d418baad31f681330a648 returned with HTTP 200
2012-12-13 10:58:53 INFO nova.osapi_compute.wsgi.server [req-97c52db3-ae4e-4e3c-8d99-053bfaef1c90 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:53] "GET /v2/88fe447d408d418baad31f681330a648/os-quota-sets/88fe447d408d418baad31f681330a648 HTTP/1.1" 200 519 0.047973
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-74b6476a-e324-466d-938c-fe09913ab0c4 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] GET http://controller:8776/v1/88fe447d408d418baad31f681330a648/volumes/detail
2012-12-13 10:58:53 DEBUG nova.api.openstack.wsgi [req-74b6476a-e324-466d-938c-fe09913ab0c4 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] No Content-Type provided in request from (pid=3821) get_body /usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py:783
2012-12-13 10:58:53 INFO nova.api.openstack.wsgi [req-74b6476a-e324-466d-938c-fe09913ab0c4 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] http://controller:8776/v1/88fe447d408d418baad31f681330a648/volumes/detail returned with HTTP 200
2012-12-13 10:58:53 INFO nova.osapi_volume.wsgi.server [req-74b6476a-e324-466d-938c-fe09913ab0c4 58c4fd56b6924264b914659e7c0ef2f2 88fe447d408d418baad31f681330a648] 127.0.0.1 - - [13/Dec/2012 10:58:53] "GET /v1/88fe447d408d418baad31f681330a648/volumes/detail HTTP/1.1" 200 187 0.050853
2012-12-14 10:58:27 INFO nova.api.openstack.wsgi [req-5064df6f-b9ca-4ced-933b-8c6e731f1b47 25182ffd9fdd4a2bbb53053d2bae0190 2ac6ca1f639944a5927f62169f8bb351] PUT http://controller:8774/v2/2ac6ca1f639944a5927f62169f8bb351/os-hosts/blade04
2012-12-14 10:58:27 AUDIT nova.api.openstack.compute.contrib.hosts [req-5064df6f-b9ca-4ced-933b-8c6e731f1b47 25182ffd9fdd4a2bbb53053d2bae0190 2ac6ca1f639944a5927f62169f8bb351] Putting host blade04 in maintenance mode True.
2012-12-14 10:58:27 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on compute.blade04 ... from (pid=13878) multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:351
2012-12-14 10:58:27 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is f80ac1cfbc4d46209771d0448ff0aa2c from (pid=13878) multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:354
2012-12-14 10:58:27 ERROR nova.api.openstack [req-5064df6f-b9ca-4ced-933b-8c6e731f1b47 25182ffd9fdd4a2bbb53053d2bae0190 2ac6ca1f639944a5927f62169f8bb351] Caught error: Remote error: NotImplementedError 
2012-12-14 10:58:27 TRACE nova.api.openstack Traceback (most recent call last):
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/api/openstack/__init__.py", line 78, in __call__
2012-12-14 10:58:27 TRACE nova.api.openstack     return req.get_response(self.application)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/WebOb-1.0.8-py2.6.egg/webob/request.py", line 1053, in get_response
2012-12-14 10:58:27 TRACE nova.api.openstack     application, catch_exc_info=False)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/WebOb-1.0.8-py2.6.egg/webob/request.py", line 1022, in call_application
2012-12-14 10:58:27 TRACE nova.api.openstack     app_iter = application(self.environ, start_response)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/WebOb-1.0.8-py2.6.egg/webob/dec.py", line 159, in __call__
2012-12-14 10:58:27 TRACE nova.api.openstack     return resp(environ, start_response)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/keystone/middleware/auth_token.py", line 278, in __call__
2012-12-14 10:58:27 TRACE nova.api.openstack     return self.app(env, start_response)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/WebOb-1.0.8-py2.6.egg/webob/dec.py", line 159, in __call__
2012-12-14 10:58:27 TRACE nova.api.openstack     return resp(environ, start_response)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/WebOb-1.0.8-py2.6.egg/webob/dec.py", line 159, in __call__
2012-12-14 10:58:27 TRACE nova.api.openstack     return resp(environ, start_response)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/WebOb-1.0.8-py2.6.egg/webob/dec.py", line 159, in __call__
2012-12-14 10:58:27 TRACE nova.api.openstack     return resp(environ, start_response)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py", line 131, in __call__
2012-12-14 10:58:27 TRACE nova.api.openstack     response = self.app(environ, start_response)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/WebOb-1.0.8-py2.6.egg/webob/dec.py", line 159, in __call__
2012-12-14 10:58:27 TRACE nova.api.openstack     return resp(environ, start_response)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/WebOb-1.0.8-py2.6.egg/webob/dec.py", line 147, in __call__
2012-12-14 10:58:27 TRACE nova.api.openstack     resp = self.call_func(req, *args, **self.kwargs)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/WebOb-1.0.8-py2.6.egg/webob/dec.py", line 208, in call_func
2012-12-14 10:58:27 TRACE nova.api.openstack     return self.func(req, *args, **kwargs)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py", line 885, in __call__
2012-12-14 10:58:27 TRACE nova.api.openstack     content_type, body, accept)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py", line 933, in _process_stack
2012-12-14 10:58:27 TRACE nova.api.openstack     action_result = self.dispatch(meth, request, action_args)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/api/openstack/wsgi.py", line 1021, in dispatch
2012-12-14 10:58:27 TRACE nova.api.openstack     return method(req=request, **action_args)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/api/openstack/compute/contrib/hosts.py", line 117, in wrapped
2012-12-14 10:58:27 TRACE nova.api.openstack     return fn(self, req, id, *args, **kwargs)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/api/openstack/compute/contrib/hosts.py", line 167, in update
2012-12-14 10:58:27 TRACE nova.api.openstack     result.update(update_setters[key](req, id, value))
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/api/openstack/compute/contrib/hosts.py", line 176, in _set_host_maintenance
2012-12-14 10:58:27 TRACE nova.api.openstack     result = self.api.set_host_maintenance(context, host, mode)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/compute/api.py", line 1993, in set_host_maintenance
2012-12-14 10:58:27 TRACE nova.api.openstack     host_param=host, mode=mode, host=host)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/compute/rpcapi.py", line 273, in host_maintenance_mode
2012-12-14 10:58:27 TRACE nova.api.openstack     topic=_compute_topic(self.topic, ctxt, host, None))
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/proxy.py", line 80, in call
2012-12-14 10:58:27 TRACE nova.api.openstack     return rpc.call(context, self._get_topic(topic), msg, timeout)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/__init__.py", line 102, in call
2012-12-14 10:58:27 TRACE nova.api.openstack     return _get_impl().call(cfg.CONF, context, topic, msg, timeout)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/impl_qpid.py", line 561, in call
2012-12-14 10:58:27 TRACE nova.api.openstack     rpc_amqp.get_connection_pool(conf, Connection))
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py", line 368, in call
2012-12-14 10:58:27 TRACE nova.api.openstack     rv = list(rv)
2012-12-14 10:58:27 TRACE nova.api.openstack   File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py", line 336, in __iter__
2012-12-14 10:58:27 TRACE nova.api.openstack     raise result
2012-12-14 10:58:27 TRACE nova.api.openstack RemoteError: Remote error: NotImplementedError 
2012-12-14 10:58:27 TRACE nova.api.openstack [u'Traceback (most recent call last):\n', u'  File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py", line 275, in _process_data\n    rval = self.proxy.dispatch(ctxt, version, method, **args)\n', u'  File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/dispatcher.py", line 145, in dispatch\n    return getattr(proxyobj, method)(ctxt, **kwargs)\n', u'  File "/usr/lib/python2.6/site-packages/nova/exception.py", line 117, in wrapped\n    temp_level, payload)\n', u'  File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__\n    self.gen.next()\n', u'  File "/usr/lib/python2.6/site-packages/nova/exception.py", line 92, in wrapped\n    return f(*args, **kw)\n', u'  File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1766, in host_maintenance_mode\n    return self.driver.host_maintenance_mode(host, mode)\n', u'  File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2750, in host_maintenance_mode\n    raise NotImplementedError()\n', u'NotImplementedError\n'].
2012-12-14 10:58:27 TRACE nova.api.openstack 
2012-12-14 10:58:27 INFO nova.api.openstack [req-5064df6f-b9ca-4ced-933b-8c6e731f1b47 25182ffd9fdd4a2bbb53053d2bae0190 2ac6ca1f639944a5927f62169f8bb351] http://controller:8774/v2/2ac6ca1f639944a5927f62169f8bb351/os-hosts/blade04 returned with HTTP 500
2012-12-14 10:58:27 INFO nova.osapi_compute.wsgi.server [req-5064df6f-b9ca-4ced-933b-8c6e731f1b47 25182ffd9fdd4a2bbb53053d2bae0190 2ac6ca1f639944a5927f62169f8bb351] 127.0.0.1 - - [14/Dec/2012 10:58:27] "PUT /v2/2ac6ca1f639944a5927f62169f8bb351/os-hosts/blade04 HTTP/1.1" 500 335 0.114668






[root@blade04 ~]# grep 10:58 /var/log/nova/compute.log 
2012-12-12 13:10:58 ERROR nova.openstack.common.rpc.impl_qpid [-] Unable to connect to AMQP server: None(already open)
2012-12-12 14:10:58 ERROR nova.openstack.common.rpc.impl_qpid [-] Unable to connect to AMQP server: None(already open)
2012-12-12 15:10:58 ERROR nova.openstack.common.rpc.impl_qpid [-] Unable to connect to AMQP server: None(already open)
2012-12-12 16:10:58 ERROR nova.openstack.common.rpc.impl_qpid [-] Unable to connect to AMQP server: None(already open)
2012-12-13 10:58:47 DEBUG nova.manager [-] Running periodic task ComputeManager._publish_service_capabilities from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 10:58:47 DEBUG nova.manager [-] Notifying Schedulers of capabilities ... from (pid=4237) _publish_service_capabilities /usr/lib/python2.6/site-packages/nova/manager.py:231
2012-12-13 10:58:47 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous fanout cast... from (pid=4237) fanout_cast /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:384
2012-12-13 10:58:47 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rescued_instances from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 10:58:47 DEBUG nova.manager [-] Skipping ComputeManager._sync_power_states, 5 ticks left until next run from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2012-12-13 10:58:47 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_bandwidth_usage from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 10:58:47 DEBUG nova.manager [-] Running periodic task ComputeManager._instance_usage_audit from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 10:58:47 DEBUG nova.manager [-] Running periodic task ComputeManager.update_available_resource from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 10:58:47 DEBUG nova.utils [-] Got semaphore "compute_resources" for method "update_available_resource"... from (pid=4237) inner /usr/lib/python2.6/site-packages/nova/utils.py:713
2012-12-13 10:58:50 DEBUG nova.compute.resource_tracker [-] Hypervisor: free ram (MB): 31524 from (pid=4237) _report_hypervisor_resource_view /usr/lib/python2.6/site-packages/nova/compute/resource_tracker.py:447
2012-12-13 10:58:50 DEBUG nova.compute.resource_tracker [-] Hypervisor: free disk (GB): 3850 from (pid=4237) _report_hypervisor_resource_view /usr/lib/python2.6/site-packages/nova/compute/resource_tracker.py:448
2012-12-13 10:58:50 DEBUG nova.compute.resource_tracker [-] Hypervisor: free VCPUs: 4 from (pid=4237) _report_hypervisor_resource_view /usr/lib/python2.6/site-packages/nova/compute/resource_tracker.py:453
2012-12-13 10:58:50 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31596
2012-12-13 10:58:50 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 3850
2012-12-13 10:58:50 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
2012-12-13 10:58:50 INFO nova.compute.resource_tracker [-] Compute_service record updated for blade04 
2012-12-13 10:58:50 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rebooting_instances from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 10:58:50 DEBUG nova.manager [-] Skipping ComputeManager._cleanup_running_deleted_instances, 8 ticks left until next run from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2012-12-13 10:58:50 DEBUG nova.manager [-] Running periodic task ComputeManager._check_instance_build_time from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 10:58:50 DEBUG nova.manager [-] Running periodic task ComputeManager._heal_instance_info_cache from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 10:58:50 DEBUG nova.manager [-] Skipping ComputeManager._run_image_cache_manager_pass, 22 ticks left until next run from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2012-12-13 10:58:50 DEBUG nova.manager [-] Running periodic task ComputeManager._reclaim_queued_deletes from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 10:58:50 DEBUG nova.compute.manager [-] FLAGS.reclaim_instance_interval <= 0, skipping... from (pid=4237) _reclaim_queued_deletes /usr/lib/python2.6/site-packages/nova/compute/manager.py:2711
2012-12-13 10:58:50 DEBUG nova.manager [-] Running periodic task ComputeManager._report_driver_status from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 10:58:50 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_unconfirmed_resizes from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 13:10:58 DEBUG nova.utils [-] Running cmd (subprocess): env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/instance-0000013f/disk from (pid=4237) execute /usr/lib/python2.6/site-packages/nova/utils.py:176
2012-12-13 13:10:58 DEBUG nova.utils [-] Result was 0 from (pid=4237) execute /usr/lib/python2.6/site-packages/nova/utils.py:191
2012-12-13 13:10:58 DEBUG nova.utils [-] Running cmd (subprocess): env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/instance-0000013f/disk from (pid=4237) execute /usr/lib/python2.6/site-packages/nova/utils.py:176
2012-12-13 13:10:58 DEBUG nova.utils [-] Result was 0 from (pid=4237) execute /usr/lib/python2.6/site-packages/nova/utils.py:191
2012-12-13 13:10:58 DEBUG nova.compute.resource_tracker [-] Hypervisor: free ram (MB): 30636 from (pid=4237) _report_hypervisor_resource_view /usr/lib/python2.6/site-packages/nova/compute/resource_tracker.py:447
2012-12-13 13:10:58 DEBUG nova.compute.resource_tracker [-] Hypervisor: free disk (GB): 3850 from (pid=4237) _report_hypervisor_resource_view /usr/lib/python2.6/site-packages/nova/compute/resource_tracker.py:448
2012-12-13 13:10:58 DEBUG nova.compute.resource_tracker [-] Hypervisor: free VCPUs: -6 from (pid=4237) _report_hypervisor_resource_view /usr/lib/python2.6/site-packages/nova/compute/resource_tracker.py:453
2012-12-13 13:10:58 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 24428
2012-12-13 13:10:58 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 3710
2012-12-13 13:10:58 AUDIT nova.compute.resource_tracker [-] Free VCPUS: -10
2012-12-13 13:10:58 INFO nova.compute.resource_tracker [-] Compute_service record updated for blade04 
2012-12-13 13:10:58 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rebooting_instances from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 13:10:58 DEBUG nova.manager [-] Skipping ComputeManager._cleanup_running_deleted_instances, 17 ticks left until next run from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2012-12-13 13:10:58 DEBUG nova.manager [-] Running periodic task ComputeManager._check_instance_build_time from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 13:10:58 DEBUG nova.manager [-] Running periodic task ComputeManager._heal_instance_info_cache from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 13:10:58 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... from (pid=4237) multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:351
2012-12-13 13:10:58 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is f76f14a7f3914b7bbc83195fed974475 from (pid=4237) multicall /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:354
2012-12-13 13:10:58 DEBUG nova.compute.manager [-] [instance: 52b3ac20-be6b-4aaa-93db-e4ec401c6e5f] Updated the info_cache for instance from (pid=4237) _heal_instance_info_cache /usr/lib/python2.6/site-packages/nova/compute/manager.py:2405
2012-12-13 13:10:58 DEBUG nova.manager [-] Skipping ComputeManager._run_image_cache_manager_pass, 30 ticks left until next run from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2012-12-13 13:10:58 DEBUG nova.manager [-] Running periodic task ComputeManager._reclaim_queued_deletes from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 13:10:58 DEBUG nova.compute.manager [-] FLAGS.reclaim_instance_interval <= 0, skipping... from (pid=4237) _reclaim_queued_deletes /usr/lib/python2.6/site-packages/nova/compute/manager.py:2711
2012-12-13 13:10:58 DEBUG nova.manager [-] Running periodic task ComputeManager._report_driver_status from (pid=4237) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-13 13:10:58 INFO nova.compute.manager [-] Updating host status
2012-12-13 13:10:58 DEBUG nova.virt.libvirt.driver [-] Updating host stats from (pid=4237) update_status /usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py:3020
2012-12-13 14:07:49 DEBUG nova.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_context_request_id': u'req-d833729a-6054-4c99-8452-98d26ceb1278', u'_context_quota_class': None, u'_context_project_name': u'the rubber obscenity company', u'_context_service_catalog': [{u'endpoints': [{u'adminURL': u'http://controller:8774/v2/88fe447d408d418baad31f681330a648', u'region': u'RegionOne', u'internalURL': u'http://controller:8774/v2/88fe447d408d418baad31f681330a648', u'id': u'f92f6fba66fc4a8d985691a9e68884dc', u'publicURL': u'http://controller:8774/v2/88fe447d408d418baad31f681330a648'}], u'endpoints_links': [], u'type': u'compute', u'name': u'nova'}, {u'endpoints': [{u'adminURL': u'http://controller:9292/v1', u'region': u'RegionOne', u'internalURL': u'http://controller:9292/v1', u'id': u'd08144ec7fb948009e563e5acabf2241', u'publicURL': u'http://controller:9292/v1'}], u'endpoints_links': [], u'type': u'image', u'name': u'glance'}, {u'endpoints': [{u'adminURL': u'http://controller:8776/v1/88fe447d408d418baad31f681330a648', u'region': u'RegionOne', u'internalURL': u'http://controller:8776/v1/88fe447d408d418baad31f681330a648', u'id': u'4931339808cb4b7389eacb6cf2285770', u'publicURL': u'http://controller:8776/v1/88fe447d408d418baad31f681330a648'}], u'endpoints_links': [], u'type': u'volume', u'name': u'volume'}, {u'endpoints': [{u'adminURL': u'http://controller:8773/services/Admin', u'region': u'RegionOne', u'internalURL': u'http://controller:8773/services/Cloud', u'id': u'12f2c19a02fb4d45963bce183ab856f4', u'publicURL': u'http://controller:8773/services/Cloud'}], u'endpoints_links': [], u'type': u'ec2', u'name': u'ec2'}, {u'endpoints': [{u'adminURL': u'http://controller:8888/v1', u'region': u'RegionOne', u'internalURL': u'http://controller:8888/v1/AUTH_88fe447d408d418baad31f681330a648', u'id': u'eccda165d0524222a5679acf2209528a', u'publicURL': u'http://controller:8888/v1/AUTH_88fe447d408d418baad31f681330a648'}], u'endpoints_links': [], u'type': u'object-store', u'name': u'swift'}, {u'endpoints': [{u'adminURL': u'http://controller:35357/v2.0', u'region': u'RegionOne', u'internalURL': u'http://controller:5000/v2.0', u'id': u'6ea5d054821341128713ca1d85a34103', u'publicURL': u'http://controller:5000/v2.0'}], u'endpoints_links': [], u'type': u'identity', u'name': u'keystone'}], u'_context_user_name': u'andrew', u'_context_auth_token': '<SANITIZED>', u'args': {u'instance': {u'vm_state': u'error', u'availability_zone': None, u'terminated_at': None, u'ephemeral_gb': 0, u'instance_type_id': 6, u'user_data': None, u'vm_mode': None, u'deleted_at': None, u'reservation_id': u'r-wisvx4zo', u'id': 331, u'security_groups': [{u'deleted_at': None, u'user_id': u'58c4fd56b6924264b914659e7c0ef2f2', u'name': u'default', u'deleted': False, u'created_at': u'2012-12-12T17:24:09.000000', u'updated_at': None, u'rules': [], u'project_id': u'88fe447d408d418baad31f681330a648', u'id': 2, u'description': u'default'}], u'disable_terminate': False, u'user_id': u'58c4fd56b6924264b914659e7c0ef2f2', u'uuid': u'52b3ac20-be6b-4aaa-93db-e4ec401c6e5f', u'server_name': None, u'default_swap_device': None, u'info_cache': {u'instance_uuid': u'52b3ac20-be6b-4aaa-93db-e4ec401c6e5f', u'deleted': False, u'created_at': u'2012-12-13T12:02:40.000000', u'updated_at': u'2012-12-13T12:10:58.000000', u'network_info': u'[{"network": {"bridge": "br146", "subnets": [{"ips": [{"meta": {}, "version": 4, "type": "fixed", "floating_ips": [], "address": "10.146.10.36"}], "version": 4, "meta": {"dhcp_server": "10.146.10.1"}, "dns": [], "routes": [], "cidr": "10.146.10.0/24", "gateway": {"meta": {}, "version": 4, "type": "gateway", "address": "10.146.10.1"}}, {"ips": [], "version": null, "meta": {"dhcp_server": null}, "dns": [], "routes": [], "cidr": null, "gateway": {"meta": {}, "version": null, "type": "gateway", "address": null}}], "meta": {"tenant_id": "88fe447d408d418baad31f681330a648", "vlan": 146, "should_create_bridge": true, "should_create_vlan": true, "bridge_interface": "eth0"}, "id": "209b53e0-ac7b-4528-bf8c-a01d8ee6a41b", "label": "example-net"}, "meta": {}, "id": "6da6922a-6f1d-4ba1-a6ef-281c6968b4e1", "address": "fa:16:3e:14:fd:e8"}]', u'deleted_at': None, u'id': 331}, u'hostname': u'abcd', u'launched_on': None, u'display_description': u'abcd', u'key_data': None, u'kernel_id': u'', u'power_state': 0, u'default_ephemeral_device': None, u'progress': 0, u'project_id': u'88fe447d408d418baad31f681330a648', u'launched_at': None, u'scheduled_at': u'2012-12-13T12:02:41.000000', u'ramdisk_id': u'', u'access_ip_v6': None, u'access_ip_v4': None, u'deleted': False, u'key_name': None, u'updated_at': u'2012-12-13T12:03:44.000000', u'host': u'blade04', u'display_name': u'abcd', u'task_state': None, u'shutdown_terminate': False, u'architecture': None, u'root_gb': 10, u'locked': False, u'name': u'instance-0000014b', u'created_at': u'2012-12-13T12:02:40.000000', u'launch_index': 0, u'metadata': [], u'memory_mb': 512, u'instance_type': {u'disabled': False, u'root_gb': 10, u'deleted_at': None, u'name': u'm1.tiny', u'deleted': False, u'created_at': u'2012-12-13T10:12:21.000000', u'ephemeral_gb': 0, u'updated_at': None, u'memory_mb': 512, u'vcpus': 1, u'swap': 0, u'rxtx_factor': 1.0, u'is_public': True, u'flavorid': u'6', u'vcpu_weight': None, u'id': 6}, u'vcpus': 1, u'image_ref': u'b60f8aa2-ed2d-4a3d-916b-be4aa73fa68b', u'root_device_name': None, u'auto_disk_config': None, u'os_type': None, u'config_drive': u''}}, u'_context_instance_lock_checked': False, u'_context_is_admin': True, u'version': u'2.0', u'_context_project_id': u'88fe447d408d418baad31f681330a648', u'_context_timestamp': u'2012-12-13T13:07:48.858035', u'_context_read_deleted': u'no', u'_context_user_id': u'58c4fd56b6924264b914659e7c0ef2f2', u'method': u'terminate_instance', u'_context_remote_address': u'127.0.0.1'} from (pid=4237) _safe_log /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/common.py:195
2012-12-14 10:10:58 DEBUG nova.utils [-] Running cmd (subprocess): env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/instance-000001e5/disk from (pid=3317) execute /usr/lib/python2.6/site-packages/nova/utils.py:176
2012-12-14 10:10:58 DEBUG nova.utils [-] Result was 0 from (pid=3317) execute /usr/lib/python2.6/site-packages/nova/utils.py:191
2012-12-14 10:10:58 DEBUG nova.utils [-] Running cmd (subprocess): env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/instance-000001e5/disk from (pid=3317) execute /usr/lib/python2.6/site-packages/nova/utils.py:176
2012-12-14 10:10:58 DEBUG nova.utils [-] Result was 0 from (pid=3317) execute /usr/lib/python2.6/site-packages/nova/utils.py:191
2012-12-14 10:10:58 DEBUG nova.utils [-] Running cmd (subprocess): env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/instance-000001e3/disk from (pid=3317) execute /usr/lib/python2.6/site-packages/nova/utils.py:176
2012-12-14 10:10:58 DEBUG nova.utils [-] Result was 0 from (pid=3317) execute /usr/lib/python2.6/site-packages/nova/utils.py:191
2012-12-14 10:10:58 DEBUG nova.utils [-] Running cmd (subprocess): env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/instance-000001e3/disk from (pid=3317) execute /usr/lib/python2.6/site-packages/nova/utils.py:176
2012-12-14 10:58:00 DEBUG nova.manager [-] Running periodic task ComputeManager._publish_service_capabilities from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-14 10:58:00 DEBUG nova.manager [-] Notifying Schedulers of capabilities ... from (pid=3317) _publish_service_capabilities /usr/lib/python2.6/site-packages/nova/manager.py:231
2012-12-14 10:58:00 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous fanout cast... from (pid=3317) fanout_cast /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py:384
2012-12-14 10:58:00 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rescued_instances from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-14 10:58:00 DEBUG nova.manager [-] Skipping ComputeManager._sync_power_states, 8 ticks left until next run from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2012-12-14 10:58:00 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_bandwidth_usage from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-14 10:58:00 INFO nova.compute.manager [-] Updating bandwidth usage cache
2012-12-14 10:58:00 DEBUG nova.manager [-] Running periodic task ComputeManager._instance_usage_audit from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-14 10:58:00 DEBUG nova.manager [-] Running periodic task ComputeManager.update_available_resource from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-14 10:58:00 DEBUG nova.utils [-] Got semaphore "compute_resources" for method "update_available_resource"... from (pid=3317) inner /usr/lib/python2.6/site-packages/nova/utils.py:713
2012-12-14 10:58:03 DEBUG nova.compute.resource_tracker [-] Hypervisor: free ram (MB): 31498 from (pid=3317) _report_hypervisor_resource_view /usr/lib/python2.6/site-packages/nova/compute/resource_tracker.py:447
2012-12-14 10:58:03 DEBUG nova.compute.resource_tracker [-] Hypervisor: free disk (GB): 3850 from (pid=3317) _report_hypervisor_resource_view /usr/lib/python2.6/site-packages/nova/compute/resource_tracker.py:448
2012-12-14 10:58:03 DEBUG nova.compute.resource_tracker [-] Hypervisor: free VCPUs: 4 from (pid=3317) _report_hypervisor_resource_view /usr/lib/python2.6/site-packages/nova/compute/resource_tracker.py:453
2012-12-14 10:58:03 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31596
2012-12-14 10:58:03 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 3850
2012-12-14 10:58:03 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
2012-12-14 10:58:03 INFO nova.compute.resource_tracker [-] Compute_service record updated for blade04 
2012-12-14 10:58:03 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rebooting_instances from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-14 10:58:03 DEBUG nova.manager [-] Skipping ComputeManager._cleanup_running_deleted_instances, 22 ticks left until next run from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2012-12-14 10:58:03 DEBUG nova.manager [-] Running periodic task ComputeManager._check_instance_build_time from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-14 10:58:03 DEBUG nova.manager [-] Running periodic task ComputeManager._heal_instance_info_cache from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-14 10:58:03 DEBUG nova.manager [-] Skipping ComputeManager._run_image_cache_manager_pass, 23 ticks left until next run from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:167
2012-12-14 10:58:03 DEBUG nova.manager [-] Running periodic task ComputeManager._reclaim_queued_deletes from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-14 10:58:03 DEBUG nova.compute.manager [-] FLAGS.reclaim_instance_interval <= 0, skipping... from (pid=3317) _reclaim_queued_deletes /usr/lib/python2.6/site-packages/nova/compute/manager.py:2711
2012-12-14 10:58:03 DEBUG nova.manager [-] Running periodic task ComputeManager._report_driver_status from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-14 10:58:03 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_unconfirmed_resizes from (pid=3317) periodic_tasks /usr/lib/python2.6/site-packages/nova/manager.py:172
2012-12-14 10:58:27 DEBUG nova.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_msg_id': u'f80ac1cfbc4d46209771d0448ff0aa2c', u'_context_quota_class': None, u'_context_request_id': u'req-5064df6f-b9ca-4ced-933b-8c6e731f1b47', u'_context_service_catalog': [{u'endpoints': [{u'adminURL': u'http://controller:8774/v2/2ac6ca1f639944a5927f62169f8bb351', u'region': u'RegionOne', u'internalURL': u'http://controller:8774/v2/2ac6ca1f639944a5927f62169f8bb351', u'id': u'f92f6fba66fc4a8d985691a9e68884dc', u'publicURL': u'http://controller:8774/v2/2ac6ca1f639944a5927f62169f8bb351'}], u'endpoints_links': [], u'type': u'compute', u'name': u'nova'}, {u'endpoints': [{u'adminURL': u'http://controller:9292/v1', u'region': u'RegionOne', u'internalURL': u'http://controller:9292/v1', u'id': u'd08144ec7fb948009e563e5acabf2241', u'publicURL': u'http://controller:9292/v1'}], u'endpoints_links': [], u'type': u'image', u'name': u'glance'}, {u'endpoints': [{u'adminURL': u'http://controller:8776/v1/2ac6ca1f639944a5927f62169f8bb351', u'region': u'RegionOne', u'internalURL': u'http://controller:8776/v1/2ac6ca1f639944a5927f62169f8bb351', u'id': u'4931339808cb4b7389eacb6cf2285770', u'publicURL': u'http://controller:8776/v1/2ac6ca1f639944a5927f62169f8bb351'}], u'endpoints_links': [], u'type': u'volume', u'name': u'volume'}, {u'endpoints': [{u'adminURL': u'http://controller:8773/services/Admin', u'region': u'RegionOne', u'internalURL': u'http://controller:8773/services/Cloud', u'id': u'12f2c19a02fb4d45963bce183ab856f4', u'publicURL': u'http://controller:8773/services/Cloud'}], u'endpoints_links': [], u'type': u'ec2', u'name': u'ec2'}, {u'endpoints': [{u'adminURL': u'http://controller:8888/v1', u'region': u'RegionOne', u'internalURL': u'http://controller:8888/v1/AUTH_2ac6ca1f639944a5927f62169f8bb351', u'id': u'eccda165d0524222a5679acf2209528a', u'publicURL': u'http://controller:8888/v1/AUTH_2ac6ca1f639944a5927f62169f8bb351'}], u'endpoints_links': [], u'type': u'object-store', u'name': u'swift'}, {u'endpoints': [{u'adminURL': u'http://controller:35357/v2.0', u'region': u'RegionOne', u'internalURL': u'http://controller:5000/v2.0', u'id': u'6ea5d054821341128713ca1d85a34103', u'publicURL': u'http://controller:5000/v2.0'}], u'endpoints_links': [], u'type': u'identity', u'name': u'keystone'}], u'_context_user_name': u'admin', u'_context_auth_token': '<SANITIZED>', u'args': {u'host': u'blade04', u'mode': True}, u'_context_instance_lock_checked': False, u'_context_project_name': u'admin', u'_context_is_admin': True, u'version': u'2.0', u'_context_project_id': u'2ac6ca1f639944a5927f62169f8bb351', u'_context_timestamp': u'2012-12-14T09:58:27.548853', u'_context_read_deleted': u'no', u'_context_user_id': u'25182ffd9fdd4a2bbb53053d2bae0190', u'method': u'host_maintenance_mode', u'_context_remote_address': u'127.0.0.1'} from (pid=3317) _safe_log /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/common.py:195
2012-12-14 10:58:27 DEBUG nova.openstack.common.rpc.amqp [-] unpacked context: {'project_name': u'admin', 'user_id': u'25182ffd9fdd4a2bbb53053d2bae0190', 'roles': [u'admin'], 'timestamp': u'2012-12-14T09:58:27.548853', 'auth_token': '<SANITIZED>', 'remote_address': u'127.0.0.1', 'quota_class': None, 'is_admin': True, 'service_catalog': [{u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://controller:8774/v2/2ac6ca1f639944a5927f62169f8bb351', u'region': u'RegionOne', u'publicURL': u'http://controller:8774/v2/2ac6ca1f639944a5927f62169f8bb351', u'internalURL': u'http://controller:8774/v2/2ac6ca1f639944a5927f62169f8bb351', u'id': u'f92f6fba66fc4a8d985691a9e68884dc'}], u'type': u'compute', u'name': u'nova'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://controller:9292/v1', u'region': u'RegionOne', u'publicURL': u'http://controller:9292/v1', u'internalURL': u'http://controller:9292/v1', u'id': u'd08144ec7fb948009e563e5acabf2241'}], u'type': u'image', u'name': u'glance'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://controller:8776/v1/2ac6ca1f639944a5927f62169f8bb351', u'region': u'RegionOne', u'publicURL': u'http://controller:8776/v1/2ac6ca1f639944a5927f62169f8bb351', u'internalURL': u'http://controller:8776/v1/2ac6ca1f639944a5927f62169f8bb351', u'id': u'4931339808cb4b7389eacb6cf2285770'}], u'type': u'volume', u'name': u'volume'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://controller:8773/services/Admin', u'region': u'RegionOne', u'publicURL': u'http://controller:8773/services/Cloud', u'internalURL': u'http://controller:8773/services/Cloud', u'id': u'12f2c19a02fb4d45963bce183ab856f4'}], u'type': u'ec2', u'name': u'ec2'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://controller:8888/v1', u'region': u'RegionOne', u'publicURL': u'http://controller:8888/v1/AUTH_2ac6ca1f639944a5927f62169f8bb351', u'internalURL': u'http://controller:8888/v1/AUTH_2ac6ca1f639944a5927f62169f8bb351', u'id': u'eccda165d0524222a5679acf2209528a'}], u'type': u'object-store', u'name': u'swift'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://controller:35357/v2.0', u'region': u'RegionOne', u'publicURL': u'http://controller:5000/v2.0', u'internalURL': u'http://controller:5000/v2.0', u'id': u'6ea5d054821341128713ca1d85a34103'}], u'type': u'identity', u'name': u'keystone'}], 'request_id': u'req-5064df6f-b9ca-4ced-933b-8c6e731f1b47', 'instance_lock_checked': False, 'project_id': u'2ac6ca1f639944a5927f62169f8bb351', 'user_name': u'admin', 'read_deleted': u'no'} from (pid=3317) _safe_log /usr/lib/python2.6/site-packages/nova/openstack/common/rpc/common.py:195
2012-12-14 10:58:27 ERROR nova.openstack.common.rpc.amqp [-] Exception during message handling
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp Traceback (most recent call last):
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp   File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py", line 275, in _process_data
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp     rval = self.proxy.dispatch(ctxt, version, method, **args)
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp   File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/dispatcher.py", line 145, in dispatch
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp     return getattr(proxyobj, method)(ctxt, **kwargs)
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp   File "/usr/lib/python2.6/site-packages/nova/exception.py", line 117, in wrapped
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp     temp_level, payload)
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp   File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp     self.gen.next()
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp   File "/usr/lib/python2.6/site-packages/nova/exception.py", line 92, in wrapped
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp     return f(*args, **kw)
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1766, in host_maintenance_mode
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp     return self.driver.host_maintenance_mode(host, mode)
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2750, in host_maintenance_mode
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp     raise NotImplementedError()
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp NotImplementedError
2012-12-14 10:58:27 TRACE nova.openstack.common.rpc.amqp 
2012-12-14 10:58:27 ERROR nova.openstack.common.rpc.common [-] Returning exception  to caller
2012-12-14 10:58:27 ERROR nova.openstack.common.rpc.common [-] ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/amqp.py", line 275, in _process_data\n    rval = self.proxy.dispatch(ctxt, version, method, **args)\n', '  File "/usr/lib/python2.6/site-packages/nova/openstack/common/rpc/dispatcher.py", line 145, in dispatch\n    return getattr(proxyobj, method)(ctxt, **kwargs)\n', '  File "/usr/lib/python2.6/site-packages/nova/exception.py", line 117, in wrapped\n    temp_level, payload)\n', '  File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__\n    self.gen.next()\n', '  File "/usr/lib/python2.6/site-packages/nova/exception.py", line 92, in wrapped\n    return f(*args, **kw)\n', '  File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1766, in host_maintenance_mode\n    return self.driver.host_maintenance_mode(host, mode)\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2750, in host_maintenance_mode\n    raise NotImplementedError()\n', 'NotImplementedError\n']
Follow ups

Re: Maintenance mode not working - NotImplementedError
From: Sébastien Han, 2012-12-14