
<html><head><meta http-equiv="Content-Type" content="text/html charset=windows-1252"></head><body style="word-wrap: break-word; -webkit-nbsp-mode: space; -webkit-line-break: after-white-space;">Hi Kevin,<div><br></div><div>No exceptions/tracebacks/errors in Neutron at all. In the Nova logs, it seems to create the instance, pause, and then resume, but it looks like maybe it is not resuming?</div><div><br></div><div><div>2015-01-01 14:44:30.716 3516 DEBUG nova.openstack.common.processutils [-] Running cmd (subprocess): sudo nova-rootwrap /etc/nova/rootwrap.conf ovs-vsctl --timeout=120 -- --if-exists del-port qvoded0d35f-20 -- add-port br-int qvoded0d35\</div><div>f-20 -- set Interface qvoded0d35f-20 external-ids:iface-id=ded0d35f-204f-4ca8-a85b-85decb53d9fe external-ids:iface-status=active external-ids:attached-mac=fa:16:3e:81:ab:12 external-ids:vm-uuid=c32ac737-1788-4420-b200-2a107d5ad335 exec\</div><div>ute /opt/stack/nova/nova/openstack/common/processutils.py:161</div><div>2015-01-01 14:44:30.786 3516 DEBUG nova.openstack.common.processutils [-] Result was 0 execute /opt/stack/nova/nova/openstack/common/processutils.py:195</div><div>2015-01-01 14:44:31.542 3516 DEBUG nova.virt.driver [-] Emitting event <LifecycleEvent: 1420123471.54, c32ac737-1788-4420-b200-2a107d5ad335 => <b>Started</b>> emit_event /opt/stack/nova/nova/virt/driver.py:1298</div><div>2015-01-01 14:44:31.543 3516 INFO nova.compute.manager [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] VM Started (Lifecycle Event)</div><div>2015-01-01 14:44:31.584 DEBUG nova.compute.manager [req-77c13ae6-ccf9-48ee-881a-8bb7f04ee4bc None None] [instance: c32ac737-1788-4420-b200-2a107d5ad335] Synchronizing instance power state after lifecycle event "Started"; current vm_sta\</div><div>te: building, current task_state: spawning, current DB power_state: 0, VM power_state: 1 handle_lifecycle_event /opt/stack/nova/nova/compute/manager.py:1105</div><div>2015-01-01 14:44:31.629 INFO nova.compute.manager [req-77c13ae6-ccf9-48ee-881a-8bb7f04ee4bc None None] [instance: c32ac737-1788-4420-b200-2a107d5ad335] During sync_power_state the instance has a pending task (spawning). Skip.</div><div>2015-01-01 14:44:31.630 3516 DEBUG nova.virt.driver [-] Emitting event <LifecycleEvent: 1420123471.54, c32ac737-1788-4420-b200-2a107d5ad335 => <b>Paused</b>> emit_event /opt/stack/nova/nova/virt/driver.py:1298</div><div>2015-01-01 14:44:31.630 3516 INFO nova.compute.manager [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] VM Paused (Lifecycle Event)</div><div>2015-01-01 14:44:31.670 3516 DEBUG nova.compute.manager [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] Synchronizing instance power state after lifecycle event "Paused"; current vm_state: building, current task_state: spawning, c\</div><div>urrent DB power_state: 0, <b>VM power_state: 3</b> handle_lifecycle_event /opt/stack/nova/nova/compute/manager.py:1105</div><div>2015-01-01 14:44:31.714 3516 INFO nova.compute.manager [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] During sync_power_state the instance has a pending task (spawning). Skip.</div><div>2015-01-01 14:44:38.293 DEBUG nova.compute.manager [req-0dc50994-e997-41b5-99f2-0a0333f1ea11 nova service] [instance: c32ac737-1788-4420-b200-2a107d5ad335] Received event network-vif-plugged-ded0d35f-204f-4ca8-a85b-85decb53d9fe externa\</div><div>l_instance_event /opt/stack/nova/nova/compute/manager.py:6180</div><div>2015-01-01 14:44:38.293 DEBUG nova.openstack.common.lockutils [req-0dc50994-e997-41b5-99f2-0a0333f1ea11 nova service] Created new semaphore "c32ac737-1788-4420-b200-2a107d5ad335-events" internal_lock /opt/stack/nova/nova/openstack/comm\</div><div>on/lockutils.py:206</div><div>2015-01-01 14:44:38.294 DEBUG nova.openstack.common.lockutils [req-0dc50994-e997-41b5-99f2-0a0333f1ea11 nova service] Acquired semaphore "c32ac737-1788-4420-b200-2a107d5ad335-events" lock /opt/stack/nova/nova/openstack/common/lockutils\</div><div>.py:229</div><div>2015-01-01 14:44:38.294 DEBUG nova.openstack.common.lockutils [req-0dc50994-e997-41b5-99f2-0a0333f1ea11 nova service] Got semaphore / lock "_pop_event" inner /opt/stack/nova/nova/openstack/common/lockutils.py:271</div><div>2015-01-01 14:44:38.294 DEBUG nova.openstack.common.lockutils [req-0dc50994-e997-41b5-99f2-0a0333f1ea11 nova service] Releasing semaphore "c32ac737-1788-4420-b200-2a107d5ad335-events" lock /opt/stack/nova/nova/openstack/common/lockutil\</div><div>s.py:238</div><div>2015-01-01 14:44:38.295 DEBUG nova.openstack.common.lockutils [req-0dc50994-e997-41b5-99f2-0a0333f1ea11 nova service] Semaphore / lock released "_pop_event" inner /opt/stack/nova/nova/openstack/common/lockutils.py:275</div><div>2015-01-01 14:44:38.295 DEBUG nova.compute.manager [req-0dc50994-e997-41b5-99f2-0a0333f1ea11 nova service] [instance: c32ac737-1788-4420-b200-2a107d5ad335] Processing event network-vif-plugged-ded0d35f-204f-4ca8-a85b-85decb53d9fe _proc\</div><div>ess_instance_event /opt/stack/nova/nova/compute/manager.py:6166</div><div>2015-01-01 14:44:38.309 3516 DEBUG nova.virt.libvirt.driver [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] Instance is running spawn /opt/stack/nova/nova/virt/libvirt/driver.py:2622</div><div>2015-01-01 14:44:38.310 3516 DEBUG nova.virt.driver [-] Emitting event <LifecycleEvent: 1420123478.31, c32ac737-1788-4420-b200-2a107d5ad335 => <b>Resumed</b>> emit_event /opt/stack/nova/nova/virt/driver.py:1298</div><div>2015-01-01 14:44:38.311 3516 INFO nova.compute.manager [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] VM Resumed (Lifecycle Event)</div><div>2015-01-01 14:44:38.315 3516 INFO nova.virt.libvirt.driver [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] Instance spawned successfully.</div><div>2015-01-01 14:44:38.315 3516 DEBUG nova.compute.manager [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] Checking state _get_power_state /opt/stack/nova/nova/compute/manager.py:1156</div><div>2015-01-01 14:44:38.348 3516 DEBUG nova.compute.manager [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] Synchronizing instance power state after lifecycle event "Resumed"; current vm_state: building, current task_state: spawning, \</div><div>current DB power_state: 0, <b>VM power_state: 1</b> handle_lifecycle_event /opt/stack/nova/nova/compute/manager.py:1105</div><div>2015-01-01 14:44:38.391 3516 DEBUG nova.openstack.common.lockutils [-] Releasing semaphore "c32ac737-1788-4420-b200-2a107d5ad335" lock /opt/stack/nova/nova/openstack/common/lockutils.py:238</div><div>2015-01-01 14:44:38.394 3516 DEBUG nova.virt.driver [-] Emitting event <LifecycleEvent: 1420123478.31, c32ac737-1788-4420-b200-2a107d5ad335 => <b>Resumed</b>> emit_event /opt/stack/nova/nova/virt/driver.py:1298</div><div>2015-01-01 14:44:38.394 3516 DEBUG nova.openstack.common.lockutils [-] Semaphore / lock released "_locked_do_build_and_run_instance" inner /opt/stack/nova/nova/openstack/common/lockutils.py:275</div><div>2015-01-01 14:44:38.396 3516 INFO nova.compute.manager [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] VM Resumed (Lifecycle Event)</div><div>2015-01-01 14:44:38.441 3516 DEBUG nova.compute.manager [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] Synchronizing instance power state after lifecycle event "Resumed"; current vm_state: active, current task_state: None, curren\</div><div>t DB power_state: 1,<b> VM power_state: 1</b> handle_lifecycle_event /opt/stack/nova/nova/compute/manager.py:1105</div><div>2015-01-01 14:44:38.489 3516 DEBUG nova.virt.driver [-] Emitting event <LifecycleEvent: 1420123478.33, c32ac737-1788-4420-b200-2a107d5ad335 => <b>Paused</b>> emit_event /opt/stack/nova/nova/virt/driver.py:1298</div><div>2015-01-01 14:44:38.490 3516 INFO nova.compute.manager [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] VM Paused (Lifecycle Event)</div><div>2015-01-01 14:44:38.535 3516 DEBUG nova.compute.manager [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] Synchronizing instance power state after lifecycle event "Paused"; current vm_state: active, current task_state: None, current\</div><div> DB power_state: 1, <b>VM power_state: 3</b> handle_lifecycle_event /opt/stack/nova/nova/compute/manager.py:1105</div><div>2015-01-01 14:44:38.643 3516 WARNING nova.compute.manager [-] [instance: c32ac737-1788-4420-b200-2a107d5ad335] <b>Instance is paused unexpectedly</b>. Ignore.</div><div><br></div><div>I think I saw this in one of my Kilo based VMs too.</div><div><br></div><div><br></div><div>Regards,</div><div><br></div><div apple-content-edited="true">

