Attachment 584917 Details for Bug 743671 – second guest can start on second time

second guest can start on second time

libvirtd.log-2usb-assign (text/plain), 125.03 KB, created by weizhang on 2012-05-16 09:32:47 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: weizhang

Created: 2012-05-16 09:32:47 UTC

Size: 125.03 KB

patch

obsolete

>2012-05-16 09:29:15.394+0000: 23007: info : libvirt version: 0.9.10, package: 20.el6 (Red Hat, Inc. <http://bugzilla.redhat.com/bugzilla>, 2012-05-15-21:58:29, x86-007.build.bos.redhat.com)
>2012-05-16 09:29:15.394+0000: 23007: debug : virRegisterNetworkDriver:592 : registering Network as network driver 3
>2012-05-16 09:29:15.394+0000: 23007: debug : virRegisterInterfaceDriver:625 : registering Interface as interface driver 3
>2012-05-16 09:29:15.394+0000: 23007: debug : virRegisterStorageDriver:658 : registering storage as storage driver 3
>2012-05-16 09:29:15.394+0000: 23007: debug : virRegisterDeviceMonitor:691 : registering udevDeviceMonitor as device driver 3
>2012-05-16 09:29:15.394+0000: 23007: debug : virRegisterSecretDriver:724 : registering secret as secret driver 3
>2012-05-16 09:29:15.394+0000: 23007: debug : virRegisterNWFilterDriver:757 : registering nwfilter as network filter driver 3
>2012-05-16 09:29:15.394+0000: 23007: debug : virRegisterDriver:775 : driver=0x73a3e0 name=QEMU
>2012-05-16 09:29:15.394+0000: 23007: debug : virRegisterDriver:799 : registering QEMU as driver 3
>2012-05-16 09:29:15.395+0000: 23007: debug : virRegisterDriver:775 : driver=0x73ab20 name=LXC
>2012-05-16 09:29:15.395+0000: 23007: debug : virRegisterDriver:799 : registering LXC as driver 4
>2012-05-16 09:29:15.734+0000: 23018: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'cpu'
>2012-05-16 09:29:15.734+0000: 23018: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'cpuacct'
>2012-05-16 09:29:15.734+0000: 23018: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'cpuset'
>2012-05-16 09:29:15.734+0000: 23018: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'memory'
>2012-05-16 09:29:15.734+0000: 23018: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'devices'
>2012-05-16 09:29:15.734+0000: 23018: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'blkio'
>2012-05-16 09:29:15.803+0000: 23018: debug : qemuCapsParseHelpStr:1337 : Version 0.12.1, cooked version 12001, flags 0x00000000400e5c000109fc6cf7effd6e
>2012-05-16 09:29:15.803+0000: 23018: debug : qemuCapsParseHelpStr:1343 : qemu-kvm version 0.12.1 detected
>2012-05-16 09:29:15.941+0000: 23018: debug : qemuCapsParseHelpStr:1337 : Version 0.12.1, cooked version 12001, flags 0x00000000400e5c000109fc6cf7effd6e
>2012-05-16 09:29:15.941+0000: 23018: debug : qemuCapsParseHelpStr:1343 : qemu-kvm version 0.12.1 detected
>2012-05-16 09:29:16.058+0000: 23018: debug : qemuCreateCapabilities:294 : Initialized caps for security driver "selinux" with DOI "0"
>2012-05-16 09:29:16.058+0000: 23018: info : virDomainLoadAllConfigs:13048 : Scanning for configs in /var/run/libvirt/qemu
>2012-05-16 09:29:16.058+0000: 23018: debug : virConnectOpen:1349 : name=qemu:///system
>2012-05-16 09:29:16.058+0000: 23018: debug : virConnectOpenResolveURIAlias:1070 : Loading config file '/etc/libvirt/libvirt.conf'
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1151 : name "qemu:///system" to URI components:
>  scheme qemu
>  opaque (null)
>  authority (null)
>  server (null)
>  user (null)
>  port 0
>  path /system
>
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1195 : trying driver 0 (Test) ...
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1201 : driver 0 Test returned DECLINED
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1195 : trying driver 1 (ESX) ...
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1201 : driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1195 : trying driver 2 (remote) ...
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1201 : driver 2 remote returned DECLINED
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1195 : trying driver 3 (QEMU) ...
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1201 : driver 3 QEMU returned SUCCESS
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1223 : network driver 0 Test returned DECLINED
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1223 : network driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1223 : network driver 2 remote returned DECLINED
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1223 : network driver 3 Network returned SUCCESS
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1238 : interface driver 0 Test returned DECLINED
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1238 : interface driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.058+0000: 23018: debug : do_open:1238 : interface driver 2 remote returned DECLINED
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1238 : interface driver 3 Interface returned SUCCESS
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1254 : storage driver 0 Test returned DECLINED
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1254 : storage driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1254 : storage driver 2 remote returned DECLINED
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1254 : storage driver 3 storage returned SUCCESS
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1270 : node driver 0 Test returned DECLINED
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1270 : node driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1270 : node driver 2 remote returned DECLINED
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1270 : node driver 3 udevDeviceMonitor returned SUCCESS
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1286 : secret driver 0 Test returned DECLINED
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1286 : secret driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1286 : secret driver 2 remote returned DECLINED
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1286 : secret driver 3 secret returned SUCCESS
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1302 : nwfilter driver 0 Test returned DECLINED
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1302 : nwfilter driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1302 : nwfilter driver 2 remote returned DECLINED
>2012-05-16 09:29:16.070+0000: 23018: debug : do_open:1302 : nwfilter driver 3 nwfilter returned SUCCESS
>2012-05-16 09:29:16.070+0000: 23018: info : virDomainLoadAllConfigs:13048 : Scanning for configs in /etc/libvirt/qemu
>2012-05-16 09:29:16.070+0000: 23018: info : virDomainLoadAllConfigs:13070 : Loading config file 'rhel6.xml'
>2012-05-16 09:29:16.071+0000: 23018: debug : virDomainObjNew:1672 : obj=0x7f6d7c160060
>2012-05-16 09:29:16.071+0000: 23018: info : virDomainLoadAllConfigs:13070 : Loading config file 'rhel5u6.xml'
>2012-05-16 09:29:16.071+0000: 23018: debug : virDomainObjNew:1672 : obj=0x7f6d7c15f490
>2012-05-16 09:29:16.071+0000: 23018: info : virDomainLoadAllConfigs:13070 : Loading config file 'mig.xml'
>2012-05-16 09:29:16.072+0000: 23018: debug : virDomainObjNew:1672 : obj=0x7f6d7c15f3c0
>2012-05-16 09:29:16.072+0000: 23018: info : virDomainLoadAllConfigs:13070 : Loading config file 'win-test.xml'
>2012-05-16 09:29:16.072+0000: 23018: debug : virDomainObjNew:1672 : obj=0x7f6d7c1783d0
>2012-05-16 09:29:16.072+0000: 23018: info : virDomainLoadAllConfigs:13070 : Loading config file 'win7-qcow2.xml'
>2012-05-16 09:29:16.073+0000: 23018: debug : virDomainObjNew:1672 : obj=0x7f6d7c16b920
>2012-05-16 09:29:16.073+0000: 23018: info : virDomainLoadAllConfigs:13070 : Loading config file 'qcow2_foo.xml'
>2012-05-16 09:29:16.073+0000: 23018: debug : virDomainObjNew:1672 : obj=0x7f6d7c15eee0
>2012-05-16 09:29:16.073+0000: 23018: info : virDomainLoadAllConfigs:13070 : Loading config file 'raw.xml'
>2012-05-16 09:29:16.074+0000: 23018: debug : virDomainObjNew:1672 : obj=0x7f6d7c15ee00
>2012-05-16 09:29:16.074+0000: 23018: info : virDomainLoadAllConfigs:13070 : Loading config file 'test.xml'
>2012-05-16 09:29:16.074+0000: 23018: debug : virDomainObjNew:1672 : obj=0x7f6d7c17e840
>2012-05-16 09:29:16.074+0000: 23018: info : virDomainLoadAllConfigs:13070 : Loading config file 'qcow2.xml'
>2012-05-16 09:29:16.075+0000: 23018: debug : virDomainObjNew:1672 : obj=0x7f6d7c17b730
>2012-05-16 09:29:16.075+0000: 23018: info : virDomainLoadAllConfigs:13070 : Loading config file 'test_foo.xml'
>2012-05-16 09:29:16.075+0000: 23018: debug : virDomainObjNew:1672 : obj=0x7f6d7c17a4c0
>2012-05-16 09:29:16.075+0000: 23018: info : qemuDomainSnapshotLoad:333 : Scanning for snapshots for domain rhel6 in /var/lib/libvirt/qemu/snapshot/rhel6
>2012-05-16 09:29:16.075+0000: 23018: info : qemuDomainSnapshotLoad:333 : Scanning for snapshots for domain win-test in /var/lib/libvirt/qemu/snapshot/win-test
>2012-05-16 09:29:16.076+0000: 23018: info : qemuDomainSnapshotLoad:333 : Scanning for snapshots for domain mig in /var/lib/libvirt/qemu/snapshot/mig
>2012-05-16 09:29:16.076+0000: 23018: info : qemuDomainSnapshotLoad:333 : Scanning for snapshots for domain test_foo in /var/lib/libvirt/qemu/snapshot/test_foo
>2012-05-16 09:29:16.076+0000: 23018: info : qemuDomainSnapshotLoad:333 : Scanning for snapshots for domain qcow2_foo in /var/lib/libvirt/qemu/snapshot/qcow2_foo
>2012-05-16 09:29:16.076+0000: 23018: info : qemuDomainSnapshotLoad:333 : Scanning for snapshots for domain qcow2 in /var/lib/libvirt/qemu/snapshot/qcow2
>2012-05-16 09:29:16.076+0000: 23018: info : qemuDomainSnapshotLoad:349 : Loading snapshot file '1337147878.xml'
>2012-05-16 09:29:16.076+0000: 23018: debug : virDomainSnapshotObjNew:13881 : obj=0x7f6d7c15ee90
>2012-05-16 09:29:16.076+0000: 23018: info : qemuDomainSnapshotLoad:333 : Scanning for snapshots for domain win7-qcow2 in /var/lib/libvirt/qemu/snapshot/win7-qcow2
>2012-05-16 09:29:16.076+0000: 23018: info : qemuDomainSnapshotLoad:333 : Scanning for snapshots for domain rhel5u6 in /var/lib/libvirt/qemu/snapshot/rhel5u6
>2012-05-16 09:29:16.076+0000: 23018: info : qemuDomainSnapshotLoad:333 : Scanning for snapshots for domain raw in /var/lib/libvirt/qemu/snapshot/raw
>2012-05-16 09:29:16.076+0000: 23018: info : qemuDomainSnapshotLoad:333 : Scanning for snapshots for domain test in /var/lib/libvirt/qemu/snapshot/test
>2012-05-16 09:29:16.076+0000: 23018: debug : virConnectOpen:1349 : name=qemu:///system
>2012-05-16 09:29:16.076+0000: 23018: debug : virConnectOpenResolveURIAlias:1070 : Loading config file '/etc/libvirt/libvirt.conf'
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1151 : name "qemu:///system" to URI components:
>  scheme qemu
>  opaque (null)
>  authority (null)
>  server (null)
>  user (null)
>  port 0
>  path /system
>
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1195 : trying driver 0 (Test) ...
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1201 : driver 0 Test returned DECLINED
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1195 : trying driver 1 (ESX) ...
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1201 : driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1195 : trying driver 2 (remote) ...
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1201 : driver 2 remote returned DECLINED
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1195 : trying driver 3 (QEMU) ...
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1201 : driver 3 QEMU returned SUCCESS
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1223 : network driver 0 Test returned DECLINED
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1223 : network driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1223 : network driver 2 remote returned DECLINED
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1223 : network driver 3 Network returned SUCCESS
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1238 : interface driver 0 Test returned DECLINED
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1238 : interface driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.076+0000: 23018: debug : do_open:1238 : interface driver 2 remote returned DECLINED
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1238 : interface driver 3 Interface returned SUCCESS
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1254 : storage driver 0 Test returned DECLINED
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1254 : storage driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1254 : storage driver 2 remote returned DECLINED
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1254 : storage driver 3 storage returned SUCCESS
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1270 : node driver 0 Test returned DECLINED
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1270 : node driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1270 : node driver 2 remote returned DECLINED
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1270 : node driver 3 udevDeviceMonitor returned SUCCESS
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1286 : secret driver 0 Test returned DECLINED
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1286 : secret driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1286 : secret driver 2 remote returned DECLINED
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1286 : secret driver 3 secret returned SUCCESS
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1302 : nwfilter driver 0 Test returned DECLINED
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1302 : nwfilter driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1302 : nwfilter driver 2 remote returned DECLINED
>2012-05-16 09:29:16.081+0000: 23018: debug : do_open:1302 : nwfilter driver 3 nwfilter returned SUCCESS
>2012-05-16 09:29:16.081+0000: 23018: debug : virConnectClose:1462 : conn=0x7f6d7c178a60
>2012-05-16 09:29:16.082+0000: 23018: debug : qemuDriverCloseCallbackRunAll:661 : conn=0x7f6d7c178a60
>2012-05-16 09:29:16.082+0000: 23018: debug : virConnectClose:1462 : conn=0x7f6d7c08ab10
>2012-05-16 09:29:16.082+0000: 23018: debug : qemuDriverCloseCallbackRunAll:661 : conn=0x7f6d7c08ab10
>2012-05-16 09:29:16.106+0000: 23018: debug : lxcCapsInit:144 : Initialized caps for security driver "selinux" with DOI "0"
>2012-05-16 09:29:16.106+0000: 23018: info : virDomainLoadAllConfigs:13048 : Scanning for configs in /var/run/libvirt/lxc
>2012-05-16 09:29:16.106+0000: 23018: info : virDomainLoadAllConfigs:13048 : Scanning for configs in /etc/libvirt/lxc
>2012-05-16 09:29:16.106+0000: 23018: debug : virConnectOpen:1349 : name=lxc:///
>2012-05-16 09:29:16.106+0000: 23018: debug : virConnectOpenResolveURIAlias:1070 : Loading config file '/etc/libvirt/libvirt.conf'
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1151 : name "lxc:///" to URI components:
>  scheme lxc
>  opaque (null)
>  authority (null)
>  server (null)
>  user (null)
>  port 0
>  path /
>
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1195 : trying driver 0 (Test) ...
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1201 : driver 0 Test returned DECLINED
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1195 : trying driver 1 (ESX) ...
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1201 : driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1195 : trying driver 2 (remote) ...
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1201 : driver 2 remote returned DECLINED
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1195 : trying driver 3 (QEMU) ...
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1201 : driver 3 QEMU returned DECLINED
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1195 : trying driver 4 (LXC) ...
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1201 : driver 4 LXC returned SUCCESS
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1223 : network driver 0 Test returned DECLINED
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1223 : network driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1223 : network driver 2 remote returned DECLINED
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1223 : network driver 3 Network returned SUCCESS
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1238 : interface driver 0 Test returned DECLINED
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1238 : interface driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.106+0000: 23018: debug : do_open:1238 : interface driver 2 remote returned DECLINED
>2012-05-16 09:29:16.111+0000: 23018: debug : do_open:1238 : interface driver 3 Interface returned SUCCESS
>2012-05-16 09:29:16.111+0000: 23018: debug : do_open:1254 : storage driver 0 Test returned DECLINED
>2012-05-16 09:29:16.111+0000: 23018: debug : do_open:1254 : storage driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.111+0000: 23018: debug : do_open:1254 : storage driver 2 remote returned DECLINED
>2012-05-16 09:29:16.111+0000: 23018: debug : do_open:1254 : storage driver 3 storage returned SUCCESS
>2012-05-16 09:29:16.111+0000: 23018: debug : do_open:1270 : node driver 0 Test returned DECLINED
>2012-05-16 09:29:16.111+0000: 23018: debug : do_open:1270 : node driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.112+0000: 23018: debug : do_open:1270 : node driver 2 remote returned DECLINED
>2012-05-16 09:29:16.112+0000: 23018: debug : do_open:1270 : node driver 3 udevDeviceMonitor returned SUCCESS
>2012-05-16 09:29:16.112+0000: 23018: debug : do_open:1286 : secret driver 0 Test returned DECLINED
>2012-05-16 09:29:16.112+0000: 23018: debug : do_open:1286 : secret driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.112+0000: 23018: debug : do_open:1286 : secret driver 2 remote returned DECLINED
>2012-05-16 09:29:16.112+0000: 23018: debug : do_open:1286 : secret driver 3 secret returned SUCCESS
>2012-05-16 09:29:16.112+0000: 23018: debug : do_open:1302 : nwfilter driver 0 Test returned DECLINED
>2012-05-16 09:29:16.112+0000: 23018: debug : do_open:1302 : nwfilter driver 1 ESX returned DECLINED
>2012-05-16 09:29:16.112+0000: 23018: debug : do_open:1302 : nwfilter driver 2 remote returned DECLINED
>2012-05-16 09:29:16.112+0000: 23018: debug : do_open:1302 : nwfilter driver 3 nwfilter returned SUCCESS
>2012-05-16 09:29:16.112+0000: 23018: debug : virConnectClose:1462 : conn=0x7f6d7c08ab10
>2012-05-16 09:29:21.435+0000: 23015: debug : virConnectOpen:1349 : name=
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1155 : no name, allowing driver auto-select
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1195 : trying driver 0 (Test) ...
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1201 : driver 0 Test returned DECLINED
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1195 : trying driver 1 (ESX) ...
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1201 : driver 1 ESX returned DECLINED
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1195 : trying driver 2 (remote) ...
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1201 : driver 2 remote returned DECLINED
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1195 : trying driver 3 (QEMU) ...
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1201 : driver 3 QEMU returned SUCCESS
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1223 : network driver 0 Test returned DECLINED
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1223 : network driver 1 ESX returned DECLINED
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1223 : network driver 2 remote returned DECLINED
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1223 : network driver 3 Network returned SUCCESS
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1238 : interface driver 0 Test returned DECLINED
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1238 : interface driver 1 ESX returned DECLINED
>2012-05-16 09:29:21.435+0000: 23015: debug : do_open:1238 : interface driver 2 remote returned DECLINED
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1238 : interface driver 3 Interface returned SUCCESS
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1254 : storage driver 0 Test returned DECLINED
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1254 : storage driver 1 ESX returned DECLINED
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1254 : storage driver 2 remote returned DECLINED
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1254 : storage driver 3 storage returned SUCCESS
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1270 : node driver 0 Test returned DECLINED
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1270 : node driver 1 ESX returned DECLINED
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1270 : node driver 2 remote returned DECLINED
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1270 : node driver 3 udevDeviceMonitor returned SUCCESS
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1286 : secret driver 0 Test returned DECLINED
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1286 : secret driver 1 ESX returned DECLINED
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1286 : secret driver 2 remote returned DECLINED
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1286 : secret driver 3 secret returned SUCCESS
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1302 : nwfilter driver 0 Test returned DECLINED
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1302 : nwfilter driver 1 ESX returned DECLINED
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1302 : nwfilter driver 2 remote returned DECLINED
>2012-05-16 09:29:21.442+0000: 23015: debug : do_open:1302 : nwfilter driver 3 nwfilter returned SUCCESS
>2012-05-16 09:29:21.443+0000: 23011: debug : virConnectGetURI:1722 : conn=0x7f6d78000bd0
>2012-05-16 09:29:21.444+0000: 23017: debug : virDomainLookupByName:2146 : conn=0x7f6d78000bd0, name=qcow2
>2012-05-16 09:29:21.444+0000: 23017: debug : virDomainFree:2313 : dom=0x7f6d70000a10, (VM: name=qcow2, uuid=5b9c7030-d0e3-2c4b-3ce3-77039e2bc3de)
>2012-05-16 09:29:21.444+0000: 23009: debug : virDomainGetXMLDesc:4379 : dom=0x7f6d7c150c90, (VM: name=qcow2, uuid=5b9c7030-d0e3-2c4b-3ce3-77039e2bc3de), flags=3
>2012-05-16 09:29:21.445+0000: 23009: debug : virDomainFree:2313 : dom=0x7f6d7c150c90, (VM: name=qcow2, uuid=5b9c7030-d0e3-2c4b-3ce3-77039e2bc3de)
>2012-05-16 09:29:45.847+0000: 23008: debug : virDomainGetXMLDesc:4379 : dom=0x7f6d740009a0, (VM: name=qcow2, uuid=5b9c7030-d0e3-2c4b-3ce3-77039e2bc3de), flags=3
>2012-05-16 09:29:45.847+0000: 23008: debug : virDomainFree:2313 : dom=0x7f6d740009a0, (VM: name=qcow2, uuid=5b9c7030-d0e3-2c4b-3ce3-77039e2bc3de)
>2012-05-16 09:29:45.847+0000: 23010: debug : virDomainDefineXML:7827 : conn=0x7f6d78000bd0, xml=<domain type='kvm'>
>  <name>qcow2</name>
>  <uuid>5b9c7030-d0e3-2c4b-3ce3-77039e2bc3de</uuid>
>  <memory unit='KiB'>1048576</memory>
>  <currentMemory unit='KiB'>1048576</currentMemory>
>  <vcpu placement='static'>2</vcpu>
>  <os>
>    <type arch='x86_64' machine='rhel6.3.0'>hvm</type>
>    <boot dev='hd'/>
>  </os>
>  <features>
>    <acpi/>
>    <apic/>
>    <pae/>
>  </features>
>  <clock offset='utc'/>
>  <on_poweroff>destroy</on_poweroff>
>  <on_reboot>restart</on_reboot>
>  <on_crash>restart</on_crash>
>  <devices>
>    <emulator>/usr/libexec/qemu-kvm</emulator>
>    <disk type='file' device='disk'>
>      <driver name='qemu' type='qcow2' cache='none'/>
>      <source file='/data/images/qcow2.img'/>
>      <target dev='hda' bus='ide'/>
>      <address type='drive' controller='0' bus='0' target='0' unit='0'/>
>    </disk>
>    <controller type='usb' index='0'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x2'/>
>    </controller>
>    <controller type='virtio-serial' index='0'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x07' function='0x0'/>
>    </controller>
>    <controller type='ide' index='0'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x1'/>
>    </controller>
>    <interface type='network'>
>      <mac address='52:54:00:30:7a:ac'/>
>      <source network='default'/>
>      <model type='virtio'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x03' function='0x0'/>
>    </interface>
>    <serial type='pty'>
>      <target port='0'/>
>    </serial>
>    <console type='pty'>
>      <target type='serial' port='0'/>
>    </console>
>    <input type='mouse' bus='ps2'/>
>    <graphics type='vnc' port='-1' autoport='yes'/>
>    <sound model='ich6'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x04' function='0x0'/>
>    </sound>
>    <video>
>      <model type='qxl' vram='8192' heads='1'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x02' function='0x0'/>
>    </video>
>    <hostdev mode='subsystem' type='usb' managed='no'>
>      <source>
>        <vendor id='0x0951'/>
>        <product id='0x1625'/>
>      </source>
>    </hostdev>
>    <memballoon model='virtio'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x0'/>
>    </memballoon>
>  </devices>
></domain>
>
>2012-05-16 09:29:45.859+0000: 23010: debug : qemuCapsParseHelpStr:1337 : Version 0.12.1, cooked version 12001, flags 0x00000000400e5c000109fc6cf7effd6e
>2012-05-16 09:29:45.859+0000: 23010: debug : qemuCapsParseHelpStr:1343 : qemu-kvm version 0.12.1 detected
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:3.0
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.1 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.2 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.3 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.4 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.5 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.6 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.7 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:4.0
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.1 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.2 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.3 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.4 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.5 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.6 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.7 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:2.0
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.1 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.2 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.3 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.4 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.5 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.6 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.7 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:1.2
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:7.0
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.1 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.2 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.3 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.4 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.5 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.6 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.7 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:1.1
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:6.0
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.1 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.2 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.3 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.4 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.5 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.6 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.7 (multifunction=off for function 0)
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.0
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.1
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.2
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.3
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.4
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.5
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.6
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.7
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.0
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.3
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.4
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.5
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.6
>2012-05-16 09:29:45.951+0000: 23010: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.7
>2012-05-16 09:29:45.988+0000: 23010: info : qemudDomainDefine:5012 : Creating domain 'qcow2'
>2012-05-16 09:29:45.988+0000: 23010: debug : virDomainFree:2313 : dom=0x7f6d64001900, (VM: name=qcow2, uuid=5b9c7030-d0e3-2c4b-3ce3-77039e2bc3de)
>2012-05-16 09:29:45.990+0000: 23007: debug : virConnectClose:1462 : conn=0x7f6d78000bd0
>2012-05-16 09:29:45.991+0000: 23007: debug : qemuDriverCloseCallbackRunAll:661 : conn=0x7f6d78000bd0
>2012-05-16 09:29:48.458+0000: 23008: debug : virConnectOpen:1349 : name=
>2012-05-16 09:29:48.458+0000: 23008: debug : do_open:1155 : no name, allowing driver auto-select
>2012-05-16 09:29:48.458+0000: 23008: debug : do_open:1195 : trying driver 0 (Test) ...
>2012-05-16 09:29:48.458+0000: 23008: debug : do_open:1201 : driver 0 Test returned DECLINED
>2012-05-16 09:29:48.458+0000: 23008: debug : do_open:1195 : trying driver 1 (ESX) ...
>2012-05-16 09:29:48.458+0000: 23008: debug : do_open:1201 : driver 1 ESX returned DECLINED
>2012-05-16 09:29:48.458+0000: 23008: debug : do_open:1195 : trying driver 2 (remote) ...
>2012-05-16 09:29:48.458+0000: 23008: debug : do_open:1201 : driver 2 remote returned DECLINED
>2012-05-16 09:29:48.458+0000: 23008: debug : do_open:1195 : trying driver 3 (QEMU) ...
>2012-05-16 09:29:48.458+0000: 23008: debug : do_open:1201 : driver 3 QEMU returned SUCCESS
>2012-05-16 09:29:48.458+0000: 23008: debug : do_open:1223 : network driver 0 Test returned DECLINED
>2012-05-16 09:29:48.458+0000: 23008: debug : do_open:1223 : network driver 1 ESX returned DECLINED
>2012-05-16 09:29:48.458+0000: 23008: debug : do_open:1223 : network driver 2 remote returned DECLINED
>2012-05-16 09:29:48.458+0000: 23008: debug : do_open:1223 : network driver 3 Network returned SUCCESS
>2012-05-16 09:29:48.459+0000: 23008: debug : do_open:1238 : interface driver 0 Test returned DECLINED
>2012-05-16 09:29:48.459+0000: 23008: debug : do_open:1238 : interface driver 1 ESX returned DECLINED
>2012-05-16 09:29:48.459+0000: 23008: debug : do_open:1238 : interface driver 2 remote returned DECLINED
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1238 : interface driver 3 Interface returned SUCCESS
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1254 : storage driver 0 Test returned DECLINED
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1254 : storage driver 1 ESX returned DECLINED
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1254 : storage driver 2 remote returned DECLINED
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1254 : storage driver 3 storage returned SUCCESS
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1270 : node driver 0 Test returned DECLINED
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1270 : node driver 1 ESX returned DECLINED
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1270 : node driver 2 remote returned DECLINED
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1270 : node driver 3 udevDeviceMonitor returned SUCCESS
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1286 : secret driver 0 Test returned DECLINED
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1286 : secret driver 1 ESX returned DECLINED
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1286 : secret driver 2 remote returned DECLINED
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1286 : secret driver 3 secret returned SUCCESS
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1302 : nwfilter driver 0 Test returned DECLINED
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1302 : nwfilter driver 1 ESX returned DECLINED
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1302 : nwfilter driver 2 remote returned DECLINED
>2012-05-16 09:29:48.465+0000: 23008: debug : do_open:1302 : nwfilter driver 3 nwfilter returned SUCCESS
>2012-05-16 09:29:48.466+0000: 23010: debug : virConnectGetURI:1722 : conn=0x7f6d74000bc0
>2012-05-16 09:29:48.467+0000: 23011: debug : virDomainLookupByName:2146 : conn=0x7f6d74000bc0, name=qcow2_foo
>2012-05-16 09:29:48.467+0000: 23011: debug : virDomainFree:2313 : dom=0x7f6d6c0008c0, (VM: name=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095)
>2012-05-16 09:29:48.467+0000: 23012: debug : virDomainGetXMLDesc:4379 : dom=0x7f6d68000930, (VM: name=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095), flags=3
>2012-05-16 09:29:48.467+0000: 23012: debug : virDomainFree:2313 : dom=0x7f6d68000930, (VM: name=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095)
>2012-05-16 09:29:54.356+0000: 23009: debug : virDomainGetXMLDesc:4379 : dom=0x7f6d7c1782e0, (VM: name=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095), flags=3
>2012-05-16 09:29:54.356+0000: 23009: debug : virDomainFree:2313 : dom=0x7f6d7c1782e0, (VM: name=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095)
>2012-05-16 09:29:54.357+0000: 23008: debug : virDomainDefineXML:7827 : conn=0x7f6d74000bc0, xml=<domain type='kvm'>
>  <name>qcow2_foo</name>
>  <uuid>8ac888e7-7288-bce9-0ef1-ce639d51c095</uuid>
>  <memory unit='KiB'>1048576</memory>
>  <currentMemory unit='KiB'>1048576</currentMemory>
>  <vcpu placement='static'>2</vcpu>
>  <os>
>    <type arch='x86_64' machine='rhel6.3.0'>hvm</type>
>    <boot dev='hd'/>
>  </os>
>  <features>
>    <acpi/>
>    <apic/>
>    <pae/>
>  </features>
>  <clock offset='utc'/>
>  <on_poweroff>destroy</on_poweroff>
>  <on_reboot>restart</on_reboot>
>  <on_crash>restart</on_crash>
>  <devices>
>    <emulator>/usr/libexec/qemu-kvm</emulator>
>    <disk type='file' device='disk'>
>      <driver name='qemu' type='qcow2' cache='none'/>
>      <source file='/data/images/qcow2_foo.img'/>
>      <target dev='hda' bus='ide'/>
>      <address type='drive' controller='0' bus='0' target='0' unit='0'/>
>    </disk>
>    <disk type='file' device='disk'>
>      <driver name='qemu' type='raw'/>
>      <source file='/var/lib/libvirt/images/share.img'/>
>      <target dev='hdb' bus='ide'/>
>      <shareable/>
>      <address type='drive' controller='0' bus='0' target='0' unit='1'/>
>    </disk>
>    <controller type='usb' index='0'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x2'/>
>    </controller>
>    <controller type='virtio-serial' index='0'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x07' function='0x0'/>
>    </controller>
>    <controller type='ide' index='0'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x1'/>
>    </controller>
>    <interface type='network'>
>      <mac address='52:54:00:30:7a:ac'/>
>      <source network='default'/>
>      <model type='virtio'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x03' function='0x0'/>
>    </interface>
>    <serial type='pty'>
>      <target port='0'/>
>    </serial>
>    <console type='pty'>
>      <target type='serial' port='0'/>
>    </console>
>    <channel type='spicevmc'>
>      <target type='virtio' name='com.redhat.spice.0'/>
>      <address type='virtio-serial' controller='0' bus='0' port='1'/>
>    </channel>
>    <input type='tablet' bus='usb'/>
>    <input type='mouse' bus='ps2'/>
>    <graphics type='spice' autoport='yes' listen='127.0.0.1'>
>      <listen type='address' address='127.0.0.1'/>
>    </graphics>
>    <sound model='ich6'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x04' function='0x0'/>
>    </sound>
>    <video>
>      <model type='cirrus' vram='9216' heads='1'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x02' function='0x0'/>
>    </video>
>    <hostdev mode='subsystem' type='usb' managed='no'>
>      <source>
>        <vendor id='0x0951'/>
>        <product id='0x1625'/>
>      </source>
>    </hostdev>
>    <memballoon model='virtio'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x0'/>
>    </memballoon>
>  </devices>
></domain>
>
>2012-05-16 09:29:54.371+0000: 23008: debug : qemuCapsParseHelpStr:1337 : Version 0.12.1, cooked version 12001, flags 0x00000000400e5c000109fc6cf7effd6e
>2012-05-16 09:29:54.371+0000: 23008: debug : qemuCapsParseHelpStr:1343 : qemu-kvm version 0.12.1 detected
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:3.0
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.1 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.2 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.3 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.4 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.5 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.6 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.7 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:4.0
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.1 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.2 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.3 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.4 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.5 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.6 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.7 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:2.0
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.1 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.2 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.3 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.4 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.5 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.6 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.7 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:1.2
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:7.0
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.1 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.2 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.3 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.4 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.5 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.6 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.7 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:1.1
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:6.0
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.1 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.2 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.3 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.4 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.5 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.6 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.7 (multifunction=off for function 0)
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.0
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.1
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.2
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.3
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.4
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.5
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.6
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.7
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.0
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.3
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.4
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.5
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.6
>2012-05-16 09:29:54.464+0000: 23008: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.7
>2012-05-16 09:29:54.498+0000: 23008: info : qemudDomainDefine:5012 : Creating domain 'qcow2_foo'
>2012-05-16 09:29:54.498+0000: 23008: debug : virDomainFree:2313 : dom=0x7f6d740c05b0, (VM: name=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095)
>2012-05-16 09:29:54.499+0000: 23007: debug : virConnectClose:1462 : conn=0x7f6d74000bc0
>2012-05-16 09:29:54.500+0000: 23007: debug : qemuDriverCloseCallbackRunAll:661 : conn=0x7f6d74000bc0
>2012-05-16 09:29:59.552+0000: 23009: debug : virConnectOpen:1349 : name=
>2012-05-16 09:29:59.552+0000: 23009: debug : do_open:1155 : no name, allowing driver auto-select
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1195 : trying driver 0 (Test) ...
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1201 : driver 0 Test returned DECLINED
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1195 : trying driver 1 (ESX) ...
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1201 : driver 1 ESX returned DECLINED
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1195 : trying driver 2 (remote) ...
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1201 : driver 2 remote returned DECLINED
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1195 : trying driver 3 (QEMU) ...
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1201 : driver 3 QEMU returned SUCCESS
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1223 : network driver 0 Test returned DECLINED
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1223 : network driver 1 ESX returned DECLINED
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1223 : network driver 2 remote returned DECLINED
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1223 : network driver 3 Network returned SUCCESS
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1238 : interface driver 0 Test returned DECLINED
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1238 : interface driver 1 ESX returned DECLINED
>2012-05-16 09:29:59.553+0000: 23009: debug : do_open:1238 : interface driver 2 remote returned DECLINED
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1238 : interface driver 3 Interface returned SUCCESS
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1254 : storage driver 0 Test returned DECLINED
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1254 : storage driver 1 ESX returned DECLINED
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1254 : storage driver 2 remote returned DECLINED
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1254 : storage driver 3 storage returned SUCCESS
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1270 : node driver 0 Test returned DECLINED
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1270 : node driver 1 ESX returned DECLINED
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1270 : node driver 2 remote returned DECLINED
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1270 : node driver 3 udevDeviceMonitor returned SUCCESS
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1286 : secret driver 0 Test returned DECLINED
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1286 : secret driver 1 ESX returned DECLINED
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1286 : secret driver 2 remote returned DECLINED
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1286 : secret driver 3 secret returned SUCCESS
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1302 : nwfilter driver 0 Test returned DECLINED
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1302 : nwfilter driver 1 ESX returned DECLINED
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1302 : nwfilter driver 2 remote returned DECLINED
>2012-05-16 09:29:59.558+0000: 23009: debug : do_open:1302 : nwfilter driver 3 nwfilter returned SUCCESS
>2012-05-16 09:29:59.558+0000: 23008: debug : virConnectGetURI:1722 : conn=0x7f6d7c17b3b0
>2012-05-16 09:29:59.559+0000: 23016: debug : virDomainLookupByName:2146 : conn=0x7f6d7c17b3b0, name=qcow2
>2012-05-16 09:29:59.559+0000: 23016: debug : virDomainFree:2313 : dom=0x7f6d5c000a10, (VM: name=qcow2, uuid=5b9c7030-d0e3-2c4b-3ce3-77039e2bc3de)
>2012-05-16 09:29:59.560+0000: 23011: debug : virDomainCreate:8065 : dom=0x7f6d6c0008c0, (VM: name=qcow2, uuid=5b9c7030-d0e3-2c4b-3ce3-77039e2bc3de)
>2012-05-16 09:29:59.560+0000: 23011: debug : virDomainObjRef:1623 : obj=0x7f6d7c17b730 refs=2
>2012-05-16 09:29:59.560+0000: 23011: debug : qemuDomainObjBeginJobInternal:753 : Starting job: modify (async=none)
>2012-05-16 09:29:59.560+0000: 23011: debug : qemuProcessStart:3202 : Beginning VM startup process
>2012-05-16 09:29:59.560+0000: 23011: debug : qemuProcessStart:3214 : Setting current domain def as transient
>2012-05-16 09:29:59.561+0000: 23011: debug : qemuProcessStart:3240 : Preparing host devices
>2012-05-16 09:29:59.562+0000: 23011: debug : qemuPrepareHostdevUSBDevices:593 : Adding 002.003 dom=qcow2 to activeUsbHostdevs
>2012-05-16 09:29:59.562+0000: 23011: debug : qemuProcessStart:3244 : Preparing chr devices
>2012-05-16 09:29:59.562+0000: 23011: debug : qemuProcessStart:3253 : Generating domain security label (if required)
>2012-05-16 09:29:59.564+0000: 23011: debug : qemuProcessStart:3262 : Ensuring no historical cgroup is lying around
>2012-05-16 09:29:59.565+0000: 23011: debug : qemuProcessStart:3315 : Creating domain log file
>2012-05-16 09:29:59.565+0000: 23011: debug : qemuProcessStart:3320 : Checking for KVM availability
>2012-05-16 09:29:59.565+0000: 23011: debug : qemuProcessStart:3330 : Determining emulator version
>2012-05-16 09:29:59.578+0000: 23011: debug : qemuCapsParseHelpStr:1337 : Version 0.12.1, cooked version 12001, flags 0x00000000400e5c000109fc6cf7effd6e
>2012-05-16 09:29:59.578+0000: 23011: debug : qemuCapsParseHelpStr:1343 : qemu-kvm version 0.12.1 detected
>2012-05-16 09:29:59.673+0000: 23011: debug : qemuProcessStart:3341 : Checking for CDROM and floppy presence
>2012-05-16 09:29:59.673+0000: 23011: debug : qemuProcessStart:3369 : Setting up domain cgroup (if required)
>2012-05-16 09:29:59.674+0000: 23011: debug : qemuSetupDiskPathAllow:70 : Process path /data/images/qcow2.img for disk
>2012-05-16 09:29:59.676+0000: 23011: debug : qemuSetupHostUsbDeviceCgroup:182 : Process path '/dev/bus/usb/002/003' for USB device
>2012-05-16 09:29:59.676+0000: 23011: debug : qemuProcessStart:3378 : Preparing monitor state
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuProcessStart:3414 : Assigning domain PCI addresses
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:3.0
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.1 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.2 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.3 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.4 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.5 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.6 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.7 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:4.0
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.1 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.2 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.3 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.4 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.5 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.6 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.7 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:2.0
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.1 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.2 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.3 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.4 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.5 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.6 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.7 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:1.2
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:7.0
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.1 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.2 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.3 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.4 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.5 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.6 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.7 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:1.1
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:6.0
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.1 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.2 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.3 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.4 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.5 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.6 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.7 (multifunction=off for function 0)
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.0
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.1
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.2
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.3
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.4
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.5
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.6
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.7
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.0
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.3
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.4
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.5
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.6
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.7
>2012-05-16 09:29:59.677+0000: 23011: debug : qemuProcessStart:3433 : Building emulator command line
>2012-05-16 09:29:59.677+0000: 23011: debug : virNetworkLookupByName:9725 : conn=0x7f6d7c17b3b0, name=default
>2012-05-16 09:29:59.677+0000: 23011: debug : virNetworkIsActive:15545 : net=0x7f6d6c003210
>2012-05-16 09:29:59.677+0000: 23011: debug : virNetworkGetBridgeName:10276 : network=0x7f6d6c003210
>2012-05-16 09:29:59.677+0000: 23011: debug : virNetworkFree:10069 : network=0x7f6d6c003210
>2012-05-16 09:29:59.690+0000: 23011: debug : qemuProcessStart:3478 : Clear emulator capabilities: 1
>2012-05-16 09:29:59.696+0000: 23011: debug : qemuProcessStart:3525 : Writing early domain status to disk
>2012-05-16 09:29:59.748+0000: 23011: debug : qemuProcessStart:3530 : Waiting for handshake from child
>2012-05-16 09:29:59.749+0000: 23011: debug : qemuProcessStart:3535 : Setting domain security labels
>2012-05-16 09:29:59.750+0000: 23011: debug : qemuProcessStart:3558 : Labelling done, completing handshake to child
>2012-05-16 09:29:59.750+0000: 23011: debug : qemuProcessStart:3562 : Handshake complete, child running
>2012-05-16 09:29:59.750+0000: 23011: debug : qemuProcessStart:3573 : Waiting for monitor to show up
>2012-05-16 09:29:59.950+0000: 23011: debug : qemuProcessWaitForMonitor:1479 : Connect monitor to 0x7f6d7c17b730 'qcow2'
>2012-05-16 09:29:59.955+0000: 23011: debug : virDomainObjRef:1623 : obj=0x7f6d7c17b730 refs=3
>2012-05-16 09:29:59.961+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=2
>2012-05-16 09:29:59.961+0000: 23011: debug : qemuMonitorOpen:734 : QEMU_MONITOR_NEW: mon=0x7f6d6c004060 refs=2 fd=22
>2012-05-16 09:29:59.961+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:29:59.961+0000: 23011: debug : qemuMonitorSetCapabilities:1017 : mon=0x7f6d6c004060
>2012-05-16 09:29:59.961+0000: 23011: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"qmp_capabilities","id":"libvirt-1"}' for write with FD -1
>2012-05-16 09:29:59.961+0000: 23011: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f6d6c004060 msg={"execute":"qmp_capabilities","id":"libvirt-1"}
> fd=-1
>2012-05-16 09:29:59.961+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:29:59.961+0000: 23007: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f6d6c004060 buf={"execute":"qmp_capabilities","id":"libvirt-1"}
> len=49 ret=49 errno=2
>2012-05-16 09:29:59.961+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.180+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:30:00.180+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c004060 buf={"QMP": {"version": {"qemu": {"micro": 1, "minor": 12, "major": 0}, "package": "(qemu-kvm-0.12.1.2)"}, "capabilities": []}}
> len=125
>2012-05-16 09:30:00.180+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"QMP": {"version": {"qemu": {"micro": 1, "minor": 12, "major": 0}, "package": "(qemu-kvm-0.12.1.2)"}, "capabilities": []}}]
>2012-05-16 09:30:00.180+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 125 bytes out of 125 available in buffer
>2012-05-16 09:30:00.180+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.181+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:30:00.181+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c004060 buf={"return": {}, "id": "libvirt-1"}
> len=35
>2012-05-16 09:30:00.181+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": {}, "id": "libvirt-1"}]
>2012-05-16 09:30:00.181+0000: 23007: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6d6c004060 reply={"return": {}, "id": "libvirt-1"}
>2012-05-16 09:30:00.181+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 35 bytes out of 35 available in buffer
>2012-05-16 09:30:00.181+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.181+0000: 23011: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x210a550
>2012-05-16 09:30:00.181+0000: 23011: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"query-commands","id":"libvirt-2"}' for write with FD -1
>2012-05-16 09:30:00.181+0000: 23011: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f6d6c004060 msg={"execute":"query-commands","id":"libvirt-2"}
> fd=-1
>2012-05-16 09:30:00.181+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:30:00.181+0000: 23007: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f6d6c004060 buf={"execute":"query-commands","id":"libvirt-2"}
> len=47 ret=47 errno=11
>2012-05-16 09:30:00.181+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.184+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:30:00.184+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c004060 buf={"return": [{"name": "quit"}, {"name": "eject"}, {"name": "__com.redhat_drive_del"}, {"name": "change"}, {"name": "screendump"}, {"name": "__com.redhat_qxl_screendump"}, {"name": "stop"}, {"name": "cont"}, {"name": "system_wakeup"}, {"name": "system_reset"}, {"name": "system_powerdown"}, {"name": "device_add"}, {"name": "device_del"}, {"name": "cpu"}, {"name": "memsave"}, {"name": "pmemsave"}, {"name": "inject-nmi"}, {"name": "migrate"}, {"name": "migrate_cancel"}, {"name": "migrate_set_speed"}, {"name": "client_migrate_info"}, {"name": "migrate_set_downtime"}, {"name": "block_resize"}, {"name": "netdev_add"}, {"name": "netdev_del"}, {"name": "transaction"}, {"name": "blockdev-snapshot-sync"}, {"name": "__com.redhat_drive-mirror"}, {"name": "__com.redhat_drive-reopen"}, {"name": "balloon"}, {"name": "set_link"}, {"name": "getfd"}, {"name": "closefd"}, {"name": "block_passwd"}, {"name": "set_password"}, {"name": "expire_password"}, {"name": "__com.redhat_set_password"}, {"name": "__com.redhat_spice_migrate_in len=1023
>2012-05-16 09:30:00.184+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 0 bytes out of 1023 available in buffer
>2012-05-16 09:30:00.184+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.184+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:30:00.184+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c004060 buf={"return": [{"name": "quit"}, {"name": "eject"}, {"name": "__com.redhat_drive_del"}, {"name": "change"}, {"name": "screendump"}, {"name": "__com.redhat_qxl_screendump"}, {"name": "stop"}, {"name": "cont"}, {"name": "system_wakeup"}, {"name": "system_reset"}, {"name": "system_powerdown"}, {"name": "device_add"}, {"name": "device_del"}, {"name": "cpu"}, {"name": "memsave"}, {"name": "pmemsave"}, {"name": "inject-nmi"}, {"name": "migrate"}, {"name": "migrate_cancel"}, {"name": "migrate_set_speed"}, {"name": "client_migrate_info"}, {"name": "migrate_set_downtime"}, {"name": "block_resize"}, {"name": "netdev_add"}, {"name": "netdev_del"}, {"name": "transaction"}, {"name": "blockdev-snapshot-sync"}, {"name": "__com.redhat_drive-mirror"}, {"name": "__com.redhat_drive-reopen"}, {"name": "balloon"}, {"name": "set_link"}, {"name": "getfd"}, {"name": "closefd"}, {"name": "block_passwd"}, {"name": "set_password"}, {"name": "expire_password"}, {"name": "__com.redhat_set_password"}, {"name": "__com.redhat_spice_migrate_info"}, {"name": "qmp_capabilities"}, {"name": "human-monitor-command"}, {"name": "__com.redhat_drive_add"}, {"name": "block-stream"}, {"name": "block-job-set-speed"}, {"name": "block-job-cancel"}, {"name": "query-version"}, {"name": "query-commands"}, {"name": "query-chardev"}, {"name": "query-block"}, {"name": "query-blockstats"}, {"name": "query-cpus"}, {"name": "query-kvm"}, {"name": "query-status"}, {"name": "query-mice"}, {"name": "query-vnc"}, {"name": "query-spice"}, {"name": "query-name"}, {"name": "query-uuid"}, {"name": "query-migrate"}, {"name": "query-balloon"}, {"name": "query-block-jobs"}], "id": "libvirt-2"}
> len=1654
>2012-05-16 09:30:00.184+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": [{"name": "quit"}, {"name": "eject"}, {"name": "__com.redhat_drive_del"}, {"name": "change"}, {"name": "screendump"}, {"name": "__com.redhat_qxl_screendump"}, {"name": "stop"}, {"name": "cont"}, {"name": "system_wakeup"}, {"name": "system_reset"}, {"name": "system_powerdown"}, {"name": "device_add"}, {"name": "device_del"}, {"name": "cpu"}, {"name": "memsave"}, {"name": "pmemsave"}, {"name": "inject-nmi"}, {"name": "migrate"}, {"name": "migrate_cancel"}, {"name": "migrate_set_speed"}, {"name": "client_migrate_info"}, {"name": "migrate_set_downtime"}, {"name": "block_resize"}, {"name": "netdev_add"}, {"name": "netdev_del"}, {"name": "transaction"}, {"name": "blockdev-snapshot-sync"}, {"name": "__com.redhat_drive-mirror"}, {"name": "__com.redhat_drive-reopen"}, {"name": "balloon"}, {"name": "set_link"}, {"name": "getfd"}, {"name": "closefd"}, {"name": "block_passwd"}, {"name": "set_password"}, {"name": "expire_password"}, {"name": "__com.redhat_set_password"}, {"name": "__com.redhat_spice_migrate_info"}, {"name": "qmp_capabilities"}, {"name": "human-monitor-command"}, {"name": "__com.redhat_drive_add"}, {"name": "block-stream"}, {"name": "block-job-set-speed"}, {"name": "block-job-cancel"}, {"name": "query-version"}, {"name": "query-commands"}, {"name": "query-chardev"}, {"name": "query-block"}, {"name": "query-blockstats"}, {"name": "query-cpus"}, {"name": "query-kvm"}, {"name": "query-status"}, {"name": "query-mice"}, {"name": "query-vnc"}, {"name": "query-spice"}, {"name": "query-name"}, {"name": "query-uuid"}, {"name": "query-migrate"}, {"name": "query-balloon"}, {"name": "query-block-jobs"}], "id": "libvirt-2"}]
>2012-05-16 09:30:00.185+0000: 23007: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6d6c004060 reply={"return": [{"name": "quit"}, {"name": "eject"}, {"name": "__com.redhat_drive_del"}, {"name": "change"}, {"name": "screendump"}, {"name": "__com.redhat_qxl_screendump"}, {"name": "stop"}, {"name": "cont"}, {"name": "system_wakeup"}, {"name": "system_reset"}, {"name": "system_powerdown"}, {"name": "device_add"}, {"name": "device_del"}, {"name": "cpu"}, {"name": "memsave"}, {"name": "pmemsave"}, {"name": "inject-nmi"}, {"name": "migrate"}, {"name": "migrate_cancel"}, {"name": "migrate_set_speed"}, {"name": "client_migrate_info"}, {"name": "migrate_set_downtime"}, {"name": "block_resize"}, {"name": "netdev_add"}, {"name": "netdev_del"}, {"name": "transaction"}, {"name": "blockdev-snapshot-sync"}, {"name": "__com.redhat_drive-mirror"}, {"name": "__com.redhat_drive-reopen"}, {"name": "balloon"}, {"name": "set_link"}, {"name": "getfd"}, {"name": "closefd"}, {"name": "block_passwd"}, {"name": "set_password"}, {"name": "expire_password"}, {"name": "__com.redhat_set_password"}, {"name": "__com.redhat_spice_migrate_info"}, {"name": "qmp_capabilities"}, {"name": "human-monitor-command"}, {"name": "__com.redhat_drive_add"}, {"name": "block-stream"}, {"name": "block-job-set-speed"}, {"name": "block-job-cancel"}, {"name": "query-version"}, {"name": "query-commands"}, {"name": "query-chardev"}, {"name": "query-block"}, {"name": "query-blockstats"}, {"name": "query-cpus"}, {"name": "query-kvm"}, {"name": "query-status"}, {"name": "query-mice"}, {"name": "query-vnc"}, {"name": "query-spice"}, {"name": "query-name"}, {"name": "query-uuid"}, {"name": "query-migrate"}, {"name": "query-balloon"}, {"name": "query-block-jobs"}], "id": "libvirt-2"}
>2012-05-16 09:30:00.185+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 1654 bytes out of 1654 available in buffer
>2012-05-16 09:30:00.185+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.185+0000: 23011: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x210aba0
>2012-05-16 09:30:00.185+0000: 23011: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=2
>2012-05-16 09:30:00.185+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.185+0000: 23011: debug : qemuMonitorGetPtyPaths:2354 : mon=0x7f6d6c004060
>2012-05-16 09:30:00.185+0000: 23011: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"query-chardev","id":"libvirt-3"}' for write with FD -1
>2012-05-16 09:30:00.185+0000: 23011: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f6d6c004060 msg={"execute":"query-chardev","id":"libvirt-3"}
> fd=-1
>2012-05-16 09:30:00.185+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:30:00.185+0000: 23007: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f6d6c004060 buf={"execute":"query-chardev","id":"libvirt-3"}
> len=46 ret=46 errno=11
>2012-05-16 09:30:00.185+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.186+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:30:00.186+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c004060 buf={"return": [{"filename": "unix:/var/lib/libvirt/qemu/qcow2.monitor,server", "label": "charmonitor"}, {"filename": "pty:/dev/pts/1", "label": "charserial0"}], "id": "libvirt-3"}
> len=178
>2012-05-16 09:30:00.186+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": [{"filename": "unix:/var/lib/libvirt/qemu/qcow2.monitor,server", "label": "charmonitor"}, {"filename": "pty:/dev/pts/1", "label": "charserial0"}], "id": "libvirt-3"}]
>2012-05-16 09:30:00.186+0000: 23007: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6d6c004060 reply={"return": [{"filename": "unix:/var/lib/libvirt/qemu/qcow2.monitor,server", "label": "charmonitor"}, {"filename": "pty:/dev/pts/1", "label": "charserial0"}], "id": "libvirt-3"}
>2012-05-16 09:30:00.186+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 178 bytes out of 178 available in buffer
>2012-05-16 09:30:00.186+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.186+0000: 23011: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x2111130
>2012-05-16 09:30:00.186+0000: 23011: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=2
>2012-05-16 09:30:00.186+0000: 23011: debug : qemuProcessWaitForMonitor:1497 : qemuMonitorGetPtyPaths returned 0
>2012-05-16 09:30:00.186+0000: 23011: debug : qemuProcessStart:3585 : Detecting VCPU PIDs
>2012-05-16 09:30:00.186+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.186+0000: 23011: debug : qemuMonitorGetCPUInfo:1161 : mon=0x7f6d6c004060
>2012-05-16 09:30:00.186+0000: 23011: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"query-cpus","id":"libvirt-4"}' for write with FD -1
>2012-05-16 09:30:00.186+0000: 23011: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f6d6c004060 msg={"execute":"query-cpus","id":"libvirt-4"}
> fd=-1
>2012-05-16 09:30:00.186+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:30:00.187+0000: 23007: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f6d6c004060 buf={"execute":"query-cpus","id":"libvirt-4"}
> len=43 ret=43 errno=11
>2012-05-16 09:30:00.187+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.187+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:30:00.187+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c004060 buf={"return": [{"current": true, "CPU": 0, "pc": 4294967280, "halted": false, "thread_id": 23169}, {"current": false, "CPU": 1, "pc": 4294967280, "halted": true, "thread_id": 23170}], "id": "libvirt-4"}
> len=201
>2012-05-16 09:30:00.187+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": [{"current": true, "CPU": 0, "pc": 4294967280, "halted": false, "thread_id": 23169}, {"current": false, "CPU": 1, "pc": 4294967280, "halted": true, "thread_id": 23170}], "id": "libvirt-4"}]
>2012-05-16 09:30:00.187+0000: 23007: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6d6c004060 reply={"return": [{"current": true, "CPU": 0, "pc": 4294967280, "halted": false, "thread_id": 23169}, {"current": false, "CPU": 1, "pc": 4294967280, "halted": true, "thread_id": 23170}], "id": "libvirt-4"}
>2012-05-16 09:30:00.187+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 201 bytes out of 201 available in buffer
>2012-05-16 09:30:00.187+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.187+0000: 23011: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x2111aa0
>2012-05-16 09:30:00.187+0000: 23011: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=2
>2012-05-16 09:30:00.187+0000: 23011: debug : qemuProcessStart:3589 : Setting cgroup for each VCPU(if required)
>2012-05-16 09:30:00.202+0000: 23011: debug : qemuProcessStart:3593 : Setting VCPU affinities
>2012-05-16 09:30:00.202+0000: 23011: debug : virNodeGetInfo:6398 : conn=0x7f6d7c17b3b0, info=0x7f6d87437820
>2012-05-16 09:30:00.203+0000: 23011: debug : qemuProcessStart:3597 : Setting any required VM passwords
>2012-05-16 09:30:00.203+0000: 23011: debug : qemuProcessStart:3612 : Setting network link states
>2012-05-16 09:30:00.203+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.203+0000: 23011: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=2
>2012-05-16 09:30:00.203+0000: 23011: debug : qemuProcessStart:3625 : Setting initial memory amount
>2012-05-16 09:30:00.203+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.203+0000: 23011: debug : qemuMonitorSetBalloon:1564 : mon=0x7f6d6c004060 newmem=1048576
>2012-05-16 09:30:00.203+0000: 23011: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"balloon","arguments":{"value":1073741824},"id":"libvirt-5"}' for write with FD -1
>2012-05-16 09:30:00.203+0000: 23011: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f6d6c004060 msg={"execute":"balloon","arguments":{"value":1073741824},"id":"libvirt-5"}
> fd=-1
>2012-05-16 09:30:00.203+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:30:00.203+0000: 23007: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f6d6c004060 buf={"execute":"balloon","arguments":{"value":1073741824},"id":"libvirt-5"}
> len=73 ret=73 errno=11
>2012-05-16 09:30:00.203+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.204+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:30:00.204+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c004060 buf={"return": {}, "id": "libvirt-5"}
> len=35
>2012-05-16 09:30:00.204+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": {}, "id": "libvirt-5"}]
>2012-05-16 09:30:00.204+0000: 23007: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6d6c004060 reply={"return": {}, "id": "libvirt-5"}
>2012-05-16 09:30:00.204+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 35 bytes out of 35 available in buffer
>2012-05-16 09:30:00.204+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.204+0000: 23011: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x2104500
>2012-05-16 09:30:00.204+0000: 23011: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=2
>2012-05-16 09:30:00.204+0000: 23011: debug : qemuProcessStart:3641 : Starting domain CPUs
>2012-05-16 09:30:00.204+0000: 23011: debug : qemuProcessStartCPUs:2554 : Using lock state '(null)'
>2012-05-16 09:30:00.204+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.204+0000: 23011: debug : qemuMonitorStartCPUs:1061 : mon=0x7f6d6c004060
>2012-05-16 09:30:00.204+0000: 23011: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"cont","id":"libvirt-6"}' for write with FD -1
>2012-05-16 09:30:00.204+0000: 23011: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f6d6c004060 msg={"execute":"cont","id":"libvirt-6"}
> fd=-1
>2012-05-16 09:30:00.204+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:30:00.204+0000: 23007: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f6d6c004060 buf={"execute":"cont","id":"libvirt-6"}
> len=37 ret=37 errno=11
>2012-05-16 09:30:00.204+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.205+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c004060 refs=4
>2012-05-16 09:30:00.205+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c004060 buf={"timestamp": {"seconds": 1337160600, "microseconds": 205207}, "event": "RESUME"}
>{"return": {}, "id": "libvirt-6"}
> len=118
>2012-05-16 09:30:00.205+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"timestamp": {"seconds": 1337160600, "microseconds": 205207}, "event": "RESUME"}]
>2012-05-16 09:30:00.205+0000: 23007: debug : qemuMonitorJSONIOProcessLine:150 : QEMU_MONITOR_RECV_EVENT: mon=0x7f6d6c004060 event={"timestamp": {"seconds": 1337160600, "microseconds": 205207}, "event": "RESUME"}
>2012-05-16 09:30:00.205+0000: 23007: debug : qemuMonitorJSONIOProcessEvent:107 : mon=0x7f6d6c004060 obj=0x2104120
>2012-05-16 09:30:00.205+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": {}, "id": "libvirt-6"}]
>2012-05-16 09:30:00.205+0000: 23007: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6d6c004060 reply={"return": {}, "id": "libvirt-6"}
>2012-05-16 09:30:00.205+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 118 bytes out of 118 available in buffer
>2012-05-16 09:30:00.205+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=3
>2012-05-16 09:30:00.205+0000: 23011: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x2103e10
>2012-05-16 09:30:00.205+0000: 23011: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c004060 refs=2
>2012-05-16 09:30:00.205+0000: 23011: debug : qemuProcessStart:3662 : Writing domain status to disk
>2012-05-16 09:30:00.251+0000: 23011: debug : qemuDomainObjEndJob:870 : Stopping job: modify (async=none)
>2012-05-16 09:30:00.283+0000: 23011: debug : virDomainObjUnref:1630 : obj=0x7f6d7c17b730 refs=2
>2012-05-16 09:30:00.283+0000: 23011: debug : virDomainFree:2313 : dom=0x7f6d6c0008c0, (VM: name=qcow2, uuid=5b9c7030-d0e3-2c4b-3ce3-77039e2bc3de)
>2012-05-16 09:30:00.290+0000: 23012: debug : virDomainLookupByUUID:2064 : conn=0x7f6d7c17b3b0, uuid=5b9c7030-d0e3-2c4b-3ce3-77039e2bc3de
>2012-05-16 09:30:00.290+0000: 23012: debug : virDomainFree:2313 : dom=0x7f6d68000df0, (VM: name=qcow2, uuid=5b9c7030-d0e3-2c4b-3ce3-77039e2bc3de)
>2012-05-16 09:30:00.291+0000: 23007: debug : virConnectClose:1462 : conn=0x7f6d7c17b3b0
>2012-05-16 09:30:00.292+0000: 23007: debug : qemuDriverCloseCallbackRunAll:661 : conn=0x7f6d7c17b3b0
>2012-05-16 09:30:02.891+0000: 23011: debug : virConnectOpen:1349 : name=
>2012-05-16 09:30:02.891+0000: 23011: debug : do_open:1155 : no name, allowing driver auto-select
>2012-05-16 09:30:02.891+0000: 23011: debug : do_open:1195 : trying driver 0 (Test) ...
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1201 : driver 0 Test returned DECLINED
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1195 : trying driver 1 (ESX) ...
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1201 : driver 1 ESX returned DECLINED
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1195 : trying driver 2 (remote) ...
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1201 : driver 2 remote returned DECLINED
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1195 : trying driver 3 (QEMU) ...
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1201 : driver 3 QEMU returned SUCCESS
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1223 : network driver 0 Test returned DECLINED
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1223 : network driver 1 ESX returned DECLINED
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1223 : network driver 2 remote returned DECLINED
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1223 : network driver 3 Network returned SUCCESS
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1238 : interface driver 0 Test returned DECLINED
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1238 : interface driver 1 ESX returned DECLINED
>2012-05-16 09:30:02.892+0000: 23011: debug : do_open:1238 : interface driver 2 remote returned DECLINED
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1238 : interface driver 3 Interface returned SUCCESS
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1254 : storage driver 0 Test returned DECLINED
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1254 : storage driver 1 ESX returned DECLINED
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1254 : storage driver 2 remote returned DECLINED
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1254 : storage driver 3 storage returned SUCCESS
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1270 : node driver 0 Test returned DECLINED
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1270 : node driver 1 ESX returned DECLINED
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1270 : node driver 2 remote returned DECLINED
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1270 : node driver 3 udevDeviceMonitor returned SUCCESS
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1286 : secret driver 0 Test returned DECLINED
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1286 : secret driver 1 ESX returned DECLINED
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1286 : secret driver 2 remote returned DECLINED
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1286 : secret driver 3 secret returned SUCCESS
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1302 : nwfilter driver 0 Test returned DECLINED
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1302 : nwfilter driver 1 ESX returned DECLINED
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1302 : nwfilter driver 2 remote returned DECLINED
>2012-05-16 09:30:02.901+0000: 23011: debug : do_open:1302 : nwfilter driver 3 nwfilter returned SUCCESS
>2012-05-16 09:30:02.901+0000: 23012: debug : virConnectGetURI:1722 : conn=0x7f6d6c003cd0
>2012-05-16 09:30:02.902+0000: 23009: debug : virDomainLookupByName:2146 : conn=0x7f6d6c003cd0, name=qcow2_foo
>2012-05-16 09:30:02.902+0000: 23009: debug : virDomainFree:2313 : dom=0x7f6d7c181de0, (VM: name=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095)
>2012-05-16 09:30:02.903+0000: 23008: debug : virDomainCreate:8065 : dom=0x7f6d74031ee0, (VM: name=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095)
>2012-05-16 09:30:02.903+0000: 23008: debug : virDomainObjRef:1623 : obj=0x7f6d7c15eee0 refs=2
>2012-05-16 09:30:02.903+0000: 23008: debug : qemuDomainObjBeginJobInternal:753 : Starting job: modify (async=none)
>2012-05-16 09:30:02.903+0000: 23008: debug : qemuProcessStart:3202 : Beginning VM startup process
>2012-05-16 09:30:02.903+0000: 23008: debug : qemuProcessStart:3214 : Setting current domain def as transient
>2012-05-16 09:30:02.904+0000: 23008: debug : qemuProcessStart:3240 : Preparing host devices
>2012-05-16 09:30:02.905+0000: 23008: debug : qemuPrepareHostdevUSBDevices:583 : Requested operation is not valid: USB device 002:003 is in use by domain qcow2
>2012-05-16 09:30:02.905+0000: 23008: debug : qemuProcessStop:3763 : Shutting down VM 'qcow2_foo' pid=0 migrated=0
>2012-05-16 09:30:02.905+0000: 23008: debug : qemuProcessKill:3691 : vm=qcow2_foo pid=0 flags=1
>2012-05-16 09:30:02.905+0000: 23008: debug : qemuDomainCleanupRun:1921 : driver=0x7f6d7c08c4b0, vm=qcow2_foo
>2012-05-16 09:30:02.905+0000: 23008: debug : qemuProcessAutoDestroyRemove:4195 : vm=qcow2_foo
>2012-05-16 09:30:02.905+0000: 23008: debug : qemuDriverCloseCallbackUnset:578 : vm=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095, cb=0x48e700
>2012-05-16 09:30:03.061+0000: 23008: warning : qemuDomainReAttachHostUsbDevices:869 : Unable to find device 002.003 in list of active USB devices
>2012-05-16 09:30:03.061+0000: 23008: error : qemuRemoveCgroup:592 : internal error Unable to find cgroup for qcow2_foo
>2012-05-16 09:30:03.061+0000: 23008: warning : qemuProcessStop:3897 : Failed to remove cgroup for qcow2_foo
>2012-05-16 09:30:03.062+0000: 23008: debug : qemuDomainObjEndJob:870 : Stopping job: modify (async=none)
>2012-05-16 09:30:03.062+0000: 23008: debug : virDomainObjUnref:1630 : obj=0x7f6d7c15eee0 refs=1
>2012-05-16 09:30:03.062+0000: 23008: debug : virDomainFree:2313 : dom=0x7f6d74031ee0, (VM: name=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095)
>2012-05-16 09:30:03.064+0000: 23007: debug : virConnectClose:1462 : conn=0x7f6d6c003cd0
>2012-05-16 09:30:03.065+0000: 23007: debug : qemuDriverCloseCallbackRunAll:661 : conn=0x7f6d6c003cd0
>2012-05-16 09:30:04.633+0000: 23009: debug : virConnectOpen:1349 : name=
>2012-05-16 09:30:04.633+0000: 23009: debug : do_open:1155 : no name, allowing driver auto-select
>2012-05-16 09:30:04.633+0000: 23009: debug : do_open:1195 : trying driver 0 (Test) ...
>2012-05-16 09:30:04.633+0000: 23009: debug : do_open:1201 : driver 0 Test returned DECLINED
>2012-05-16 09:30:04.633+0000: 23009: debug : do_open:1195 : trying driver 1 (ESX) ...
>2012-05-16 09:30:04.633+0000: 23009: debug : do_open:1201 : driver 1 ESX returned DECLINED
>2012-05-16 09:30:04.633+0000: 23009: debug : do_open:1195 : trying driver 2 (remote) ...
>2012-05-16 09:30:04.633+0000: 23009: debug : do_open:1201 : driver 2 remote returned DECLINED
>2012-05-16 09:30:04.633+0000: 23009: debug : do_open:1195 : trying driver 3 (QEMU) ...
>2012-05-16 09:30:04.633+0000: 23009: debug : do_open:1201 : driver 3 QEMU returned SUCCESS
>2012-05-16 09:30:04.633+0000: 23009: debug : do_open:1223 : network driver 0 Test returned DECLINED
>2012-05-16 09:30:04.633+0000: 23009: debug : do_open:1223 : network driver 1 ESX returned DECLINED
>2012-05-16 09:30:04.634+0000: 23009: debug : do_open:1223 : network driver 2 remote returned DECLINED
>2012-05-16 09:30:04.634+0000: 23009: debug : do_open:1223 : network driver 3 Network returned SUCCESS
>2012-05-16 09:30:04.634+0000: 23009: debug : do_open:1238 : interface driver 0 Test returned DECLINED
>2012-05-16 09:30:04.634+0000: 23009: debug : do_open:1238 : interface driver 1 ESX returned DECLINED
>2012-05-16 09:30:04.634+0000: 23009: debug : do_open:1238 : interface driver 2 remote returned DECLINED
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1238 : interface driver 3 Interface returned SUCCESS
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1254 : storage driver 0 Test returned DECLINED
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1254 : storage driver 1 ESX returned DECLINED
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1254 : storage driver 2 remote returned DECLINED
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1254 : storage driver 3 storage returned SUCCESS
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1270 : node driver 0 Test returned DECLINED
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1270 : node driver 1 ESX returned DECLINED
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1270 : node driver 2 remote returned DECLINED
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1270 : node driver 3 udevDeviceMonitor returned SUCCESS
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1286 : secret driver 0 Test returned DECLINED
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1286 : secret driver 1 ESX returned DECLINED
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1286 : secret driver 2 remote returned DECLINED
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1286 : secret driver 3 secret returned SUCCESS
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1302 : nwfilter driver 0 Test returned DECLINED
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1302 : nwfilter driver 1 ESX returned DECLINED
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1302 : nwfilter driver 2 remote returned DECLINED
>2012-05-16 09:30:04.638+0000: 23009: debug : do_open:1302 : nwfilter driver 3 nwfilter returned SUCCESS
>2012-05-16 09:30:04.639+0000: 23008: debug : virConnectGetURI:1722 : conn=0x7f6d7c17b3b0
>2012-05-16 09:30:04.640+0000: 23014: debug : virDomainLookupByName:2146 : conn=0x7f6d7c17b3b0, name=qcow2_foo
>2012-05-16 09:30:04.640+0000: 23014: debug : virDomainFree:2313 : dom=0x7f6d60000a10, (VM: name=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095)
>2012-05-16 09:30:04.640+0000: 23011: debug : virDomainCreate:8065 : dom=0x7f6d6c00b660, (VM: name=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095)
>2012-05-16 09:30:04.640+0000: 23011: debug : virDomainObjRef:1623 : obj=0x7f6d7c15eee0 refs=2
>2012-05-16 09:30:04.640+0000: 23011: debug : qemuDomainObjBeginJobInternal:753 : Starting job: modify (async=none)
>2012-05-16 09:30:04.641+0000: 23011: debug : qemuProcessStart:3202 : Beginning VM startup process
>2012-05-16 09:30:04.641+0000: 23011: debug : qemuProcessStart:3214 : Setting current domain def as transient
>2012-05-16 09:30:04.641+0000: 23011: debug : qemuProcessStart:3240 : Preparing host devices
>2012-05-16 09:30:04.642+0000: 23011: debug : qemuPrepareHostdevUSBDevices:593 : Adding 002.003 dom=qcow2_foo to activeUsbHostdevs
>2012-05-16 09:30:04.642+0000: 23011: debug : qemuProcessStart:3244 : Preparing chr devices
>2012-05-16 09:30:04.642+0000: 23011: debug : qemuProcessStart:3253 : Generating domain security label (if required)
>2012-05-16 09:30:04.642+0000: 23011: debug : qemuProcessStart:3262 : Ensuring no historical cgroup is lying around
>2012-05-16 09:30:04.643+0000: 23011: debug : qemuProcessStart:3315 : Creating domain log file
>2012-05-16 09:30:04.643+0000: 23011: debug : qemuProcessStart:3320 : Checking for KVM availability
>2012-05-16 09:30:04.643+0000: 23011: debug : qemuProcessStart:3330 : Determining emulator version
>2012-05-16 09:30:04.654+0000: 23011: debug : qemuCapsParseHelpStr:1337 : Version 0.12.1, cooked version 12001, flags 0x00000000400e5c000109fc6cf7effd6e
>2012-05-16 09:30:04.654+0000: 23011: debug : qemuCapsParseHelpStr:1343 : qemu-kvm version 0.12.1 detected
>2012-05-16 09:30:04.759+0000: 23011: debug : qemuProcessStart:3341 : Checking for CDROM and floppy presence
>2012-05-16 09:30:04.759+0000: 23011: debug : qemuProcessStart:3369 : Setting up domain cgroup (if required)
>2012-05-16 09:30:04.760+0000: 23011: debug : qemuSetupDiskPathAllow:70 : Process path /data/images/qcow2_foo.img for disk
>2012-05-16 09:30:04.760+0000: 23011: debug : qemuSetupDiskPathAllow:70 : Process path /var/lib/libvirt/images/share.img for disk
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuSetupHostUsbDeviceCgroup:182 : Process path '/dev/bus/usb/002/003' for USB device
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuProcessStart:3378 : Preparing monitor state
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuProcessStart:3414 : Assigning domain PCI addresses
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:3.0
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.1 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.2 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.3 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.4 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.5 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.6 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.7 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:4.0
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.1 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.2 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.3 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.4 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.5 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.6 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.7 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:2.0
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.1 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.2 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.3 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.4 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.5 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.6 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.7 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:1.2
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:7.0
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.1 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.2 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.3 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.4 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.5 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.6 (multifunction=off for function 0)
>2012-05-16 09:30:04.762+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:7.7 (multifunction=off for function 0)
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:1.1
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:6.0
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.1 (multifunction=off for function 0)
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.2 (multifunction=off for function 0)
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.3 (multifunction=off for function 0)
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.4 (multifunction=off for function 0)
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.5 (multifunction=off for function 0)
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.6 (multifunction=off for function 0)
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:6.7 (multifunction=off for function 0)
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.0
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.1
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.2
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.3
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.4
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.5
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.6
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.7
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.0
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.3
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.4
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.5
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.6
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.7
>2012-05-16 09:30:04.763+0000: 23011: debug : qemuProcessStart:3433 : Building emulator command line
>2012-05-16 09:30:04.763+0000: 23011: debug : virNetworkLookupByName:9725 : conn=0x7f6d7c17b3b0, name=default
>2012-05-16 09:30:04.763+0000: 23011: debug : virNetworkIsActive:15545 : net=0x7f6d6c00d7b0
>2012-05-16 09:30:04.763+0000: 23011: debug : virNetworkGetBridgeName:10276 : network=0x7f6d6c00d7b0
>2012-05-16 09:30:04.763+0000: 23011: debug : virNetworkFree:10069 : network=0x7f6d6c00d7b0
>2012-05-16 09:30:04.801+0000: 23011: debug : qemuProcessStart:3478 : Clear emulator capabilities: 1
>2012-05-16 09:30:04.804+0000: 23011: debug : qemuProcessStart:3525 : Writing early domain status to disk
>2012-05-16 09:30:04.867+0000: 23011: debug : qemuProcessStart:3530 : Waiting for handshake from child
>2012-05-16 09:30:04.867+0000: 23011: debug : qemuProcessStart:3535 : Setting domain security labels
>2012-05-16 09:30:04.868+0000: 23011: debug : qemuProcessStart:3558 : Labelling done, completing handshake to child
>2012-05-16 09:30:04.868+0000: 23011: debug : qemuProcessStart:3562 : Handshake complete, child running
>2012-05-16 09:30:04.868+0000: 23011: debug : qemuProcessStart:3573 : Waiting for monitor to show up
>2012-05-16 09:30:05.068+0000: 23011: debug : qemuProcessWaitForMonitor:1479 : Connect monitor to 0x7f6d7c15eee0 'qcow2_foo'
>2012-05-16 09:30:05.074+0000: 23011: debug : virDomainObjRef:1623 : obj=0x7f6d7c15eee0 refs=3
>2012-05-16 09:30:05.081+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=2
>2012-05-16 09:30:05.081+0000: 23011: debug : qemuMonitorOpen:734 : QEMU_MONITOR_NEW: mon=0x7f6d6c00e060 refs=2 fd=23
>2012-05-16 09:30:05.081+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.081+0000: 23011: debug : qemuMonitorSetCapabilities:1017 : mon=0x7f6d6c00e060
>2012-05-16 09:30:05.081+0000: 23011: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"qmp_capabilities","id":"libvirt-1"}' for write with FD -1
>2012-05-16 09:30:05.081+0000: 23011: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f6d6c00e060 msg={"execute":"qmp_capabilities","id":"libvirt-1"}
> fd=-1
>2012-05-16 09:30:05.081+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=4
>2012-05-16 09:30:05.081+0000: 23007: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f6d6c00e060 buf={"execute":"qmp_capabilities","id":"libvirt-1"}
> len=49 ret=49 errno=2
>2012-05-16 09:30:05.081+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c00e060 buf={"QMP": {"version": {"qemu": {"micro": 1, "minor": 12, "major": 0}, "package": "(qemu-kvm-0.12.1.2)"}, "capabilities": []}}
> len=125
>2012-05-16 09:30:05.081+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"QMP": {"version": {"qemu": {"micro": 1, "minor": 12, "major": 0}, "package": "(qemu-kvm-0.12.1.2)"}, "capabilities": []}}]
>2012-05-16 09:30:05.081+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 125 bytes out of 125 available in buffer
>2012-05-16 09:30:05.081+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.081+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=4
>2012-05-16 09:30:05.082+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c00e060 buf={"return": {}, "id": "libvirt-1"}
> len=35
>2012-05-16 09:30:05.082+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": {}, "id": "libvirt-1"}]
>2012-05-16 09:30:05.082+0000: 23007: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6d6c00e060 reply={"return": {}, "id": "libvirt-1"}
>2012-05-16 09:30:05.082+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 35 bytes out of 35 available in buffer
>2012-05-16 09:30:05.082+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.082+0000: 23011: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x2107090
>2012-05-16 09:30:05.082+0000: 23011: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"query-commands","id":"libvirt-2"}' for write with FD -1
>2012-05-16 09:30:05.082+0000: 23011: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f6d6c00e060 msg={"execute":"query-commands","id":"libvirt-2"}
> fd=-1
>2012-05-16 09:30:05.082+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=4
>2012-05-16 09:30:05.082+0000: 23007: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f6d6c00e060 buf={"execute":"query-commands","id":"libvirt-2"}
> len=47 ret=47 errno=11
>2012-05-16 09:30:05.082+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.084+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=4
>2012-05-16 09:30:05.085+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c00e060 buf={"return": [{"name": "quit"}, {"name": "eject"}, {"name": "__com.redhat_drive_del"}, {"name": "change"}, {"name": "screendump"}, {"name": "__com.redhat_qxl_screendump"}, {"name": "stop"}, {"name": "cont"}, {"name": "system_wakeup"}, {"name": "system_reset"}, {"name": "system_powerdown"}, {"name": "device_add"}, {"name": "device_del"}, {"name": "cpu"}, {"name": "memsave"}, {"name": "pmemsave"}, {"name": "inject-nmi"}, {"name": "migrate"}, {"name": "migrate_cancel"}, {"name": "migrate_set_speed"}, {"name": "client_migrate_info"}, {"name": "migrate_set_downtime"}, {"name": "block_resize"}, {"name": "netdev_add"}, {"name": "netdev_del"}, {"name": "transaction"}, {"name": "blockdev-snapshot-sync"}, {"name": "__com.redhat_drive-mirror"}, {"name": "__com.redhat_drive-reopen"}, {"name": "balloon"}, {"name": "set_link"}, {"name": "getfd"}, {"name": "closefd"}, {"name": "block_passwd"}, {"name": "set_password"}, {"name": "expire_password"}, {"name": "__com.redhat_set_password"}, {"name": "__com.redhat_spice_migrate_in len=1023
>2012-05-16 09:30:05.085+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 0 bytes out of 1023 available in buffer
>2012-05-16 09:30:05.085+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.085+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=4
>2012-05-16 09:30:05.085+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c00e060 buf={"return": [{"name": "quit"}, {"name": "eject"}, {"name": "__com.redhat_drive_del"}, {"name": "change"}, {"name": "screendump"}, {"name": "__com.redhat_qxl_screendump"}, {"name": "stop"}, {"name": "cont"}, {"name": "system_wakeup"}, {"name": "system_reset"}, {"name": "system_powerdown"}, {"name": "device_add"}, {"name": "device_del"}, {"name": "cpu"}, {"name": "memsave"}, {"name": "pmemsave"}, {"name": "inject-nmi"}, {"name": "migrate"}, {"name": "migrate_cancel"}, {"name": "migrate_set_speed"}, {"name": "client_migrate_info"}, {"name": "migrate_set_downtime"}, {"name": "block_resize"}, {"name": "netdev_add"}, {"name": "netdev_del"}, {"name": "transaction"}, {"name": "blockdev-snapshot-sync"}, {"name": "__com.redhat_drive-mirror"}, {"name": "__com.redhat_drive-reopen"}, {"name": "balloon"}, {"name": "set_link"}, {"name": "getfd"}, {"name": "closefd"}, {"name": "block_passwd"}, {"name": "set_password"}, {"name": "expire_password"}, {"name": "__com.redhat_set_password"}, {"name": "__com.redhat_spice_migrate_info"}, {"name": "qmp_capabilities"}, {"name": "human-monitor-command"}, {"name": "__com.redhat_drive_add"}, {"name": "block-stream"}, {"name": "block-job-set-speed"}, {"name": "block-job-cancel"}, {"name": "query-version"}, {"name": "query-commands"}, {"name": "query-chardev"}, {"name": "query-block"}, {"name": "query-blockstats"}, {"name": "query-cpus"}, {"name": "query-kvm"}, {"name": "query-status"}, {"name": "query-mice"}, {"name": "query-vnc"}, {"name": "query-spice"}, {"name": "query-name"}, {"name": "query-uuid"}, {"name": "query-migrate"}, {"name": "query-balloon"}, {"name": "query-block-jobs"}], "id": "libvirt-2"}
> len=1654
>2012-05-16 09:30:05.085+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": [{"name": "quit"}, {"name": "eject"}, {"name": "__com.redhat_drive_del"}, {"name": "change"}, {"name": "screendump"}, {"name": "__com.redhat_qxl_screendump"}, {"name": "stop"}, {"name": "cont"}, {"name": "system_wakeup"}, {"name": "system_reset"}, {"name": "system_powerdown"}, {"name": "device_add"}, {"name": "device_del"}, {"name": "cpu"}, {"name": "memsave"}, {"name": "pmemsave"}, {"name": "inject-nmi"}, {"name": "migrate"}, {"name": "migrate_cancel"}, {"name": "migrate_set_speed"}, {"name": "client_migrate_info"}, {"name": "migrate_set_downtime"}, {"name": "block_resize"}, {"name": "netdev_add"}, {"name": "netdev_del"}, {"name": "transaction"}, {"name": "blockdev-snapshot-sync"}, {"name": "__com.redhat_drive-mirror"}, {"name": "__com.redhat_drive-reopen"}, {"name": "balloon"}, {"name": "set_link"}, {"name": "getfd"}, {"name": "closefd"}, {"name": "block_passwd"}, {"name": "set_password"}, {"name": "expire_password"}, {"name": "__com.redhat_set_password"}, {"name": "__com.redhat_spice_migrate_info"}, {"name": "qmp_capabilities"}, {"name": "human-monitor-command"}, {"name": "__com.redhat_drive_add"}, {"name": "block-stream"}, {"name": "block-job-set-speed"}, {"name": "block-job-cancel"}, {"name": "query-version"}, {"name": "query-commands"}, {"name": "query-chardev"}, {"name": "query-block"}, {"name": "query-blockstats"}, {"name": "query-cpus"}, {"name": "query-kvm"}, {"name": "query-status"}, {"name": "query-mice"}, {"name": "query-vnc"}, {"name": "query-spice"}, {"name": "query-name"}, {"name": "query-uuid"}, {"name": "query-migrate"}, {"name": "query-balloon"}, {"name": "query-block-jobs"}], "id": "libvirt-2"}]
>2012-05-16 09:30:05.086+0000: 23007: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6d6c00e060 reply={"return": [{"name": "quit"}, {"name": "eject"}, {"name": "__com.redhat_drive_del"}, {"name": "change"}, {"name": "screendump"}, {"name": "__com.redhat_qxl_screendump"}, {"name": "stop"}, {"name": "cont"}, {"name": "system_wakeup"}, {"name": "system_reset"}, {"name": "system_powerdown"}, {"name": "device_add"}, {"name": "device_del"}, {"name": "cpu"}, {"name": "memsave"}, {"name": "pmemsave"}, {"name": "inject-nmi"}, {"name": "migrate"}, {"name": "migrate_cancel"}, {"name": "migrate_set_speed"}, {"name": "client_migrate_info"}, {"name": "migrate_set_downtime"}, {"name": "block_resize"}, {"name": "netdev_add"}, {"name": "netdev_del"}, {"name": "transaction"}, {"name": "blockdev-snapshot-sync"}, {"name": "__com.redhat_drive-mirror"}, {"name": "__com.redhat_drive-reopen"}, {"name": "balloon"}, {"name": "set_link"}, {"name": "getfd"}, {"name": "closefd"}, {"name": "block_passwd"}, {"name": "set_password"}, {"name": "expire_password"}, {"name": "__com.redhat_set_password"}, {"name": "__com.redhat_spice_migrate_info"}, {"name": "qmp_capabilities"}, {"name": "human-monitor-command"}, {"name": "__com.redhat_drive_add"}, {"name": "block-stream"}, {"name": "block-job-set-speed"}, {"name": "block-job-cancel"}, {"name": "query-version"}, {"name": "query-commands"}, {"name": "query-chardev"}, {"name": "query-block"}, {"name": "query-blockstats"}, {"name": "query-cpus"}, {"name": "query-kvm"}, {"name": "query-status"}, {"name": "query-mice"}, {"name": "query-vnc"}, {"name": "query-spice"}, {"name": "query-name"}, {"name": "query-uuid"}, {"name": "query-migrate"}, {"name": "query-balloon"}, {"name": "query-block-jobs"}], "id": "libvirt-2"}
>2012-05-16 09:30:05.086+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 1654 bytes out of 1654 available in buffer
>2012-05-16 09:30:05.086+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.086+0000: 23011: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x2107630
>2012-05-16 09:30:05.086+0000: 23011: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=2
>2012-05-16 09:30:05.086+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.086+0000: 23011: debug : qemuMonitorGetPtyPaths:2354 : mon=0x7f6d6c00e060
>2012-05-16 09:30:05.086+0000: 23011: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"query-chardev","id":"libvirt-3"}' for write with FD -1
>2012-05-16 09:30:05.086+0000: 23011: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f6d6c00e060 msg={"execute":"query-chardev","id":"libvirt-3"}
> fd=-1
>2012-05-16 09:30:05.086+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=4
>2012-05-16 09:30:05.086+0000: 23007: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f6d6c00e060 buf={"execute":"query-chardev","id":"libvirt-3"}
> len=46 ret=46 errno=11
>2012-05-16 09:30:05.086+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.087+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=4
>2012-05-16 09:30:05.087+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c00e060 buf={"return": [{"filename": "unix:/var/lib/libvirt/qemu/qcow2_foo.monitor,server", "label": "charmonitor"}, {"filename": "pty:/dev/pts/4", "label": "charserial0"}, {"filename": "spicevmc", "label": "charchannel0"}], "id": "libvirt-3"}
> len=233
>2012-05-16 09:30:05.087+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": [{"filename": "unix:/var/lib/libvirt/qemu/qcow2_foo.monitor,server", "label": "charmonitor"}, {"filename": "pty:/dev/pts/4", "label": "charserial0"}, {"filename": "spicevmc", "label": "charchannel0"}], "id": "libvirt-3"}]
>2012-05-16 09:30:05.087+0000: 23007: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6d6c00e060 reply={"return": [{"filename": "unix:/var/lib/libvirt/qemu/qcow2_foo.monitor,server", "label": "charmonitor"}, {"filename": "pty:/dev/pts/4", "label": "charserial0"}, {"filename": "spicevmc", "label": "charchannel0"}], "id": "libvirt-3"}
>2012-05-16 09:30:05.087+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 233 bytes out of 233 available in buffer
>2012-05-16 09:30:05.087+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.087+0000: 23011: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x210df10
>2012-05-16 09:30:05.087+0000: 23011: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=2
>2012-05-16 09:30:05.087+0000: 23011: debug : qemuProcessWaitForMonitor:1497 : qemuMonitorGetPtyPaths returned 0
>2012-05-16 09:30:05.087+0000: 23011: debug : qemuProcessStart:3585 : Detecting VCPU PIDs
>2012-05-16 09:30:05.087+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.087+0000: 23011: debug : qemuMonitorGetCPUInfo:1161 : mon=0x7f6d6c00e060
>2012-05-16 09:30:05.087+0000: 23011: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"query-cpus","id":"libvirt-4"}' for write with FD -1
>2012-05-16 09:30:05.087+0000: 23011: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f6d6c00e060 msg={"execute":"query-cpus","id":"libvirt-4"}
> fd=-1
>2012-05-16 09:30:05.088+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=4
>2012-05-16 09:30:05.088+0000: 23007: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f6d6c00e060 buf={"execute":"query-cpus","id":"libvirt-4"}
> len=43 ret=43 errno=11
>2012-05-16 09:30:05.088+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.088+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=4
>2012-05-16 09:30:05.088+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c00e060 buf={"return": [{"current": true, "CPU": 0, "pc": 4294967280, "halted": false, "thread_id": 23211}, {"current": false, "CPU": 1, "pc": 4294967280, "halted": true, "thread_id": 23212}], "id": "libvirt-4"}
> len=201
>2012-05-16 09:30:05.088+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": [{"current": true, "CPU": 0, "pc": 4294967280, "halted": false, "thread_id": 23211}, {"current": false, "CPU": 1, "pc": 4294967280, "halted": true, "thread_id": 23212}], "id": "libvirt-4"}]
>2012-05-16 09:30:05.088+0000: 23007: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6d6c00e060 reply={"return": [{"current": true, "CPU": 0, "pc": 4294967280, "halted": false, "thread_id": 23211}, {"current": false, "CPU": 1, "pc": 4294967280, "halted": true, "thread_id": 23212}], "id": "libvirt-4"}
>2012-05-16 09:30:05.088+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 201 bytes out of 201 available in buffer
>2012-05-16 09:30:05.088+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.088+0000: 23011: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x210d060
>2012-05-16 09:30:05.089+0000: 23011: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=2
>2012-05-16 09:30:05.089+0000: 23011: debug : qemuProcessStart:3589 : Setting cgroup for each VCPU(if required)
>2012-05-16 09:30:05.106+0000: 23011: debug : qemuProcessStart:3593 : Setting VCPU affinities
>2012-05-16 09:30:05.106+0000: 23011: debug : virNodeGetInfo:6398 : conn=0x7f6d7c17b3b0, info=0x7f6d87437820
>2012-05-16 09:30:05.107+0000: 23011: debug : qemuProcessStart:3597 : Setting any required VM passwords
>2012-05-16 09:30:05.107+0000: 23011: debug : qemuProcessStart:3612 : Setting network link states
>2012-05-16 09:30:05.107+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.107+0000: 23011: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=2
>2012-05-16 09:30:05.107+0000: 23011: debug : qemuProcessStart:3625 : Setting initial memory amount
>2012-05-16 09:30:05.107+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.107+0000: 23011: debug : qemuMonitorSetBalloon:1564 : mon=0x7f6d6c00e060 newmem=1048576
>2012-05-16 09:30:05.107+0000: 23011: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"balloon","arguments":{"value":1073741824},"id":"libvirt-5"}' for write with FD -1
>2012-05-16 09:30:05.107+0000: 23011: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f6d6c00e060 msg={"execute":"balloon","arguments":{"value":1073741824},"id":"libvirt-5"}
> fd=-1
>2012-05-16 09:30:05.107+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=4
>2012-05-16 09:30:05.107+0000: 23007: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f6d6c00e060 buf={"execute":"balloon","arguments":{"value":1073741824},"id":"libvirt-5"}
> len=73 ret=73 errno=11
>2012-05-16 09:30:05.107+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.107+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=4
>2012-05-16 09:30:05.107+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c00e060 buf={"return": {}, "id": "libvirt-5"}
> len=35
>2012-05-16 09:30:05.107+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": {}, "id": "libvirt-5"}]
>2012-05-16 09:30:05.108+0000: 23007: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6d6c00e060 reply={"return": {}, "id": "libvirt-5"}
>2012-05-16 09:30:05.108+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 35 bytes out of 35 available in buffer
>2012-05-16 09:30:05.108+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.108+0000: 23011: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x210be30
>2012-05-16 09:30:05.108+0000: 23011: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=2
>2012-05-16 09:30:05.108+0000: 23011: debug : qemuProcessStart:3641 : Starting domain CPUs
>2012-05-16 09:30:05.108+0000: 23011: debug : qemuProcessStartCPUs:2554 : Using lock state '(null)'
>2012-05-16 09:30:05.108+0000: 23011: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.108+0000: 23011: debug : qemuMonitorStartCPUs:1061 : mon=0x7f6d6c00e060
>2012-05-16 09:30:05.108+0000: 23011: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"cont","id":"libvirt-6"}' for write with FD -1
>2012-05-16 09:30:05.108+0000: 23011: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f6d6c00e060 msg={"execute":"cont","id":"libvirt-6"}
> fd=-1
>2012-05-16 09:30:05.108+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=4
>2012-05-16 09:30:05.108+0000: 23007: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f6d6c00e060 buf={"execute":"cont","id":"libvirt-6"}
> len=37 ret=37 errno=11
>2012-05-16 09:30:05.108+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.108+0000: 23007: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f6d6c00e060 refs=4
>2012-05-16 09:30:05.108+0000: 23007: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6d6c00e060 buf={"timestamp": {"seconds": 1337160605, "microseconds": 108823}, "event": "RESUME"}
>{"return": {}, "id": "libvirt-6"}
> len=118
>2012-05-16 09:30:05.109+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"timestamp": {"seconds": 1337160605, "microseconds": 108823}, "event": "RESUME"}]
>2012-05-16 09:30:05.109+0000: 23007: debug : qemuMonitorJSONIOProcessLine:150 : QEMU_MONITOR_RECV_EVENT: mon=0x7f6d6c00e060 event={"timestamp": {"seconds": 1337160605, "microseconds": 108823}, "event": "RESUME"}
>2012-05-16 09:30:05.109+0000: 23007: debug : qemuMonitorJSONIOProcessEvent:107 : mon=0x7f6d6c00e060 obj=0x210b8f0
>2012-05-16 09:30:05.109+0000: 23007: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": {}, "id": "libvirt-6"}]
>2012-05-16 09:30:05.109+0000: 23007: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6d6c00e060 reply={"return": {}, "id": "libvirt-6"}
>2012-05-16 09:30:05.109+0000: 23007: debug : qemuMonitorJSONIOProcess:206 : Total used 118 bytes out of 118 available in buffer
>2012-05-16 09:30:05.109+0000: 23007: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=3
>2012-05-16 09:30:05.109+0000: 23011: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x210b500
>2012-05-16 09:30:05.109+0000: 23011: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f6d6c00e060 refs=2
>2012-05-16 09:30:05.109+0000: 23011: debug : qemuProcessStart:3662 : Writing domain status to disk
>2012-05-16 09:30:05.143+0000: 23011: debug : qemuDomainObjEndJob:870 : Stopping job: modify (async=none)
>2012-05-16 09:30:05.177+0000: 23011: debug : virDomainObjUnref:1630 : obj=0x7f6d7c15eee0 refs=2
>2012-05-16 09:30:05.177+0000: 23011: debug : virDomainFree:2313 : dom=0x7f6d6c00b660, (VM: name=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095)
>2012-05-16 09:30:05.178+0000: 23015: debug : virDomainLookupByUUID:2064 : conn=0x7f6d7c17b3b0, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095
>2012-05-16 09:30:05.178+0000: 23015: debug : virDomainFree:2313 : dom=0x7f6d780086b0, (VM: name=qcow2_foo, uuid=8ac888e7-7288-bce9-0ef1-ce639d51c095)
>2012-05-16 09:30:05.180+0000: 23007: debug : virConnectClose:1462 : conn=0x7f6d7c17b3b0
>2012-05-16 09:30:05.181+0000: 23007: debug : qemuDriverCloseCallbackRunAll:661 : conn=0x7f6d7c17b3b0

Actions: View

Attachments on bug 743671: 572614 | 584917