← Back to team overview

yahoo-eng-team team mailing list archive

[Bug 1259611] [NEW] launch of Windows VMs fails when passing the --file argument

 

Public bug reported:

When launching an instance with the following command:
nova boot --image 7a5a741c-73ac-4061-8c10-254a77b96c9e --availability_zone nova:beijing.sics.se --flavor 3 --file injectedFile.txt=injectedFile.txt VM1

the instance fails to launch with the following output (LOG1)

Launching the instance without the --file option results in a successful launch (LOG2)
nova boot --image 7a5a741c-73ac-4061-8c10-254a77b96c9e --availability_zone nova:beijing.sics.se --flavor 3 VM2


There has been an earlier question on this topic (https://lists.launchpad.net/openstack/msg22969.html), but this is a different problem.


Logs following:


LOG1:
2013-12-06 18:04:02.810 AUDIT nova.compute.manager [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Starting instance...
2013-12-06 18:04:04.119 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Attempting claim: memory 4096 MB, disk 40 GB, VCPUs 2
2013-12-06 18:04:04.120 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Total Memory: 64378 MB, used: 37376 MB
2013-12-06 18:04:04.120 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Memory limit: 96567 MB, free: 59191 MB
2013-12-06 18:04:04.121 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Total Disk: 886 GB, used: 360 GB
2013-12-06 18:04:04.121 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Disk limit not specified, defaulting to unlimited
2013-12-06 18:04:04.122 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Total CPU: 12 VCPUs, used: 18 VCPUs
2013-12-06 18:04:04.122 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] CPU limit: 192 VCPUs, free: 174 VCPUs
2013-12-06 18:04:04.122 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Claim successful
2013-12-06 18:04:07.350 INFO nova.virt.libvirt.driver [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Creating image
2013-12-06 18:04:07.443 INFO nova.virt.libvirt.driver [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Injecting files into image 702b3e56-8d05-4f58-ac9d-8fdbf77ba31a
2013-12-06 18:04:11.890 ERROR nova.virt.libvirt.driver [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Error injecting data into image 702b3e56-8d05-4f58-ac9d-8fdbf77ba31a (No operating system found in /var/lib/nova/instances/70145a30-5602-4b66-b41b-d9737f737ce8/disk)
2013-12-06 18:04:11.892 ERROR nova.compute.manager [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Instance failed to spawn
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Traceback (most recent call last):
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1120, in _spawn
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     block_device_info)
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1524, in spawn
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     admin_pass=admin_password)
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1922, in _create_image
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     instance=instance)
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     self.gen.next()
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1917, in _create_image
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     mandatory=('files',))
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/disk/api.py", line 291, in inject_data
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     fs.setup()
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/disk/vfs/guestfs.py", line 106, in setup
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     self.setup_os()
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/disk/vfs/guestfs.py", line 50, in setup_os
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     self.setup_os_inspect()
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/disk/vfs/guestfs.py", line 69, in setup_os_inspect
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     % self.imgfile)
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] NovaException: No operating system found in /var/lib/nova/instances/70145a30-5602-4b66-b41b-d9737f737ce8/disk
2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] 
2013-12-06 18:04:12.092 AUDIT nova.compute.manager [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Terminating instance
2013-12-06 18:04:13.405 8751 ERROR nova.virt.libvirt.driver [-] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] During wait destroy, instance disappeared.
2013-12-06 18:04:13.601 ERROR nova.virt.libvirt.vif [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Failed while unplugging vif
2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Traceback (most recent call last):
2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/vif.py", line 456, in unplug_ovs_hybrid
2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     utils.execute('brctl', 'delif', br_name, v1_name, run_as_root=True)
2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 239, in execute
2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     cmd=' '.join(cmd))
2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] ProcessExecutionError: Unexpected error while running command.
2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Command: sudo nova-rootwrap /etc/nova/rootwrap.conf brctl delif qbr93cb730b-39 qvb93cb730b-39
2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Exit code: 1
2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stdout: ''
2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stderr: 'interface qvb93cb730b-39 does not exist!\n'
2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] 
2013-12-06 18:04:13.754 ERROR nova.virt.libvirt.vif [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Failed while unplugging vif
2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Traceback (most recent call last):
2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/vif.py", line 456, in unplug_ovs_hybrid
2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     utils.execute('brctl', 'delif', br_name, v1_name, run_as_root=True)
2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 239, in execute
2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     cmd=' '.join(cmd))
2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] ProcessExecutionError: Unexpected error while running command.
2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Command: sudo nova-rootwrap /etc/nova/rootwrap.conf brctl delif qbr69aa7db1-df qvb69aa7db1-df
2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Exit code: 1
2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stdout: ''
2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stderr: 'interface qvb69aa7db1-df does not exist!\n'
2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] 
2013-12-06 18:04:13.909 ERROR nova.virt.libvirt.vif [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Failed while unplugging vif
2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Traceback (most recent call last):
2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/vif.py", line 456, in unplug_ovs_hybrid
2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     utils.execute('brctl', 'delif', br_name, v1_name, run_as_root=True)
2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 239, in execute
2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     cmd=' '.join(cmd))
2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] ProcessExecutionError: Unexpected error while running command.
2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Command: sudo nova-rootwrap /etc/nova/rootwrap.conf brctl delif qbr556e0c0a-7b qvb556e0c0a-7b
2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Exit code: 1
2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stdout: ''
2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stderr: 'interface qvb556e0c0a-7b does not exist!\n'
2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] 
2013-12-06 18:04:14.057 ERROR nova.virt.libvirt.vif [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Failed while unplugging vif
2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Traceback (most recent call last):
2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/vif.py", line 456, in unplug_ovs_hybrid
2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     utils.execute('brctl', 'delif', br_name, v1_name, run_as_root=True)
2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 239, in execute
2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     cmd=' '.join(cmd))
2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] ProcessExecutionError: Unexpected error while running command.
2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Command: sudo nova-rootwrap /etc/nova/rootwrap.conf brctl delif qbr186fa478-3a qvb186fa478-3a
2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Exit code: 1
2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stdout: ''
2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stderr: 'interface qvb186fa478-3a does not exist!\n'
2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] 
2013-12-06 18:04:14.059 INFO nova.virt.libvirt.driver [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Deleting instance files /var/lib/nova/instances/70145a30-5602-4b66-b41b-d9737f737ce8
2013-12-06 18:04:15.011 ERROR nova.compute.manager [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Error: ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 865, in _run_instance\n    set_access_ip=set_access_ip)\n', '  File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1124, in _spawn\n    LOG.exception(_(\'Instance failed to spawn\'), instance=instance)\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/compute/manager.py", line 1120, in _spawn\n    block_device_info)\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1524, in spawn\n    admin_pass=admin_password)\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1922, in _create_image\n    instance=instance)\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/virt/libvirt/driver.py", line 1917, in _create_image\n    mandatory=(\'files\',))\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/disk/api.py", line 291, in inject_data\n    fs.setup()\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/disk/vfs/guestfs.py", line 106, in setup\n    self.setup_os()\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/disk/vfs/guestfs.py", line 50, in setup_os\n    self.setup_os_inspect()\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/disk/vfs/guestfs.py", line 69, in setup_os_inspect\n    % self.imgfile)\n', 'NovaException: No operating system found in /var/lib/nova/instances/70145a30-5602-4b66-b41b-d9737f737ce8/disk\n']


LOG2:

2013-12-06 18:00:46.219 AUDIT nova.compute.manager [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Starting instance...
2013-12-06 18:00:47.090 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Attempting claim: memory 4096 MB, disk 40 GB, VCPUs 2
2013-12-06 18:00:47.091 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Total Memory: 64378 MB, used: 33280 MB
2013-12-06 18:00:47.091 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Memory limit: 96567 MB, free: 63287 MB
2013-12-06 18:00:47.091 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Total Disk: 886 GB, used: 320 GB
2013-12-06 18:00:47.092 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Disk limit not specified, defaulting to unlimited
2013-12-06 18:00:47.092 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Total CPU: 12 VCPUs, used: 16 VCPUs
2013-12-06 18:00:47.093 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] CPU limit: 192 VCPUs, free: 176 VCPUs
2013-12-06 18:00:47.093 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Claim successful
2013-12-06 18:00:50.362 INFO nova.virt.libvirt.driver [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Creating image
2013-12-06 18:01:00.685 8751 INFO nova.compute.manager [-] Lifecycle event 0 on VM e93734fb-7956-4fa3-9bb6-7c8ff470c374
2013-12-06 18:01:00.887 8751 INFO nova.compute.manager [-] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] During sync_power_state the instance has a pending task. Skip.
2013-12-06 18:01:01.307 8751 INFO nova.virt.libvirt.driver [-] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Instance spawned successfully.

** Affects: nova
     Importance: Undecided
         Status: New


** Tags: file injection windows

-- 
You received this bug notification because you are a member of Yahoo!
Engineering Team, which is subscribed to OpenStack Compute (nova).
https://bugs.launchpad.net/bugs/1259611

Title:
  launch of Windows VMs fails when passing the --file argument

Status in OpenStack Compute (Nova):
  New

Bug description:
  When launching an instance with the following command:
  nova boot --image 7a5a741c-73ac-4061-8c10-254a77b96c9e --availability_zone nova:beijing.sics.se --flavor 3 --file injectedFile.txt=injectedFile.txt VM1

  the instance fails to launch with the following output (LOG1)

  Launching the instance without the --file option results in a successful launch (LOG2)
  nova boot --image 7a5a741c-73ac-4061-8c10-254a77b96c9e --availability_zone nova:beijing.sics.se --flavor 3 VM2

  
  There has been an earlier question on this topic (https://lists.launchpad.net/openstack/msg22969.html), but this is a different problem.

  
  Logs following:

  
  LOG1:
  2013-12-06 18:04:02.810 AUDIT nova.compute.manager [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Starting instance...
  2013-12-06 18:04:04.119 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Attempting claim: memory 4096 MB, disk 40 GB, VCPUs 2
  2013-12-06 18:04:04.120 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Total Memory: 64378 MB, used: 37376 MB
  2013-12-06 18:04:04.120 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Memory limit: 96567 MB, free: 59191 MB
  2013-12-06 18:04:04.121 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Total Disk: 886 GB, used: 360 GB
  2013-12-06 18:04:04.121 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Disk limit not specified, defaulting to unlimited
  2013-12-06 18:04:04.122 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Total CPU: 12 VCPUs, used: 18 VCPUs
  2013-12-06 18:04:04.122 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] CPU limit: 192 VCPUs, free: 174 VCPUs
  2013-12-06 18:04:04.122 AUDIT nova.compute.claims [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Claim successful
  2013-12-06 18:04:07.350 INFO nova.virt.libvirt.driver [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Creating image
  2013-12-06 18:04:07.443 INFO nova.virt.libvirt.driver [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Injecting files into image 702b3e56-8d05-4f58-ac9d-8fdbf77ba31a
  2013-12-06 18:04:11.890 ERROR nova.virt.libvirt.driver [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Error injecting data into image 702b3e56-8d05-4f58-ac9d-8fdbf77ba31a (No operating system found in /var/lib/nova/instances/70145a30-5602-4b66-b41b-d9737f737ce8/disk)
  2013-12-06 18:04:11.892 ERROR nova.compute.manager [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Instance failed to spawn
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Traceback (most recent call last):
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1120, in _spawn
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     block_device_info)
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1524, in spawn
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     admin_pass=admin_password)
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1922, in _create_image
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     instance=instance)
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     self.gen.next()
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1917, in _create_image
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     mandatory=('files',))
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/disk/api.py", line 291, in inject_data
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     fs.setup()
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/disk/vfs/guestfs.py", line 106, in setup
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     self.setup_os()
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/disk/vfs/guestfs.py", line 50, in setup_os
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     self.setup_os_inspect()
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/disk/vfs/guestfs.py", line 69, in setup_os_inspect
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     % self.imgfile)
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] NovaException: No operating system found in /var/lib/nova/instances/70145a30-5602-4b66-b41b-d9737f737ce8/disk
  2013-12-06 18:04:11.892 8751 TRACE nova.compute.manager [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] 
  2013-12-06 18:04:12.092 AUDIT nova.compute.manager [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Terminating instance
  2013-12-06 18:04:13.405 8751 ERROR nova.virt.libvirt.driver [-] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] During wait destroy, instance disappeared.
  2013-12-06 18:04:13.601 ERROR nova.virt.libvirt.vif [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Failed while unplugging vif
  2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Traceback (most recent call last):
  2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/vif.py", line 456, in unplug_ovs_hybrid
  2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     utils.execute('brctl', 'delif', br_name, v1_name, run_as_root=True)
  2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 239, in execute
  2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     cmd=' '.join(cmd))
  2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] ProcessExecutionError: Unexpected error while running command.
  2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Command: sudo nova-rootwrap /etc/nova/rootwrap.conf brctl delif qbr93cb730b-39 qvb93cb730b-39
  2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Exit code: 1
  2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stdout: ''
  2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stderr: 'interface qvb93cb730b-39 does not exist!\n'
  2013-12-06 18:04:13.601 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] 
  2013-12-06 18:04:13.754 ERROR nova.virt.libvirt.vif [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Failed while unplugging vif
  2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Traceback (most recent call last):
  2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/vif.py", line 456, in unplug_ovs_hybrid
  2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     utils.execute('brctl', 'delif', br_name, v1_name, run_as_root=True)
  2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 239, in execute
  2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     cmd=' '.join(cmd))
  2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] ProcessExecutionError: Unexpected error while running command.
  2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Command: sudo nova-rootwrap /etc/nova/rootwrap.conf brctl delif qbr69aa7db1-df qvb69aa7db1-df
  2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Exit code: 1
  2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stdout: ''
  2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stderr: 'interface qvb69aa7db1-df does not exist!\n'
  2013-12-06 18:04:13.754 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] 
  2013-12-06 18:04:13.909 ERROR nova.virt.libvirt.vif [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Failed while unplugging vif
  2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Traceback (most recent call last):
  2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/vif.py", line 456, in unplug_ovs_hybrid
  2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     utils.execute('brctl', 'delif', br_name, v1_name, run_as_root=True)
  2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 239, in execute
  2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     cmd=' '.join(cmd))
  2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] ProcessExecutionError: Unexpected error while running command.
  2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Command: sudo nova-rootwrap /etc/nova/rootwrap.conf brctl delif qbr556e0c0a-7b qvb556e0c0a-7b
  2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Exit code: 1
  2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stdout: ''
  2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stderr: 'interface qvb556e0c0a-7b does not exist!\n'
  2013-12-06 18:04:13.909 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] 
  2013-12-06 18:04:14.057 ERROR nova.virt.libvirt.vif [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Failed while unplugging vif
  2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Traceback (most recent call last):
  2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/vif.py", line 456, in unplug_ovs_hybrid
  2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     utils.execute('brctl', 'delif', br_name, v1_name, run_as_root=True)
  2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 239, in execute
  2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8]     cmd=' '.join(cmd))
  2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] ProcessExecutionError: Unexpected error while running command.
  2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Command: sudo nova-rootwrap /etc/nova/rootwrap.conf brctl delif qbr186fa478-3a qvb186fa478-3a
  2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Exit code: 1
  2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stdout: ''
  2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Stderr: 'interface qvb186fa478-3a does not exist!\n'
  2013-12-06 18:04:14.057 8751 TRACE nova.virt.libvirt.vif [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] 
  2013-12-06 18:04:14.059 INFO nova.virt.libvirt.driver [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Deleting instance files /var/lib/nova/instances/70145a30-5602-4b66-b41b-d9737f737ce8
  2013-12-06 18:04:15.011 ERROR nova.compute.manager [req-63f3917c-592f-4bc2-ab21-ad73f85c4f4c 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: 70145a30-5602-4b66-b41b-d9737f737ce8] Error: ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 865, in _run_instance\n    set_access_ip=set_access_ip)\n', '  File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1124, in _spawn\n    LOG.exception(_(\'Instance failed to spawn\'), instance=instance)\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/compute/manager.py", line 1120, in _spawn\n    block_device_info)\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1524, in spawn\n    admin_pass=admin_password)\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1922, in _create_image\n    instance=instance)\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/virt/libvirt/driver.py", line 1917, in _create_image\n    mandatory=(\'files\',))\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/disk/api.py", line 291, in inject_data\n    fs.setup()\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/disk/vfs/guestfs.py", line 106, in setup\n    self.setup_os()\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/disk/vfs/guestfs.py", line 50, in setup_os\n    self.setup_os_inspect()\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/disk/vfs/guestfs.py", line 69, in setup_os_inspect\n    % self.imgfile)\n', 'NovaException: No operating system found in /var/lib/nova/instances/70145a30-5602-4b66-b41b-d9737f737ce8/disk\n']


  LOG2:

  2013-12-06 18:00:46.219 AUDIT nova.compute.manager [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Starting instance...
  2013-12-06 18:00:47.090 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Attempting claim: memory 4096 MB, disk 40 GB, VCPUs 2
  2013-12-06 18:00:47.091 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Total Memory: 64378 MB, used: 33280 MB
  2013-12-06 18:00:47.091 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Memory limit: 96567 MB, free: 63287 MB
  2013-12-06 18:00:47.091 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Total Disk: 886 GB, used: 320 GB
  2013-12-06 18:00:47.092 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Disk limit not specified, defaulting to unlimited
  2013-12-06 18:00:47.092 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Total CPU: 12 VCPUs, used: 16 VCPUs
  2013-12-06 18:00:47.093 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] CPU limit: 192 VCPUs, free: 176 VCPUs
  2013-12-06 18:00:47.093 AUDIT nova.compute.claims [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Claim successful
  2013-12-06 18:00:50.362 INFO nova.virt.libvirt.driver [req-e0ba84f2-e9c9-4f00-8769-62ec9181ae1b 77aabd14a2e1453489dec37d7b174e58 fa47eeb8b84a4b6d87ef3332604894b0] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Creating image
  2013-12-06 18:01:00.685 8751 INFO nova.compute.manager [-] Lifecycle event 0 on VM e93734fb-7956-4fa3-9bb6-7c8ff470c374
  2013-12-06 18:01:00.887 8751 INFO nova.compute.manager [-] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] During sync_power_state the instance has a pending task. Skip.
  2013-12-06 18:01:01.307 8751 INFO nova.virt.libvirt.driver [-] [instance: e93734fb-7956-4fa3-9bb6-7c8ff470c374] Instance spawned successfully.

To manage notifications about this bug go to:
https://bugs.launchpad.net/nova/+bug/1259611/+subscriptions


Follow ups

References