yahoo-eng-team team mailing list archive
-
yahoo-eng-team team
-
Mailing list archive
-
Message #07091
[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