Attachment 586744 Details for Bug 825068 – log for starting 2 guest with assigned same usb

log for starting 2 guest with assigned same usb

change-label.log (text/plain), 40.04 KB, created by weizhang on 2012-05-25 02:07:03 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: weizhang

Created: 2012-05-25 02:07:03 UTC

Size: 40.04 KB

patch

obsolete

>2012-05-25 02:03:04.898+0000: 16363: debug : virConnectOpen:1349 : name=
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1155 : no name, allowing driver auto-select
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1195 : trying driver 0 (Test) ...
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1201 : driver 0 Test returned DECLINED
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1195 : trying driver 1 (ESX) ...
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1201 : driver 1 ESX returned DECLINED
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1195 : trying driver 2 (remote) ...
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1201 : driver 2 remote returned DECLINED
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1195 : trying driver 3 (QEMU) ...
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1201 : driver 3 QEMU returned SUCCESS
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1223 : network driver 0 Test returned DECLINED
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1223 : network driver 1 ESX returned DECLINED
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1223 : network driver 2 remote returned DECLINED
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1223 : network driver 3 Network returned SUCCESS
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1238 : interface driver 0 Test returned DECLINED
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1238 : interface driver 1 ESX returned DECLINED
>2012-05-25 02:03:04.898+0000: 16363: debug : do_open:1238 : interface driver 2 remote returned DECLINED
>2012-05-25 02:03:04.910+0000: 16363: debug : do_open:1238 : interface driver 3 Interface returned SUCCESS
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1254 : storage driver 0 Test returned DECLINED
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1254 : storage driver 1 ESX returned DECLINED
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1254 : storage driver 2 remote returned DECLINED
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1254 : storage driver 3 storage returned SUCCESS
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1270 : node driver 0 Test returned DECLINED
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1270 : node driver 1 ESX returned DECLINED
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1270 : node driver 2 remote returned DECLINED
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1270 : node driver 3 udevDeviceMonitor returned SUCCESS
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1286 : secret driver 0 Test returned DECLINED
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1286 : secret driver 1 ESX returned DECLINED
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1286 : secret driver 2 remote returned DECLINED
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1286 : secret driver 3 secret returned SUCCESS
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1302 : nwfilter driver 0 Test returned DECLINED
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1302 : nwfilter driver 1 ESX returned DECLINED
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1302 : nwfilter driver 2 remote returned DECLINED
>2012-05-25 02:03:04.911+0000: 16363: debug : do_open:1302 : nwfilter driver 3 nwfilter returned SUCCESS
>2012-05-25 02:03:04.911+0000: 16364: debug : virConnectGetURI:1722 : conn=0x7f7f1c0456d0
>2012-05-25 02:03:04.912+0000: 16368: debug : virDomainLookupByName:2146 : conn=0x7f7f1c0456d0, name=kvm-rhel6u3-i386
>2012-05-25 02:03:04.912+0000: 16368: debug : virDomainFree:2313 : dom=0x7f7ef8031f70, (VM: name=kvm-rhel6u3-i386, uuid=96be2130-2400-eead-9e53-691a59d10c71)
>2012-05-25 02:03:04.913+0000: 16367: debug : virDomainCreate:8065 : dom=0x7f7f1402aca0, (VM: name=kvm-rhel6u3-i386, uuid=96be2130-2400-eead-9e53-691a59d10c71)
>2012-05-25 02:03:04.913+0000: 16367: debug : virDomainObjRef:1623 : obj=0x7f7f201b05f0 refs=2
>2012-05-25 02:03:04.913+0000: 16367: debug : qemuDomainObjBeginJobInternal:753 : Starting job: modify (async=none)
>2012-05-25 02:03:04.913+0000: 16367: debug : qemuProcessStart:3202 : Beginning VM startup process
>2012-05-25 02:03:04.913+0000: 16367: debug : qemuProcessStart:3214 : Setting current domain def as transient
>2012-05-25 02:03:04.914+0000: 16367: debug : qemuProcessStart:3240 : Preparing host devices
>2012-05-25 02:03:04.915+0000: 16367: debug : qemuPrepareHostdevUSBDevices:593 : Adding 002.007 dom=kvm-rhel6u3-i386 to activeUsbHostdevs
>2012-05-25 02:03:04.915+0000: 16367: debug : qemuProcessStart:3244 : Preparing chr devices
>2012-05-25 02:03:04.915+0000: 16367: debug : qemuProcessStart:3253 : Generating domain security label (if required)
>2012-05-25 02:03:04.918+0000: 16367: debug : qemuProcessStart:3262 : Ensuring no historical cgroup is lying around
>2012-05-25 02:03:04.919+0000: 16367: debug : qemuProcessStart:3315 : Creating domain log file
>2012-05-25 02:03:04.920+0000: 16367: debug : qemuProcessStart:3320 : Checking for KVM availability
>2012-05-25 02:03:04.920+0000: 16367: debug : qemuProcessStart:3330 : Determining emulator version
>2012-05-25 02:03:04.937+0000: 16367: debug : qemuCapsParseHelpStr:1337 : Version 0.12.1, cooked version 12001, flags 0x00000000400e5c000109fc6cf7effd6e
>2012-05-25 02:03:04.937+0000: 16367: debug : qemuCapsParseHelpStr:1343 : qemu-kvm version 0.12.1 detected
>2012-05-25 02:03:05.028+0000: 16367: debug : qemuProcessStart:3341 : Checking for CDROM and floppy presence
>2012-05-25 02:03:05.028+0000: 16367: debug : qemuProcessStart:3369 : Setting up domain cgroup (if required)
>2012-05-25 02:03:05.029+0000: 16367: debug : qemuSetupDiskPathAllow:70 : Process path /var/lib/libvirt/images/kvm-rhel6u3-i386.img for disk
>2012-05-25 02:03:05.031+0000: 16367: debug : qemuSetupHostUsbDeviceCgroup:182 : Process path '/dev/bus/usb/002/007' for USB device
>2012-05-25 02:03:05.031+0000: 16367: debug : qemuProcessStart:3378 : Preparing monitor state
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuProcessStart:3414 : Assigning domain PCI addresses
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:3.0
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.1 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.2 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.3 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.4 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.5 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.6 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:3.7 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:4.0
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.1 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.2 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.3 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.4 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.5 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.6 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:4.7 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:2.0
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.1 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.2 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.3 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.4 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.5 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.6 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:2.7 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:1.2
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:1.1
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:878 : Remembering PCI addr 0:0:5.0
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:5.1 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:5.2 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:5.3 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:5.4 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:5.5 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:5.6 (multifunction=off for function 0)
>2012-05-25 02:03:05.032+0000: 16367: debug : qemuCollectPCIAddress:903 : Remembering PCI addr 0:0:5.7 (multifunction=off for function 0)
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.0
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.1
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.2
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.3
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.4
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.5
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.6
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:0.7
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.0
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.3
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.4
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.5
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.6
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuDomainPCIAddressReserveAddr:1023 : Reserving PCI addr 0:0:1.7
>2012-05-25 02:03:05.033+0000: 16367: debug : qemuProcessStart:3433 : Building emulator command line
>2012-05-25 02:03:05.047+0000: 16367: debug : qemuProcessStart:3478 : Clear emulator capabilities: 1
>2012-05-25 02:03:05.051+0000: 16367: debug : qemuProcessStart:3525 : Writing early domain status to disk
>2012-05-25 02:03:05.127+0000: 16367: debug : qemuProcessStart:3530 : Waiting for handshake from child
>2012-05-25 02:03:05.127+0000: 16367: debug : qemuProcessStart:3535 : Setting domain security labels
>2012-05-25 02:03:05.129+0000: 16367: debug : qemuProcessStart:3558 : Labelling done, completing handshake to child
>2012-05-25 02:03:05.129+0000: 16367: debug : qemuProcessStart:3562 : Handshake complete, child running
>2012-05-25 02:03:05.129+0000: 16367: debug : qemuProcessStart:3573 : Waiting for monitor to show up
>2012-05-25 02:03:05.330+0000: 16367: debug : qemuProcessWaitForMonitor:1479 : Connect monitor to 0x7f7f201b05f0 'kvm-rhel6u3-i386'
>2012-05-25 02:03:05.330+0000: 16367: debug : virDomainObjRef:1623 : obj=0x7f7f201b05f0 refs=3
>2012-05-25 02:03:05.336+0000: 16367: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=2
>2012-05-25 02:03:05.336+0000: 16367: debug : qemuMonitorOpen:734 : QEMU_MONITOR_NEW: mon=0x7f7f140e93f0 refs=2 fd=26
>2012-05-25 02:03:05.336+0000: 16367: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.336+0000: 16367: debug : qemuMonitorSetCapabilities:1017 : mon=0x7f7f140e93f0
>2012-05-25 02:03:05.336+0000: 16367: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"qmp_capabilities","id":"libvirt-1"}' for write with FD -1
>2012-05-25 02:03:05.336+0000: 16367: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f7f140e93f0 msg={"execute":"qmp_capabilities","id":"libvirt-1"}
> fd=-1
>2012-05-25 02:03:05.336+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.336+0000: 16362: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f7f140e93f0 buf={"execute":"qmp_capabilities","id":"libvirt-1"}
> len=49 ret=49 errno=2
>2012-05-25 02:03:05.336+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.668+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.668+0000: 16362: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f7f140e93f0 buf={"QMP": {"version": {"qemu": {"micro": 1, "minor": 12, "major": 0}, "package": "(qemu-kvm-0.12.1.2)"}, "capabilities": []}}
> len=125
>2012-05-25 02:03:05.668+0000: 16362: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"QMP": {"version": {"qemu": {"micro": 1, "minor": 12, "major": 0}, "package": "(qemu-kvm-0.12.1.2)"}, "capabilities": []}}]
>2012-05-25 02:03:05.669+0000: 16362: debug : qemuMonitorJSONIOProcess:206 : Total used 125 bytes out of 125 available in buffer
>2012-05-25 02:03:05.669+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.669+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.669+0000: 16362: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f7f140e93f0 buf={"return": {}, "id": "libvirt-1"}
> len=35
>2012-05-25 02:03:05.669+0000: 16362: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": {}, "id": "libvirt-1"}]
>2012-05-25 02:03:05.669+0000: 16362: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f7f140e93f0 reply={"return": {}, "id": "libvirt-1"}
>2012-05-25 02:03:05.669+0000: 16362: debug : qemuMonitorJSONIOProcess:206 : Total used 35 bytes out of 35 available in buffer
>2012-05-25 02:03:05.669+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.669+0000: 16367: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x210e790
>2012-05-25 02:03:05.669+0000: 16367: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"query-commands","id":"libvirt-2"}' for write with FD -1
>2012-05-25 02:03:05.669+0000: 16367: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f7f140e93f0 msg={"execute":"query-commands","id":"libvirt-2"}
> fd=-1
>2012-05-25 02:03:05.669+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.669+0000: 16362: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f7f140e93f0 buf={"execute":"query-commands","id":"libvirt-2"}
> len=47 ret=47 errno=11
>2012-05-25 02:03:05.669+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.671+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.671+0000: 16362: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f7f140e93f0 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": "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-job-set-speed"}, len=1023
>2012-05-25 02:03:05.671+0000: 16362: debug : qemuMonitorJSONIOProcess:206 : Total used 0 bytes out of 1023 available in buffer
>2012-05-25 02:03:05.671+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.671+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.671+0000: 16362: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f7f140e93f0 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": "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-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=1489
>2012-05-25 02:03:05.672+0000: 16362: 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": "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-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-25 02:03:05.672+0000: 16362: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f7f140e93f0 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": "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-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-25 02:03:05.672+0000: 16362: debug : qemuMonitorJSONIOProcess:206 : Total used 1489 bytes out of 1489 available in buffer
>2012-05-25 02:03:05.672+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.672+0000: 16367: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x210e280
>2012-05-25 02:03:05.673+0000: 16367: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=2
>2012-05-25 02:03:05.673+0000: 16367: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.673+0000: 16367: debug : qemuMonitorGetPtyPaths:2354 : mon=0x7f7f140e93f0
>2012-05-25 02:03:05.673+0000: 16367: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"query-chardev","id":"libvirt-3"}' for write with FD -1
>2012-05-25 02:03:05.673+0000: 16367: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f7f140e93f0 msg={"execute":"query-chardev","id":"libvirt-3"}
> fd=-1
>2012-05-25 02:03:05.673+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.673+0000: 16362: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f7f140e93f0 buf={"execute":"query-chardev","id":"libvirt-3"}
> len=46 ret=46 errno=11
>2012-05-25 02:03:05.673+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.673+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.673+0000: 16362: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f7f140e93f0 buf={"return": [{"filename": "unix:/var/lib/libvirt/qemu/kvm-rhel6u3-i386.monitor,server", "label": "charmonitor"}, {"filename": "pty:/dev/pts/12", "label": "charserial0"}], "id": "libvirt-3"}
> len=190
>2012-05-25 02:03:05.673+0000: 16362: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": [{"filename": "unix:/var/lib/libvirt/qemu/kvm-rhel6u3-i386.monitor,server", "label": "charmonitor"}, {"filename": "pty:/dev/pts/12", "label": "charserial0"}], "id": "libvirt-3"}]
>2012-05-25 02:03:05.673+0000: 16362: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f7f140e93f0 reply={"return": [{"filename": "unix:/var/lib/libvirt/qemu/kvm-rhel6u3-i386.monitor,server", "label": "charmonitor"}, {"filename": "pty:/dev/pts/12", "label": "charserial0"}], "id": "libvirt-3"}
>2012-05-25 02:03:05.673+0000: 16362: debug : qemuMonitorJSONIOProcess:206 : Total used 190 bytes out of 190 available in buffer
>2012-05-25 02:03:05.673+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.674+0000: 16367: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x210bd00
>2012-05-25 02:03:05.674+0000: 16367: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=2
>2012-05-25 02:03:05.674+0000: 16367: debug : qemuProcessWaitForMonitor:1497 : qemuMonitorGetPtyPaths returned 0
>2012-05-25 02:03:05.674+0000: 16367: debug : qemuProcessStart:3585 : Detecting VCPU PIDs
>2012-05-25 02:03:05.674+0000: 16367: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.674+0000: 16367: debug : qemuMonitorGetCPUInfo:1161 : mon=0x7f7f140e93f0
>2012-05-25 02:03:05.674+0000: 16367: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"query-cpus","id":"libvirt-4"}' for write with FD -1
>2012-05-25 02:03:05.674+0000: 16367: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f7f140e93f0 msg={"execute":"query-cpus","id":"libvirt-4"}
> fd=-1
>2012-05-25 02:03:05.674+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.674+0000: 16362: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f7f140e93f0 buf={"execute":"query-cpus","id":"libvirt-4"}
> len=43 ret=43 errno=11
>2012-05-25 02:03:05.674+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.674+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.674+0000: 16362: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f7f140e93f0 buf={"return": [{"current": true, "CPU": 0, "pc": 4294967280, "halted": false, "thread_id": 30805}], "id": "libvirt-4"}
> len=117
>2012-05-25 02:03:05.674+0000: 16362: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": [{"current": true, "CPU": 0, "pc": 4294967280, "halted": false, "thread_id": 30805}], "id": "libvirt-4"}]
>2012-05-25 02:03:05.674+0000: 16362: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f7f140e93f0 reply={"return": [{"current": true, "CPU": 0, "pc": 4294967280, "halted": false, "thread_id": 30805}], "id": "libvirt-4"}
>2012-05-25 02:03:05.674+0000: 16362: debug : qemuMonitorJSONIOProcess:206 : Total used 117 bytes out of 117 available in buffer
>2012-05-25 02:03:05.674+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.675+0000: 16367: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x210c060
>2012-05-25 02:03:05.675+0000: 16367: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=2
>2012-05-25 02:03:05.675+0000: 16367: debug : qemuProcessStart:3589 : Setting cgroup for each VCPU(if required)
>2012-05-25 02:03:05.685+0000: 16367: debug : qemuProcessStart:3593 : Setting VCPU affinities
>2012-05-25 02:03:05.685+0000: 16367: debug : virNodeGetInfo:6398 : conn=0x7f7f1c0456d0, info=0x7f7f2b0a6820
>2012-05-25 02:03:05.685+0000: 16367: debug : qemuProcessStart:3597 : Setting any required VM passwords
>2012-05-25 02:03:05.686+0000: 16367: debug : qemuProcessStart:3612 : Setting network link states
>2012-05-25 02:03:05.686+0000: 16367: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.686+0000: 16367: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=2
>2012-05-25 02:03:05.686+0000: 16367: debug : qemuProcessStart:3625 : Setting initial memory amount
>2012-05-25 02:03:05.686+0000: 16367: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.686+0000: 16367: debug : qemuMonitorSetBalloon:1564 : mon=0x7f7f140e93f0 newmem=1048576
>2012-05-25 02:03:05.686+0000: 16367: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"balloon","arguments":{"value":1073741824},"id":"libvirt-5"}' for write with FD -1
>2012-05-25 02:03:05.686+0000: 16367: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f7f140e93f0 msg={"execute":"balloon","arguments":{"value":1073741824},"id":"libvirt-5"}
> fd=-1
>2012-05-25 02:03:05.686+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.686+0000: 16362: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f7f140e93f0 buf={"execute":"balloon","arguments":{"value":1073741824},"id":"libvirt-5"}
> len=73 ret=73 errno=11
>2012-05-25 02:03:05.686+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.686+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.686+0000: 16362: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f7f140e93f0 buf={"return": {}, "id": "libvirt-5"}
> len=35
>2012-05-25 02:03:05.686+0000: 16362: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": {}, "id": "libvirt-5"}]
>2012-05-25 02:03:05.686+0000: 16362: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f7f140e93f0 reply={"return": {}, "id": "libvirt-5"}
>2012-05-25 02:03:05.686+0000: 16362: debug : qemuMonitorJSONIOProcess:206 : Total used 35 bytes out of 35 available in buffer
>2012-05-25 02:03:05.686+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.687+0000: 16367: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x2111d30
>2012-05-25 02:03:05.687+0000: 16367: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=2
>2012-05-25 02:03:05.687+0000: 16367: debug : qemuProcessStart:3641 : Starting domain CPUs
>2012-05-25 02:03:05.687+0000: 16367: debug : qemuProcessStartCPUs:2554 : Using lock state '(null)'
>2012-05-25 02:03:05.687+0000: 16367: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.687+0000: 16367: debug : qemuMonitorStartCPUs:1061 : mon=0x7f7f140e93f0
>2012-05-25 02:03:05.687+0000: 16367: debug : qemuMonitorJSONCommandWithFd:248 : Send command '{"execute":"cont","id":"libvirt-6"}' for write with FD -1
>2012-05-25 02:03:05.687+0000: 16367: debug : qemuMonitorSend:823 : QEMU_MONITOR_SEND_MSG: mon=0x7f7f140e93f0 msg={"execute":"cont","id":"libvirt-6"}
> fd=-1
>2012-05-25 02:03:05.687+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.687+0000: 16362: debug : qemuMonitorIOWrite:432 : QEMU_MONITOR_IO_WRITE: mon=0x7f7f140e93f0 buf={"execute":"cont","id":"libvirt-6"}
> len=37 ret=37 errno=11
>2012-05-25 02:03:05.687+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.687+0000: 16362: debug : qemuMonitorRef:201 : QEMU_MONITOR_REF: mon=0x7f7f140e93f0 refs=4
>2012-05-25 02:03:05.687+0000: 16362: debug : qemuMonitorIOProcess:327 : QEMU_MONITOR_IO_PROCESS: mon=0x7f7f140e93f0 buf={"timestamp": {"seconds": 1337911385, "microseconds": 687799}, "event": "RESUME"}
>{"return": {}, "id": "libvirt-6"}
> len=118
>2012-05-25 02:03:05.687+0000: 16362: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"timestamp": {"seconds": 1337911385, "microseconds": 687799}, "event": "RESUME"}]
>2012-05-25 02:03:05.688+0000: 16362: debug : qemuMonitorJSONIOProcessLine:150 : QEMU_MONITOR_RECV_EVENT: mon=0x7f7f140e93f0 event={"timestamp": {"seconds": 1337911385, "microseconds": 687799}, "event": "RESUME"}
>2012-05-25 02:03:05.688+0000: 16362: debug : qemuMonitorJSONIOProcessEvent:107 : mon=0x7f7f140e93f0 obj=0x21044c0
>2012-05-25 02:03:05.688+0000: 16362: debug : qemuMonitorJSONIOProcessLine:135 : Line [{"return": {}, "id": "libvirt-6"}]
>2012-05-25 02:03:05.688+0000: 16362: debug : qemuMonitorJSONIOProcessLine:155 : QEMU_MONITOR_RECV_REPLY: mon=0x7f7f140e93f0 reply={"return": {}, "id": "libvirt-6"}
>2012-05-25 02:03:05.688+0000: 16362: debug : qemuMonitorJSONIOProcess:206 : Total used 118 bytes out of 118 available in buffer
>2012-05-25 02:03:05.688+0000: 16362: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=3
>2012-05-25 02:03:05.688+0000: 16367: debug : qemuMonitorJSONCommandWithFd:253 : Receive command reply ret=0 rxObject=0x21026a0
>2012-05-25 02:03:05.688+0000: 16367: debug : qemuMonitorUnref:210 : QEMU_MONITOR_UNREF: mon=0x7f7f140e93f0 refs=2
>2012-05-25 02:03:05.688+0000: 16367: debug : qemuProcessStart:3662 : Writing domain status to disk
>2012-05-25 02:03:05.753+0000: 16367: debug : qemuDomainObjEndJob:870 : Stopping job: modify (async=none)
>2012-05-25 02:03:05.803+0000: 16367: debug : virDomainObjUnref:1630 : obj=0x7f7f201b05f0 refs=2
>2012-05-25 02:03:05.803+0000: 16367: debug : virDomainFree:2313 : dom=0x7f7f1402aca0, (VM: name=kvm-rhel6u3-i386, uuid=96be2130-2400-eead-9e53-691a59d10c71)
>2012-05-25 02:03:05.804+0000: 16369: debug : virDomainLookupByUUID:2064 : conn=0x7f7f1c0456d0, uuid=96be2130-2400-eead-9e53-691a59d10c71
>2012-05-25 02:03:05.804+0000: 16369: debug : virDomainFree:2313 : dom=0x7f7f00020330, (VM: name=kvm-rhel6u3-i386, uuid=96be2130-2400-eead-9e53-691a59d10c71)
>2012-05-25 02:03:05.806+0000: 16362: debug : virConnectClose:1462 : conn=0x7f7f1c0456d0
>2012-05-25 02:03:05.806+0000: 16362: debug : qemuDriverCloseCallbackRunAll:661 : conn=0x7f7f1c0456d0
>2012-05-25 02:03:10.404+0000: 16367: debug : virConnectOpen:1349 : name=
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1155 : no name, allowing driver auto-select
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1195 : trying driver 0 (Test) ...
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1201 : driver 0 Test returned DECLINED
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1195 : trying driver 1 (ESX) ...
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1201 : driver 1 ESX returned DECLINED
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1195 : trying driver 2 (remote) ...
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1201 : driver 2 remote returned DECLINED
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1195 : trying driver 3 (QEMU) ...
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1201 : driver 3 QEMU returned SUCCESS
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1223 : network driver 0 Test returned DECLINED
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1223 : network driver 1 ESX returned DECLINED
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1223 : network driver 2 remote returned DECLINED
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1223 : network driver 3 Network returned SUCCESS
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1238 : interface driver 0 Test returned DECLINED
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1238 : interface driver 1 ESX returned DECLINED
>2012-05-25 02:03:10.404+0000: 16367: debug : do_open:1238 : interface driver 2 remote returned DECLINED
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1238 : interface driver 3 Interface returned SUCCESS
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1254 : storage driver 0 Test returned DECLINED
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1254 : storage driver 1 ESX returned DECLINED
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1254 : storage driver 2 remote returned DECLINED
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1254 : storage driver 3 storage returned SUCCESS
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1270 : node driver 0 Test returned DECLINED
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1270 : node driver 1 ESX returned DECLINED
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1270 : node driver 2 remote returned DECLINED
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1270 : node driver 3 udevDeviceMonitor returned SUCCESS
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1286 : secret driver 0 Test returned DECLINED
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1286 : secret driver 1 ESX returned DECLINED
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1286 : secret driver 2 remote returned DECLINED
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1286 : secret driver 3 secret returned SUCCESS
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1302 : nwfilter driver 0 Test returned DECLINED
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1302 : nwfilter driver 1 ESX returned DECLINED
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1302 : nwfilter driver 2 remote returned DECLINED
>2012-05-25 02:03:10.414+0000: 16367: debug : do_open:1302 : nwfilter driver 3 nwfilter returned SUCCESS
>2012-05-25 02:03:10.415+0000: 16366: debug : virConnectGetURI:1722 : conn=0x7f7f1414dda0
>2012-05-25 02:03:10.416+0000: 16370: debug : virDomainLookupByName:2146 : conn=0x7f7f1414dda0, name=kvm-rhel6u3-x86_64
>2012-05-25 02:03:10.416+0000: 16370: debug : virDomainFree:2313 : dom=0x7f7f20295610, (VM: name=kvm-rhel6u3-x86_64, uuid=e1d58f3e-82fe-5688-e569-8bfbbc4a1d64)
>2012-05-25 02:03:10.417+0000: 16364: debug : virDomainCreate:8065 : dom=0x7f7f1800a1b0, (VM: name=kvm-rhel6u3-x86_64, uuid=e1d58f3e-82fe-5688-e569-8bfbbc4a1d64)
>2012-05-25 02:03:10.417+0000: 16364: debug : virDomainObjRef:1623 : obj=0x7f7f201c8a00 refs=2
>2012-05-25 02:03:10.417+0000: 16364: debug : qemuDomainObjBeginJobInternal:753 : Starting job: modify (async=none)
>2012-05-25 02:03:10.417+0000: 16364: debug : qemuProcessStart:3202 : Beginning VM startup process
>2012-05-25 02:03:10.417+0000: 16364: debug : qemuProcessStart:3214 : Setting current domain def as transient
>2012-05-25 02:03:10.417+0000: 16364: debug : qemuProcessStart:3240 : Preparing host devices
>2012-05-25 02:03:10.418+0000: 16364: debug : qemuPrepareHostdevUSBDevices:583 : Requested operation is not valid: USB device 002:007 is in use by domain kvm-rhel6u3-i386
>2012-05-25 02:03:10.418+0000: 16364: debug : qemuProcessStop:3763 : Shutting down VM 'kvm-rhel6u3-x86_64' pid=-1 migrated=0
>2012-05-25 02:03:10.418+0000: 16364: debug : qemuProcessKill:3691 : vm=kvm-rhel6u3-x86_64 pid=-1 flags=1
>2012-05-25 02:03:10.418+0000: 16364: debug : qemuDomainCleanupRun:1921 : driver=0x7f7f20002ad0, vm=kvm-rhel6u3-x86_64
>2012-05-25 02:03:10.418+0000: 16364: debug : qemuProcessAutoDestroyRemove:4195 : vm=kvm-rhel6u3-x86_64
>2012-05-25 02:03:10.418+0000: 16364: debug : qemuDriverCloseCallbackUnset:578 : vm=kvm-rhel6u3-x86_64, uuid=e1d58f3e-82fe-5688-e569-8bfbbc4a1d64, cb=0x48e6f0
>2012-05-25 02:03:10.580+0000: 16364: error : qemuRemoveCgroup:592 : internal error Unable to find cgroup for kvm-rhel6u3-x86_64
>2012-05-25 02:03:10.580+0000: 16364: warning : qemuProcessStop:3897 : Failed to remove cgroup for kvm-rhel6u3-x86_64
>2012-05-25 02:03:10.580+0000: 16364: debug : qemuDomainObjEndJob:870 : Stopping job: modify (async=none)
>2012-05-25 02:03:10.580+0000: 16364: debug : virDomainObjUnref:1630 : obj=0x7f7f201c8a00 refs=1
>2012-05-25 02:03:10.580+0000: 16364: debug : virDomainFree:2313 : dom=0x7f7f1800a1b0, (VM: name=kvm-rhel6u3-x86_64, uuid=e1d58f3e-82fe-5688-e569-8bfbbc4a1d64)
>2012-05-25 02:03:10.582+0000: 16362: debug : virConnectClose:1462 : conn=0x7f7f1414dda0
>2012-05-25 02:03:10.582+0000: 16362: debug : qemuDriverCloseCallbackRunAll:661 : conn=0x7f7f1414dda0
>

Actions: View

Attachments on bug 825068: 586744