But still compute agent times out (after 5min set in conf):
[yfried-mobl-rh ~/workspace/Hosts/scale_debug] # grep -rn f41ec90c-6d10-4ee5-be7f-96590ec7c8c1 | grep WARN
var/log/nova/nova-compute.log:14360:2014-09-21 11:36:23.690 57535 WARNING nova.virt.libvirt.driver [req-10ffb1e2-6592-492f-b465-00a0658a6251 16480061596d47ea819c7def6d7eef1f 3957117d021244b5badcacb08cdc83e2] Timeout waiting for vif plugging callback for instance f41ec90c-6d10-4ee5-be7f-96590ec7c8c1
Well, all I've been able to find, so far, is that it seems like nova-compute is ignoring neutron port-notifications:
taking 2 instances 6d10-4ee5- be7f-96590ec7c8 c1 - ERROR - VirtualInterfac eCreateExceptio n: Virtual Interface creation failed 07d3-4ec1- a6e7-79b459d631 c5 - ACTIVE
f41ec90c-
be60b489-
server be60b489- 07d3-4ec1- a6e7-79b459d631 c5 Neutron sends port notifications: Hosts/scale_ debug] # grep -rn be60b489- 07d3-4ec1- a6e7-79b459d631 c5 | grep notifiers neutron/ server. log:9482: 2014-09- 21 11:32:02.358 57216 DEBUG neutron. notifiers. nova [-] Sending events: [{'status': 'completed', 'tag': u'7c3b3872- f711-43a4- a566-669c640c99 f4', 'name': 'network- vif-plugged' , 'server_uuid': u'0d3be93b- 73d3-4995- 913c-03a4b80ad3 7e'}, {'status': 'completed', 'tag': u'a09f7486- aae6-4b0b- b298-9e0f80b462 78', 'name': 'network- vif-plugged' , 'server_uuid': u'be60b489- 07d3-4ec1- a6e7-79b459d631 c5'}, {'status': 'completed', 'tag': u'ff2bb3bb- bcbd-4bc8- 80b4-5c91841048 c0', 'name': 'network- vif-plugged' , 'server_uuid': u'547d6f97- 31db-4718- afb3-4b6123d710 76'}, {'status': 'completed', 'tag': u'549de3d5- 2b8f-4822- 9912-15730b0323 4f', 'name': 'network- vif-plugged' , 'server_uuid': u'a431c16f- bf1e-424c- 88f9-b0ba2a683f 99'}, {'status': 'completed', 'tag': u'820d8afc- 4d74-4f19- a7a5-8c58cd41e8 98', 'name': 'network- vif-plugged' , 'server_uuid': u'2b7256a8- c04a-42cf- 9c19-5836b585c0 f5'}] send_events /usr/lib/ python2. 7/site- packages/ neutron/ notifiers/ nova.py: 218 neutron/ server. log:9507: 2014-09- 21 11:32:03.618 57216 INFO neutron. notifiers. nova [-] Nova event response: {u'status': u'completed', u'tag': u'a09f7486- aae6-4b0b- b298-9e0f80b462 78', u'name': u'network- vif-plugged' , u'server_uuid': u'be60b489- 07d3-4ec1- a6e7-79b459d631 c5', u'code': 200}
[yfried-mobl-rh ~/workspace/
var/log/
var/log/
Nova receives notifications: Hosts/scale_ debug] # grep -rn be60b489- 07d3-4ec1- a6e7-79b459d631 c5 var/log/nova | grep "event network- vif-plugged" nova/nova- api.log: 3059:2014- 09-21 11:32:03.486 57411 AUDIT nova.api. openstack. compute. contrib. server_ external_ events [req-50beaf5f- 5db9-4fd3- b1fd-26b5a7d39b 11 940afd62ccde437 6915abee8356a28 d9 9426944e683b41d 5aa4d7f8e298fd4 d8] Creating event network- vif-plugged: a09f7486- aae6-4b0b- b298-9e0f80b462 78 for instance be60b489- 07d3-4ec1- a6e7-79b459d631 c5 nova/nova- compute. log:8370: 2014-09- 21 11:31:46.012 57535 DEBUG nova.compute. manager [req-10ffb1e2- 6592-492f- b465-00a0658a62 51 16480061596d47e a819c7def6d7eef 1f 3957117d021244b 5badcacb08cdc83 e2] [instance: be60b489- 07d3-4ec1- a6e7-79b459d631 c5] Preparing to wait for external event network- vif-plugged- a09f7486- aae6-4b0b- b298-9e0f80b462 78 prepare_ for_instance_ event /usr/lib/ python2. 7/site- packages/ nova/compute/ manager. py:447 nova/nova- compute. log:14539: 2014-09- 21 11:36:24.837 57535 DEBUG nova.compute. manager [req-50beaf5f- 5db9-4fd3- b1fd-26b5a7d39b 11 940afd62ccde437 6915abee8356a28 d9 9426944e683b41d 5aa4d7f8e298fd4 d8] [instance: be60b489- 07d3-4ec1- a6e7-79b459d631 c5] Processing event network- vif-plugged- a09f7486- aae6-4b0b- b298-9e0f80b462 78 _process_ instance_ event /usr/lib/ python2. 7/site- packages/ nova/compute/ manager. py:5665
[yfried-mobl-rh ~/workspace/
var/log/
var/log/
var/log/
But on server f41ec90c- 6d10-4ee5- be7f-96590ec7c8 c1: Hosts/scale_ debug] # grep -rn f41ec90c- 6d10-4ee5- be7f-96590ec7c8 c1 | grep notifiers neutron/ server. log:3785: 2014-09- 21 11:31:27.143 57216 DEBUG neutron. notifiers. nova [req-059e9a9d- b943-4497- a9e6-6fbeb4d164 72 None] Sending events: [{'status': 'completed', 'tag': u'ca09537e- 3cb8-4c81- a2ca-db8ac1f9e9 5b', 'name': 'network- vif-plugged' , 'server_uuid': u'f41ec90c- 6d10-4ee5- be7f-96590ec7c8 c1'}] send_events /usr/lib/ python2. 7/site- packages/ neutron/ notifiers/ nova.py: 218 neutron/ server. log:4873: 2014-09- 21 11:31:29.468 57216 INFO neutron. notifiers. nova [req-17ea7119- 3a5f-4d38- a878-ec0b6b6985 a5 None] Nova event response: {u'status': u'completed', u'tag': u'ca09537e- 3cb8-4c81- a2ca-db8ac1f9e9 5b', u'name': u'network- vif-plugged' , u'server_uuid': u'f41ec90c- 6d10-4ee5- be7f-96590ec7c8 c1', u'code': 200}
Neutron sends port notification:
[yfried-mobl-rh ~/workspace/
var/log/
var/log/
And nova responds: Hosts/scale_ debug] # grep -rn f41ec90c- 6d10-4ee5- be7f-96590ec7c8 c1 var/log/nova | grep "event network- vif-plugged" nova/nova- api.log: 2924:2014- 09-21 11:31:29.435 57407 AUDIT nova.api. openstack. compute. contrib. server_ external_ events [req-bed8b911- 3b41-4788- beb7-93738dd156 97 940afd62ccde437 6915abee8356a28 d9 9426944e683b41d 5aa4d7f8e298fd4 d8] Creating event network- vif-plugged: ca09537e- 3cb8-4c81- a2ca-db8ac1f9e9 5b for instance f41ec90c- 6d10-4ee5- be7f-96590ec7c8 c1 nova/nova- compute. log:1715: 2014-09- 21 11:31:22.024 57535 DEBUG nova.compute. manager [req-10ffb1e2- 6592-492f- b465-00a0658a62 51 16480061596d47e a819c7def6d7eef 1f 3957117d021244b 5badcacb08cdc83 e2] [instance: f41ec90c- 6d10-4ee5- be7f-96590ec7c8 c1] Preparing to wait for external event network- vif-plugged- ca09537e- 3cb8-4c81- a2ca-db8ac1f9e9 5b prepare_ for_instance_ event /usr/lib/ python2. 7/site- packages/ nova/compute/ manager. py:447 nova/nova- compute. log:14428: 2014-09- 21 11:36:24.692 57535 DEBUG nova.compute. manager [req-bed8b911- 3b41-4788- beb7-93738dd156 97 940afd62ccde437 6915abee8356a28 d9 9426944e683b41d 5aa4d7f8e298fd4 d8] [instance: f41ec90c- 6d10-4ee5- be7f-96590ec7c8 c1] Processing event network- vif-plugged- ca09537e- 3cb8-4c81- a2ca-db8ac1f9e9 5b _process_ instance_ event /usr/lib/ python2. 7/site- packages/ nova/compute/ manager. py:5665
[yfried-mobl-rh ~/workspace/
var/log/
var/log/
var/log/
But still compute agent times out (after 5min set in conf): Hosts/scale_ debug] # grep -rn f41ec90c- 6d10-4ee5- be7f-96590ec7c8 c1 | grep WARN nova/nova- compute. log:14360: 2014-09- 21 11:36:23.690 57535 WARNING nova.virt. libvirt. driver [req-10ffb1e2- 6592-492f- b465-00a0658a62 51 16480061596d47e a819c7def6d7eef 1f 3957117d021244b 5badcacb08cdc83 e2] Timeout waiting for vif plugging callback for instance f41ec90c- 6d10-4ee5- be7f-96590ec7c8 c1
[yfried-mobl-rh ~/workspace/
var/log/