<div style="color: rgb(0, 0, 0); letter-spacing: normal; orphans: auto; text-align: start; text-indent: 0px; text-transform: none; white-space: normal; widows: auto; word-spacing: 0px; -webkit-text-stroke-width: 0px; word-wrap: break-word; -webkit-nbsp-mode: space; -webkit-line-break: after-white-space;"><div><div>PCM (Paul Michali)</div><div><br></div><div>MAIL Е..Е. <a href="mailto:pcm@cisco.com">pcm@cisco.com</a></div><div>IRC ЕЕ..Е pc_m (<a href="http://irc.freenode.com">irc.freenode.com</a>)</div><div>TW ЕЕЕ... @pmichali</div><div>GPG Key Е 4525ECC253E31A83</div><div>Fingerprint .. 307A 96BB 1A4C D2C7 931D 8D2D 4525 ECC2 53E3 1A83</div></div><div><br></div></div><br class="Apple-interchange-newline"><br class="Apple-interchange-newline">

</div>

<br><div><div>On Dec 31, 2014, at 7:57 PM, Kevin Benton <<a href="mailto:blak111@gmail.com">blak111@gmail.com</a>> wrote:</div><br class="Apple-interchange-newline"><blockquote type="cite">Any exceptions on the Neutron side? It might not be notifying nova<br>that the network is ready.<br><br>On Wed, Dec 31, 2014 at 8:41 AM, Paul Michali (pcm) <<a href="mailto:pcm@cisco.com">pcm@cisco.com</a>> wrote:<br><blockquote type="cite">Not sure if IТm going crazy or what. IТm using DevStack and, after stacking<br>I tried booting a Cirros 3.2, 3.3, and Ubuntu cloud 14.04 image. Each time,<br>the image ends up in PAUSED power state:<br><br>ubuntu@juno:/opt/stack/neutron$ nova show peter<br>+--------------------------------------+----------------------------------------------------------------+<br>| Property                             | Value<br>|<br>+--------------------------------------+----------------------------------------------------------------+<br>| OS-DCF:diskConfig                    | MANUAL<br>|<br>| OS-EXT-AZ:availability_zone          | nova<br>|<br>| OS-EXT-SRV-ATTR:host                 | juno<br>|<br>| OS-EXT-SRV-ATTR:hypervisor_hostname  | juno<br>|<br>| OS-EXT-SRV-ATTR:instance_name        | instance-00000001<br>|<br>| OS-EXT-STS:power_state               | 3<br>|<br>| OS-EXT-STS:task_state                | -<br>|<br>| OS-EXT-STS:vm_state                  | active<br>|<br>| OS-SRV-USG:launched_at               | 2014-12-31T15:15:33.000000<br>|<br>| OS-SRV-USG:terminated_at             | -<br>|<br>| accessIPv4                           |<br>|<br>| accessIPv6                           |<br>|<br>| config_drive                         |<br>|<br>| created                              | 2014-12-31T15:15:24Z<br>|<br>| flavor                               | m1.tiny (1)<br>|<br>| hostId                               |<br>5b0c48250ccc0ac3fca8a821e29e4b154ec0b101f9cc0a0b27071a3f       |<br>| id                                   |<br>ec5c8d70-ae80-4cc3-a5bb-b68019170dd6                           |<br>| image                                | cirros-0.3.3-x86_64-uec<br>(797e4dee-8c03-497f-8dac-a44b9351dfa3) |<br>| key_name                             | -<br>|<br>| metadata                             | {}<br>|<br>| name                                 | peter<br>|<br>| os-extended-volumes:volumes_attached | []<br>|<br>| private network                      | 10.0.0.4<br>|<br>| progress                             | 0<br>|<br>| security_groups                      | default<br>|<br>| status                               | ACTIVE<br>|<br>| tenant_id                            | 7afb5bc1d88d462c8d57178437d3c277<br>|<br>| updated                              | 2014-12-31T15:15:34Z<br>|<br>| user_id                              | 4ff18bdbeb4d436ea4ff1bcd29e269a9<br>|<br>+--------------------------------------+----------------------------------------------------------------+<br>ubuntu@juno:/opt/stack/neutron$ nova list<br>+--------------------------------------+-------+--------+------------+-------------+------------------+<br>| ID                                   | Name  | Status | Task State | Power<br>State | Networks         |<br>+--------------------------------------+-------+--------+------------+-------------+------------------+<br>| ec5c8d70-ae80-4cc3-a5bb-b68019170dd6 | peter | ACTIVE | -          |<br>Paused      | private=10.0.0.4 |<br><br><br>I donТt see this with Kilo latest images. Any idea what I may be doing<br>wrong, or if there is an issue (I didnТt see anything on Google search)?<br><br>IMAGE_ID=`nova image-list | grep 'cloudimg-amd64 ' | cut -d' ' -f 2`<br>PRIVATE_NET=`neutron net-list | grep 'private ' | cut -f 2 -d' С`<br><br>nova boot peter --flavor 3 --image $IMAGE_ID --user-data<br>~/devstack/user_data.txt --nic net-id=$PRIVATE_NET<br>nova boot --flavor 1 --image cirros-0.3.3-x86_64-uec --nic<br>net-id=$PRIVATE_NET paul<br><br>Thanks.<br><br><br>PCM (Paul Michali)<br><br>MAIL Е..Е. <a href="mailto:pcm@cisco.com">pcm@cisco.com</a><br>IRC ЕЕ..Е pc_m (<a href="http://irc.freenode.com">irc.freenode.com</a>)<br>TW ЕЕЕ... @pmichali<br>GPG Key Е 4525ECC253E31A83<br>Fingerprint .. 307A 96BB 1A4C D2C7 931D 8D2D 4525 ECC2 53E3 1A83<br><br><br><br><br><br>_______________________________________________<br>OpenStack-dev mailing list<br><a href="mailto:OpenStack-dev@lists.openstack.org">OpenStack-dev@lists.openstack.org</a><br>http://lists.openstack.org/cgi-bin/mailman/listinfo/openstack-dev<br><br></blockquote><br><br><br>-- <br>Kevin Benton<br><br>_______________________________________________<br>OpenStack-dev mailing list<br><a href="mailto:OpenStack-dev@lists.openstack.org">OpenStack-dev@lists.openstack.org</a><br>http://lists.openstack.org/cgi-bin/mailman/listinfo/openstack-dev<br></blockquote></div><br></div></body></html>