2017-02-17 15:38:42.913 264015 DEBUG nova.virt.libvirt.vif [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] vif_type=tap instance=Instance(access_ip_v4=None,access_ip_v6=None,architecture=None,auto_disk_config=True,availability_zone='nova',cell_name=None,cleaned=False,config_drive='',created_at=2017-02-17T15:38:38Z,default_ephemeral_device=None,default_swap_device=None,deleted=False,deleted_at=None,device_metadata=None,disable_terminate=False,display_description='test',display_name='test',ec2_ids=EC2Ids,ephemeral_gb=0,ephemeral_key_uuid=None,fault=<?>,flavor=Flavor(1),host='ubuntu-trusty-5773',hostname='test',id=6,image_ref='26a1228b-f3b4-4ca8-be1f-1ef9247dbb4d',info_cache=InstanceInfoCache,instance_type_id=1,kernel_id='',key_data=None,key_name=None,keypairs=KeyPairList,launch_index=0,launched_at=None,launched_on='ubuntu-trusty-5773',locked=False,locked_by=None,memory_mb=256,metadata={},migration_context=<?>,new_flavor=None,node='ubuntu-trusty-5773.oneportalrapid',numa_topology=None,old_flavor=None,os_type=None,pci_devices=PciDeviceList,pci_requests=InstancePCIRequests,power_state=0,progress=0,project_id='2f960ab0e9114573813f519b1950694e',ramdisk_id='',reservation_id='r-y212fg73',root_device_name='/dev/vda',root_gb=1,security_groups=SecurityGroupList,services=<?>,shutdown_terminate=False,system_metadata={image_base_image_ref='26a1228b-f3b4-4ca8-be1f-1ef9247dbb4d',image_container_format='bare',image_disk_format='qcow2',image_min_disk='1',image_min_ram='0',network_allocated='True'},tags=<?>,task_state='spawning',terminated_at=None,updated_at=2017-02-17T15:38:39Z,user_data=None,user_id='916262856a9740caa4d0c5f10cfbb95a',uuid=0c97ab16-2d30-43fa-b0e4-a064a842b5ed,vcpu_model=VirtCPUModel,vcpus=1,vm_mode=None,vm_state='building') vif={"profile": {}, "ovs_interfaceid": null, "preserve_on_delete": false, "network": {"bridge": null, "subnets": [{"ips": [{"meta": {}, "version": 4, "type": "fixed", "floating_ips": [], "address": "192.168.74.2"}], "version": 4, "meta": {}, "dns": [], "routes": [], "cidr": "192.168.74.0/28", "gateway": {"meta": {}, "version": 4, "type": "gateway", "address": "192.168.74.1"}}], "meta": {"injected": false, "tenant_id": "2f960ab0e9114573813f519b1950694e", "mtu": 0}, "id": "28b9ef39-01a9-4c3a-9a37-8010ec619bd5", "label": "private"}, "devname": "tapf34ef99e-18", "vnic_type": "normal", "qbh_params": null, "meta": {}, "details": {"port_filter": true, "mac_address": "00:61:fe:ed:ca:fe"}, "address": "fa:16:3e:3b:8b:2b", "active": false, "type": "tap", "id": "f34ef99e-1882-4e19-bc72-78e356178f27", "qbg_params": null} plug /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/virt/libvirt/vif.py:812
2017-02-17 15:38:42.916 264015 DEBUG nova.network.os_vif_util [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] Converting VIF {"profile": {}, "ovs_interfaceid": null, "preserve_on_delete": false, "network": {"bridge": null, "subnets": [{"ips": [{"meta": {}, "version": 4, "type": "fixed", "floating_ips": [], "address": "192.168.74.2"}], "version": 4, "meta": {}, "dns": [], "routes": [], "cidr": "192.168.74.0/28", "gateway": {"meta": {}, "version": 4, "type": "gateway", "address": "192.168.74.1"}}], "meta": {"injected": false, "tenant_id": "2f960ab0e9114573813f519b1950694e", "mtu": 0}, "id": "28b9ef39-01a9-4c3a-9a37-8010ec619bd5", "label": "private"}, "devname": "tapf34ef99e-18", "vnic_type": "normal", "qbh_params": null, "meta": {}, "details": {"port_filter": true, "mac_address": "00:61:fe:ed:ca:fe"}, "address": "fa:16:3e:3b:8b:2b", "active": false, "type": "tap", "id": "f34ef99e-1882-4e19-bc72-78e356178f27", "qbg_params": null} nova_to_osvif_vif /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/network/os_vif_util.py:362
2017-02-17 15:38:42.916 264015 DEBUG nova.network.os_vif_util [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] No conversion for VIF type tap yet nova_to_osvif_vif /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/network/os_vif_util.py:378
2017-02-17 15:38:42.918 264015 DEBUG oslo_concurrency.processutils [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] Running cmd (subprocess): sudo nova-rootwrap /etc/nova/rootwrap.conf ip tuntap add tapf34ef99e-18 mode tap execute /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_concurrency/processutils.py:344
2017-02-17 15:38:43.038 264015 DEBUG oslo_concurrency.processutils [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] CMD "sudo nova-rootwrap /etc/nova/rootwrap.conf ip tuntap add tapf34ef99e-18 mode tap" returned: 0 in 0.121s execute /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_concurrency/processutils.py:374
2017-02-17 15:38:43.041 264015 DEBUG oslo_concurrency.processutils [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] Running cmd (subprocess): sudo nova-rootwrap /etc/nova/rootwrap.conf ip link set tapf34ef99e-18 address 00:61:fe:ed:ca:fe execute /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_concurrency/processutils.py:344
2017-02-17 15:38:43.154 264015 DEBUG oslo_concurrency.processutils [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] CMD "sudo nova-rootwrap /etc/nova/rootwrap.conf ip link set tapf34ef99e-18 address 00:61:fe:ed:ca:fe" returned: 0 in 0.113s execute /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_concurrency/processutils.py:374
2017-02-17 15:38:43.156 264015 DEBUG oslo_concurrency.processutils [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] Running cmd (subprocess): sudo nova-rootwrap /etc/nova/rootwrap.conf ip link set tapf34ef99e-18 up execute /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_concurrency/processutils.py:344
2017-02-17 15:38:43.291 264015 DEBUG oslo_concurrency.processutils [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] CMD "sudo nova-rootwrap /etc/nova/rootwrap.conf ip link set tapf34ef99e-18 up" returned: 0 in 0.135s execute /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_concurrency/processutils.py:374
2017-02-17 15:38:43.304 264015 DEBUG oslo_messaging._drivers.amqpdriver [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] CALL msg_id: 899be0b9b322436ca7ef1d8f1668177c exchange 'nova' topic 'conductor' _send /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:448
2017-02-17 15:38:43.382 264015 DEBUG oslo_messaging._drivers.amqpdriver [-] received reply msg_id: 899be0b9b322436ca7ef1d8f1668177c __call__ /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:296
2017-02-17 15:38:43.386 264015 DEBUG oslo_messaging._drivers.amqpdriver [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] CALL msg_id: c8d3f006c3df41e4b7c9e011b3fb88f4 exchange 'nova' topic 'conductor' _send /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:448
2017-02-17 15:38:43.404 264015 DEBUG oslo_messaging._drivers.amqpdriver [-] received reply msg_id: c8d3f006c3df41e4b7c9e011b3fb88f4 __call__ /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:296
2017-02-17 15:38:43.939 264015 ERROR nova.virt.libvirt.guest [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] Error launching a defined domain with XML: <domain type='kvm'>
  <name>instance-00000006</name>
  <uuid>0c97ab16-2d30-43fa-b0e4-a064a842b5ed</uuid>
  <metadata>
    <nova:instance xmlns:nova="http://openstack.org/xmlns/libvirt/nova/1.0">
      <nova:package version="0.0.1"/>
      <nova:name>test</nova:name>
      <nova:creationTime>2017-02-17 15:38:42</nova:creationTime>
      <nova:flavor name="tempest1">
        <nova:memory>256</nova:memory>
        <nova:disk>1</nova:disk>
        <nova:swap>0</nova:swap>
        <nova:ephemeral>0</nova:ephemeral>
        <nova:vcpus>1</nova:vcpus>
      </nova:flavor>
      <nova:owner>
        <nova:user uuid="916262856a9740caa4d0c5f10cfbb95a">admin</nova:user>
        <nova:project uuid="2f960ab0e9114573813f519b1950694e">demo</nova:project>
      </nova:owner>
      <nova:root type="image" uuid="26a1228b-f3b4-4ca8-be1f-1ef9247dbb4d"/>
    </nova:instance>
  </metadata>
  <memory unit='KiB'>262144</memory>
  <currentMemory unit='KiB'>262144</currentMemory>
  <vcpu placement='static'>1</vcpu>
  <cputune>
    <shares>1024</shares>
  </cputune>
  <sysinfo type='smbios'>
    <system>
      <entry name='manufacturer'>OpenStack Foundation</entry>
      <entry name='product'>OpenStack Nova</entry>
      <entry name='version'>0.0.1</entry>
      <entry name='serial'>395a1b47-9ba9-4dfd-87e5-5b354ed70297</entry>
      <entry name='uuid'>0c97ab16-2d30-43fa-b0e4-a064a842b5ed</entry>
      <entry name='family'>Virtual Machine</entry>
    </system>
  </sysinfo>
  <os>
    <type arch='x86_64' machine='pc-i440fx-xenial'>hvm</type>
    <boot dev='hd'/>
    <smbios mode='sysinfo'/>
  </os>
  <features>
    <acpi/>
    <apic/>
  </features>
  <cpu mode='host-model'>
    <model fallback='allow'/>
    <topology sockets='1' cores='1' threads='1'/>
  </cpu>
  <clock offset='utc'>
    <timer name='pit' tickpolicy='delay'/>
    <timer name='rtc' tickpolicy='catchup'/>
    <timer name='hpet' present='no'/>
  </clock>
  <on_poweroff>destroy</on_poweroff>
  <on_reboot>restart</on_reboot>
  <on_crash>destroy</on_crash>
  <devices>
    <emulator>/usr/bin/qemu-system-x86_64</emulator>
    <disk type='network' device='disk'>
      <driver name='qemu' type='raw' cache='writeback' discard='unmap'/>
      <auth username='cinder'>
        <secret type='ceph' uuid='9e079baf-0b2a-43c6-95fc-e7beb87fb472'/>
      </auth>
      <source protocol='rbd' name='openstack_vms/0c97ab16-2d30-43fa-b0e4-a064a842b5ed_disk'>
        <host name='172.29.237.200' port='6789'/>
      </source>
      <target dev='vda' bus='virtio'/>
      <address type='pci' domain='0x0000' bus='0x00' slot='0x04' function='0x0'/>
    </disk>
    <controller type='usb' index='0'>
      <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x2'/>
    </controller>
    <controller type='pci' index='0' model='pci-root'/>
    <interface type='ethernet'>
      <mac address='fa:16:3e:3b:8b:2b'/>
      <target dev='tapf34ef99e-18'/>
      <model type='virtio'/>
      <address type='pci' domain='0x0000' bus='0x00' slot='0x03' function='0x0'/>
    </interface>
    <serial type='file'>
      <source path='/var/lib/nova/instances/0c97ab16-2d30-43fa-b0e4-a064a842b5ed/console.log'/>
      <target port='0'/>
    </serial>
    <serial type='pty'>
      <target port='1'/>
    </serial>
    <console type='file'>
      <source path='/var/lib/nova/instances/0c97ab16-2d30-43fa-b0e4-a064a842b5ed/console.log'/>
      <target type='serial' port='0'/>
    </console>
    <input type='tablet' bus='usb'/>
    <input type='mouse' bus='ps2'/>
    <input type='keyboard' bus='ps2'/>
    <graphics type='vnc' port='-1' autoport='yes' listen='172.29.236.100' keymap='en-us'>
      <listen type='address' address='172.29.236.100'/>
    </graphics>
    <video>
      <model type='cirrus' vram='16384' heads='1'/>
      <address type='pci' domain='0x0000' bus='0x00' slot='0x02' function='0x0'/>
    </video>
    <memballoon model='virtio'>
      <stats period='10'/>
      <address type='pci' domain='0x0000' bus='0x00' slot='0x05' function='0x0'/>
    </memballoon>
  </devices>
</domain>

2017-02-17 15:38:43.940 264015 ERROR nova.virt.libvirt.driver [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed] Failed to start libvirt guest
2017-02-17 15:38:43.941 264015 DEBUG nova.virt.libvirt.vif [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] vif_type=tap instance=Instance(access_ip_v4=None,access_ip_v6=None,architecture=None,auto_disk_config=True,availability_zone='nova',cell_name=None,cleaned=False,config_drive='',created_at=2017-02-17T15:38:38Z,default_ephemeral_device=None,default_swap_device=None,deleted=False,deleted_at=None,device_metadata=None,disable_terminate=False,display_description='test',display_name='test',ec2_ids=EC2Ids,ephemeral_gb=0,ephemeral_key_uuid=None,fault=<?>,flavor=Flavor(1),host='ubuntu-trusty-5773',hostname='test',id=6,image_ref='26a1228b-f3b4-4ca8-be1f-1ef9247dbb4d',info_cache=InstanceInfoCache,instance_type_id=1,kernel_id='',key_data=None,key_name=None,keypairs=KeyPairList,launch_index=0,launched_at=None,launched_on='ubuntu-trusty-5773',locked=False,locked_by=None,memory_mb=256,metadata={},migration_context=<?>,new_flavor=None,node='ubuntu-trusty-5773.oneportalrapid',numa_topology=None,old_flavor=None,os_type=None,pci_devices=PciDeviceList,pci_requests=InstancePCIRequests,power_state=0,progress=0,project_id='2f960ab0e9114573813f519b1950694e',ramdisk_id='',reservation_id='r-y212fg73',root_device_name='/dev/vda',root_gb=1,security_groups=SecurityGroupList,services=<?>,shutdown_terminate=False,system_metadata={image_base_image_ref='26a1228b-f3b4-4ca8-be1f-1ef9247dbb4d',image_container_format='bare',image_disk_format='qcow2',image_min_disk='1',image_min_ram='0',network_allocated='True'},tags=<?>,task_state='spawning',terminated_at=None,updated_at=2017-02-17T15:38:39Z,user_data=None,user_id='916262856a9740caa4d0c5f10cfbb95a',uuid=0c97ab16-2d30-43fa-b0e4-a064a842b5ed,vcpu_model=VirtCPUModel,vcpus=1,vm_mode=None,vm_state='building') vif={"profile": {}, "ovs_interfaceid": null, "preserve_on_delete": false, "network": {"bridge": null, "subnets": [{"ips": [{"meta": {}, "version": 4, "type": "fixed", "floating_ips": [], "address": "192.168.74.2"}], "version": 4, "meta": {}, "dns": [], "routes": [], "cidr": "192.168.74.0/28", "gateway": {"meta": {}, "version": 4, "type": "gateway", "address": "192.168.74.1"}}], "meta": {"injected": false, "tenant_id": "2f960ab0e9114573813f519b1950694e", "mtu": 0}, "id": "28b9ef39-01a9-4c3a-9a37-8010ec619bd5", "label": "private"}, "devname": "tapf34ef99e-18", "vnic_type": "normal", "qbh_params": null, "meta": {}, "details": {"port_filter": true, "mac_address": "00:61:fe:ed:ca:fe"}, "address": "fa:16:3e:3b:8b:2b", "active": false, "type": "tap", "id": "f34ef99e-1882-4e19-bc72-78e356178f27", "qbg_params": null} unplug /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/virt/libvirt/vif.py:1033
2017-02-17 15:38:43.944 264015 DEBUG nova.network.os_vif_util [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] Converting VIF {"profile": {}, "ovs_interfaceid": null, "preserve_on_delete": false, "network": {"bridge": null, "subnets": [{"ips": [{"meta": {}, "version": 4, "type": "fixed", "floating_ips": [], "address": "192.168.74.2"}], "version": 4, "meta": {}, "dns": [], "routes": [], "cidr": "192.168.74.0/28", "gateway": {"meta": {}, "version": 4, "type": "gateway", "address": "192.168.74.1"}}], "meta": {"injected": false, "tenant_id": "2f960ab0e9114573813f519b1950694e", "mtu": 0}, "id": "28b9ef39-01a9-4c3a-9a37-8010ec619bd5", "label": "private"}, "devname": "tapf34ef99e-18", "vnic_type": "normal", "qbh_params": null, "meta": {}, "details": {"port_filter": true, "mac_address": "00:61:fe:ed:ca:fe"}, "address": "fa:16:3e:3b:8b:2b", "active": false, "type": "tap", "id": "f34ef99e-1882-4e19-bc72-78e356178f27", "qbg_params": null} nova_to_osvif_vif /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/network/os_vif_util.py:362
2017-02-17 15:38:43.945 264015 DEBUG nova.network.os_vif_util [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] No conversion for VIF type tap yet nova_to_osvif_vif /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/network/os_vif_util.py:378
2017-02-17 15:38:43.946 264015 DEBUG oslo_concurrency.processutils [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] Running cmd (subprocess): sudo nova-rootwrap /etc/nova/rootwrap.conf ip link delete tapf34ef99e-18 execute /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_concurrency/processutils.py:344
2017-02-17 15:38:44.099 264015 DEBUG oslo_concurrency.processutils [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] CMD "sudo nova-rootwrap /etc/nova/rootwrap.conf ip link delete tapf34ef99e-18" returned: 0 in 0.153s execute /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_concurrency/processutils.py:374
2017-02-17 15:38:44.101 264015 DEBUG nova.network.linux_net [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] Net device removed: 'tapf34ef99e-18' delete_net_dev /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/network/linux_net.py:1350
2017-02-17 15:38:44.258 264015 DEBUG oslo_concurrency.processutils [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] Running cmd (subprocess): mv /var/lib/nova/instances/0c97ab16-2d30-43fa-b0e4-a064a842b5ed /var/lib/nova/instances/0c97ab16-2d30-43fa-b0e4-a064a842b5ed_del execute /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_concurrency/processutils.py:344
2017-02-17 15:38:44.272 264015 DEBUG oslo_concurrency.processutils [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] CMD "mv /var/lib/nova/instances/0c97ab16-2d30-43fa-b0e4-a064a842b5ed /var/lib/nova/instances/0c97ab16-2d30-43fa-b0e4-a064a842b5ed_del" returned: 0 in 0.014s execute /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_concurrency/processutils.py:374
2017-02-17 15:38:44.274 264015 INFO nova.virt.libvirt.driver [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed] Deleting instance files /var/lib/nova/instances/0c97ab16-2d30-43fa-b0e4-a064a842b5ed_del
2017-02-17 15:38:44.275 264015 INFO nova.virt.libvirt.driver [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed] Deletion of /var/lib/nova/instances/0c97ab16-2d30-43fa-b0e4-a064a842b5ed_del complete
2017-02-17 15:38:44.281 264015 DEBUG oslo_messaging._drivers.amqpdriver [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] CALL msg_id: 1123f70b2e3040db84324d2f585850bb exchange 'nova' topic 'conductor' _send /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:448
2017-02-17 15:38:44.371 264015 DEBUG oslo_messaging._drivers.amqpdriver [-] received message with unique_id: 25b1a8a82f5d42b3b4f87f27a6d9146f __call__ /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:196
2017-02-17 15:38:44.375 264015 DEBUG nova.compute.manager [req-1c0bbbae-aeca-46da-a0ad-b2a022c1ea47 nova service] [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed] Received event network-changed external_instance_event /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/compute/manager.py:6683
2017-02-17 15:38:44.376 264015 DEBUG oslo_concurrency.lockutils [req-1c0bbbae-aeca-46da-a0ad-b2a022c1ea47 nova service] Acquired semaphore "refresh_cache-0c97ab16-2d30-43fa-b0e4-a064a842b5ed" lock /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_concurrency/lockutils.py:212
2017-02-17 15:38:44.377 264015 DEBUG nova.network.neutronv2.api [req-1c0bbbae-aeca-46da-a0ad-b2a022c1ea47 nova service] [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed] _get_instance_nw_info() _get_instance_nw_info /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/network/neutronv2/api.py:1276
2017-02-17 15:38:44.377 264015 DEBUG nova.objects.instance [req-1c0bbbae-aeca-46da-a0ad-b2a022c1ea47 nova service] Lazy-loading 'info_cache' on Instance uuid 0c97ab16-2d30-43fa-b0e4-a064a842b5ed obj_load_attr /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/objects/instance.py:1013
2017-02-17 15:38:44.379 264015 DEBUG oslo_messaging._drivers.amqpdriver [req-1c0bbbae-aeca-46da-a0ad-b2a022c1ea47 nova service] CALL msg_id: 181607f28ffa4a59bd998a41d0e27267 exchange 'nova' topic 'conductor' _send /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:448
2017-02-17 15:38:44.438 264015 DEBUG oslo_messaging._drivers.amqpdriver [-] received reply msg_id: 1123f70b2e3040db84324d2f585850bb __call__ /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:296
2017-02-17 15:38:44.443 264015 DEBUG oslo_messaging._drivers.amqpdriver [-] received reply msg_id: 181607f28ffa4a59bd998a41d0e27267 __call__ /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:296
2017-02-17 15:38:44.446 264015 DEBUG oslo_messaging._drivers.amqpdriver [req-1c0bbbae-aeca-46da-a0ad-b2a022c1ea47 nova service] CALL msg_id: 395b2147471a41f6889dc338a35f5017 exchange 'nova' topic 'conductor' _send /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:448
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed] Instance failed to spawn
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed] Traceback (most recent call last):
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/compute/manager.py", line 2082, in _build_resources
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     yield resources
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/compute/manager.py", line 1924, in _build_and_run_instance
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     block_device_info=block_device_info)
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 2619, in spawn
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     destroy_disks_on_failure=True)
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 4904, in _create_domain_and_network
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     destroy_disks_on_failure)
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_utils/excutils.py", line 220, in __exit__
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     self.force_reraise()
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_utils/excutils.py", line 196, in force_reraise
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     six.reraise(self.type_, self.value, self.tb)
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 4876, in _create_domain_and_network
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     post_xml_callback=post_xml_callback)
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 4794, in _create_domain
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     guest.launch(pause=pause)
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/virt/libvirt/guest.py", line 144, in launch
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     self._encoded_xml, errors='ignore')
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_utils/excutils.py", line 220, in __exit__
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     self.force_reraise()
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_utils/excutils.py", line 196, in force_reraise
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     six.reraise(self.type_, self.value, self.tb)
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/virt/libvirt/guest.py", line 139, in launch
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     return self._domain.createWithFlags(flags)
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/eventlet/tpool.py", line 186, in doit
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     result = proxy_call(self._autowrap, f, *args, **kwargs)
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/eventlet/tpool.py", line 144, in proxy_call
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     rv = execute(f, *args, **kwargs)
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/eventlet/tpool.py", line 125, in execute
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     six.reraise(c, e, tb)
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/eventlet/tpool.py", line 83, in tworker
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     rv = meth(*args, **kwargs)
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]   File "/openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/libvirt.py", line 1065, in createWithFlags
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed] libvirtError: internal error: process exited while connecting to monitor: 2017-02-17T15:38:43.907222Z qemu-system-x86_64: -netdev tap,ifname=tapf34ef99e-18,id=hostnet0,vhost=on,vhostfd=28: network script /etc/qemu-ifup failed with status 256
2017-02-17 15:38:44.458 264015 ERROR nova.compute.manager [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed]
2017-02-17 15:38:44.461 264015 INFO nova.compute.manager [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed] Terminating instance
2017-02-17 15:38:44.463 264015 DEBUG oslo_messaging._drivers.amqpdriver [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] CAST unique_id: 8c3c206c8c574d80b20e3ba95b2e108b NOTIFY exchange 'nova' topic 'notifications.info' _send /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:432
2017-02-17 15:38:44.467 264015 DEBUG nova.compute.manager [req-316cae6a-2338-44a4-8c86-fe4d3682c65c admin demo] [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed] Start destroying the instance on the hypervisor. _shutdown_instance /openstack/venvs/nova-untagged/local/lib/python2.7/site-packages/nova/compute/manager.py:2196
2017-02-17 15:38:44.471 264015 INFO nova.virt.libvirt.driver [-] [instance: 0c97ab16-2d30-43fa-b0e4-a064a842b5ed] During wait destroy, instance disappeared.