Attachment 641268 Details for Bug 872420 – crashed libvirtd log

crashed libvirtd log

libvirtd.log (text/plain), 64.83 KB, created by EricLee on 2012-11-09 06:03:21 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: EricLee

Created: 2012-11-09 06:03:21 UTC

Size: 64.83 KB

patch

obsolete

>2012-11-09 05:51:57.996+0000: 5685: warning : virCgroupMoveTask:885 : no vm cgroup in controller 3
>2012-11-09 05:51:57.996+0000: 5685: warning : virCgroupMoveTask:885 : no vm cgroup in controller 4
>2012-11-09 05:51:57.996+0000: 5685: warning : virCgroupMoveTask:885 : no vm cgroup in controller 6
>2012-11-09 06:00:29.448+0000: 5683: error : virNetSocketReadWire:1184 : End of file while reading data: Input/output error
>2012-11-09 06:00:40.673+0000: 5683: warning : qemuAgentNotifyEvent:1274 : Received unexpected event 1
>2012-11-09 06:00:40.679+0000: 5683: error : qemuMonitorIO:613 : internal error End of file from monitor
>2012-11-09 06:00:40.679+0000: 5683: error : qemuAgentIO:642 : internal error End of file from monitor
>Caught Segmentation violation dumping internal log buffer:
>
>
>    ====== start of log =====
>
>eouts:501 : Cleanup 6
>2012-11-09 06:00:40.198+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.198+00005690: debug : virConnectOpen:1331 : name=
>2012-11-09 06:00:40.198+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-09 06:00:40.198+00005690: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f3af0181df0 classname=virConnect
>2012-11-09 06:00:40.198+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-09 06:00:40.198+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-09 06:00:40.198+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-09 06:00:40.198+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-09 06:00:40.198+00005690: debug : virConnectGetConfigFile:953 : Loading config file '/etc/libvirt/libvirt.conf'
>2012-11-09 06:00:40.198+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-11-09 06:00:40.198+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-09 06:00:40.198+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=24 e=25 d=0
>2012-11-09 06:00:40.198+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=23 e=25 d=0
>2012-11-09 06:00:40.198+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=13, f=22 e=1 d=0
>2012-11-09 06:00:40.198+00005690: debug : virFileClose:72 : Closed fd 28
>2012-11-09 06:00:40.198+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=14, f=19 e=1 d=0
>2012-11-09 06:00:40.198+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=1 d=0
>2012-11-09 06:00:40.198+00005683: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 6 timers
>2012-11-09 06:00:40.198+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440843209
>2012-11-09 06:00:40.198+00005690: debug : do_open:1131 : no name, allowing driver auto-select
>2012-11-09 06:00:40.198+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440844024
>2012-11-09 06:00:40.198+00005690: debug : do_open:1174 : trying driver 0 (Test) ...
>2012-11-09 06:00:40.198+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440845198
>2012-11-09 06:00:40.198+00005690: debug : do_open:1180 : driver 0 Test returned DECLINED
>2012-11-09 06:00:40.198+00005683: debug : virEventPollCalculateTimeout:346 : Timeout at 1352440843209 due in 3011 ms
>2012-11-09 06:00:40.198+00005690: debug : do_open:1174 : trying driver 1 (ESX) ...
>2012-11-09 06:00:40.198+00005683: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3011
>2012-11-09 06:00:40.198+00005690: debug : do_open:1180 : driver 1 ESX returned DECLINED
>2012-11-09 06:00:40.198+00005690: debug : do_open:1174 : trying driver 2 (remote) ...
>2012-11-09 06:00:40.198+00005690: debug : do_open:1180 : driver 2 remote returned DECLINED
>2012-11-09 06:00:40.198+00005690: debug : do_open:1174 : trying driver 3 (QEMU) ...
>2012-11-09 06:00:40.198+00005690: debug : do_open:1180 : driver 3 QEMU returned SUCCESS
>2012-11-09 06:00:40.198+00005690: debug : do_open:1202 : network driver 0 Test returned DECLINED
>2012-11-09 06:00:40.198+00005690: debug : do_open:1202 : network driver 1 ESX returned DECLINED
>2012-11-09 06:00:40.198+00005690: debug : do_open:1202 : network driver 2 remote returned DECLINED
>2012-11-09 06:00:40.198+00005690: debug : do_open:1202 : network driver 3 Network returned SUCCESS
>2012-11-09 06:00:40.198+00005690: debug : do_open:1217 : interface driver 0 Test returned DECLINED
>2012-11-09 06:00:40.198+00005690: debug : do_open:1217 : interface driver 1 ESX returned DECLINED
>2012-11-09 06:00:40.198+00005690: debug : do_open:1217 : interface driver 2 remote returned DECLINED
>2012-11-09 06:00:40.205+00005690: debug : do_open:1217 : interface driver 3 Interface returned SUCCESS
>2012-11-09 06:00:40.205+00005690: debug : do_open:1233 : storage driver 0 Test returned DECLINED
>2012-11-09 06:00:40.205+00005690: debug : do_open:1233 : storage driver 1 ESX returned DECLINED
>2012-11-09 06:00:40.205+00005690: debug : do_open:1233 : storage driver 2 remote returned DECLINED
>2012-11-09 06:00:40.205+00005690: debug : do_open:1233 : storage driver 3 storage returned SUCCESS
>2012-11-09 06:00:40.205+00005690: debug : do_open:1249 : node driver 0 Test returned DECLINED
>2012-11-09 06:00:40.205+00005690: debug : do_open:1249 : node driver 1 ESX returned DECLINED
>2012-11-09 06:00:40.205+00005690: debug : do_open:1249 : node driver 2 remote returned DECLINED
>2012-11-09 06:00:40.205+00005690: debug : do_open:1249 : node driver 3 udevDeviceMonitor returned SUCCESS
>2012-11-09 06:00:40.205+00005690: debug : do_open:1265 : secret driver 0 Test returned DECLINED
>2012-11-09 06:00:40.205+00005690: debug : do_open:1265 : secret driver 1 ESX returned DECLINED
>2012-11-09 06:00:40.205+00005690: debug : do_open:1265 : secret driver 2 remote returned DECLINED
>2012-11-09 06:00:40.205+00005690: debug : do_open:1265 : secret driver 3 secret returned SUCCESS
>2012-11-09 06:00:40.205+00005690: debug : do_open:1281 : nwfilter driver 0 Test returned DECLINED
>2012-11-09 06:00:40.205+00005690: debug : do_open:1281 : nwfilter driver 1 ESX returned DECLINED
>2012-11-09 06:00:40.205+00005690: debug : do_open:1281 : nwfilter driver 2 remote returned DECLINED
>2012-11-09 06:00:40.205+00005690: debug : do_open:1281 : nwfilter driver 3 nwfilter returned SUCCESS
>2012-11-09 06:00:40.205+00005690: debug : virNetMessageEncodePayload:358 : Encode length as 28
>2012-11-09 06:00:40.205+00005690: debug : virNetServerClientSendMessageLocked:1116 : msg=0x12e6a10 proc=1 len=28 offset=0
>2012-11-09 06:00:40.205+00005690: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x12e8cc0 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2
>2012-11-09 06:00:40.205+00005690: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12e6b80 tx=0x12e6a10
>2012-11-09 06:00:40.205+00005690: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-11-09 06:00:40.205+00005690: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=3
>2012-11-09 06:00:40.205+00005690: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-09 06:00:40.205+00005690: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x12e9fd0
>2012-11-09 06:00:40.205+00005690: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x12e8cc0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchTimeouts:410 : Dispatch 6
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=9 w=13
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=10 w=14
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.205+00005683: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=24 e=25 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=23 e=25 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=13, f=22 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=14, f=19 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=5 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 6 timers
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440843209
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440844024
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440845198
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCalculateTimeout:346 : Timeout at 1352440843209 due in 3004 ms
>2012-11-09 06:00:40.205+00005683: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3004
>2012-11-09 06:00:40.205+00005683: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchTimeouts:410 : Dispatch 6
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=9 w=13
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=10 w=14
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=2
>2012-11-09 06:00:40.205+00005683: debug : virNetMessageFree:73 : msg=0x12e6a10 nfds=0 cb=(nil)
>2012-11-09 06:00:40.205+00005683: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12e6b80 tx=(nil)
>2012-11-09 06:00:40.205+00005683: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-09 06:00:40.205+00005683: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2012-11-09 06:00:40.205+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.205+00005683: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=24 e=25 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=23 e=25 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=13, f=22 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=14, f=19 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 6 timers
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440843209
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440844024
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440845198
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCalculateTimeout:346 : Timeout at 1352440843209 due in 3004 ms
>2012-11-09 06:00:40.205+00005683: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3004
>2012-11-09 06:00:40.205+00005683: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchTimeouts:410 : Dispatch 6
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=9 w=13
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=10 w=14
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2012-11-09 06:00:40.205+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=1
>2012-11-09 06:00:40.205+00005683: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2012-11-09 06:00:40.205+00005683: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12e6b80 tx=(nil)
>2012-11-09 06:00:40.205+00005683: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-09 06:00:40.205+00005683: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2012-11-09 06:00:40.205+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.205+00005683: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x12e8cc0 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2012-11-09 06:00:40.205+00005683: debug : virKeepAliveCheckMessage:384 : ka=0x12e6fe0, client=0x12e8cc0, msg=0x12e6b80
>2012-11-09 06:00:40.205+00005683: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=13 frequency=5000
>2012-11-09 06:00:40.205+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.205+00005683: debug : virObjectRef:168 : OBJECT_REF: obj=0x12e8cc0
>2012-11-09 06:00:40.205+00005683: debug : virNetServerDispatchNewMessage:218 : server=0x12ded20 client=0x12e8cc0 message=0x12e6b80
>2012-11-09 06:00:40.205+00005683: debug : virObjectRef:168 : OBJECT_REF: obj=0x12e9fd0
>2012-11-09 06:00:40.205+00005683: debug : virNetMessageNew:45 : msg=0x12e6c60 tracked=1
>2012-11-09 06:00:40.205+00005683: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12e6c60 tx=(nil)
>2012-11-09 06:00:40.205+00005684: debug : virNetServerHandleJob:189 : server=0x12ded20 client=0x12e8cc0 message=0x12e6b80 prog=0x12e9fd0
>2012-11-09 06:00:40.205+00005683: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-09 06:00:40.205+00005683: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2012-11-09 06:00:40.205+00005684: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110
>2012-11-09 06:00:40.205+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.205+00005683: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-09 06:00:40.205+00005684: debug : remoteDispatchGetURIHelper:7297 : server=0x12ded20 client=0x12e8cc0 msg=0x12e6b80 rerr=0x7f3b1e11bb80 args=0x7f3b10169c00 ret=0x7f3b100d6700
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.205+00005684: debug : virConnectGetURI:1700 : conn=0x7f3af0181df0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-09 06:00:40.205+00005684: debug : virNetMessageEncodePayload:358 : Encode length as 48
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-09 06:00:40.205+00005684: debug : virNetServerClientSendMessageLocked:1116 : msg=0x12e6b80 proc=110 len=48 offset=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-09 06:00:40.205+00005684: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x12e8cc0 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-09 06:00:40.205+00005684: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12e6c60 tx=0x12e6b80
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-11-09 06:00:40.205+00005684: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-09 06:00:40.205+00005684: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=3
>2012-11-09 06:00:40.205+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=24 e=25 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=23 e=25 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=13, f=22 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=14, f=19 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 6 timers
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440843209
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440844024
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440845205
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:346 : Timeout at 1352440843209 due in 3003 ms
>2012-11-09 06:00:40.206+00005683: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3003
>2012-11-09 06:00:40.206+00005684: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-09 06:00:40.206+00005684: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x12e9fd0
>2012-11-09 06:00:40.206+00005684: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x12e8cc0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchTimeouts:410 : Dispatch 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=9 w=13
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=10 w=14
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.206+00005683: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=24 e=25 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=23 e=25 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=13, f=22 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=14, f=19 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=5 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 6 timers
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440843209
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440844024
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440845205
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:346 : Timeout at 1352440843209 due in 3003 ms
>2012-11-09 06:00:40.206+00005683: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3003
>2012-11-09 06:00:40.206+00005683: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchTimeouts:410 : Dispatch 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=9 w=13
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=10 w=14
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=2
>2012-11-09 06:00:40.206+00005683: debug : virNetMessageFree:73 : msg=0x12e6b80 nfds=0 cb=(nil)
>2012-11-09 06:00:40.206+00005683: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12e6c60 tx=(nil)
>2012-11-09 06:00:40.206+00005683: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.206+00005683: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=24 e=25 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=23 e=25 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=13, f=22 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=14, f=19 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 6 timers
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440843209
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440844024
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440845205
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:346 : Timeout at 1352440843209 due in 3003 ms
>2012-11-09 06:00:40.206+00005683: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3003
>2012-11-09 06:00:40.206+00005683: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchTimeouts:410 : Dispatch 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=9 w=13
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=10 w=14
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=1
>2012-11-09 06:00:40.206+00005683: debug : virNetMessageDecodeLength:152 : Got length, now need 36 total (32 more)
>2012-11-09 06:00:40.206+00005683: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12e6c60 tx=(nil)
>2012-11-09 06:00:40.206+00005683: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.206+00005683: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x12e8cc0 len=36 prog=536903814 vers=1 proc=23 type=0 status=0 serial=4
>2012-11-09 06:00:40.206+00005683: debug : virKeepAliveCheckMessage:384 : ka=0x12e6fe0, client=0x12e8cc0, msg=0x12e6c60
>2012-11-09 06:00:40.206+00005683: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=13 frequency=5000
>2012-11-09 06:00:40.206+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.206+00005683: debug : virObjectRef:168 : OBJECT_REF: obj=0x12e8cc0
>2012-11-09 06:00:40.206+00005683: debug : virNetServerDispatchNewMessage:218 : server=0x12ded20 client=0x12e8cc0 message=0x12e6c60
>2012-11-09 06:00:40.206+00005683: debug : virObjectRef:168 : OBJECT_REF: obj=0x12e9fd0
>2012-11-09 06:00:40.206+00005683: debug : virNetMessageNew:45 : msg=0x12e85d0 tracked=1
>2012-11-09 06:00:40.206+00005683: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12e85d0 tx=(nil)
>2012-11-09 06:00:40.206+00005683: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-09 06:00:40.206+00005692: debug : virNetServerHandleJob:189 : server=0x12ded20 client=0x12e8cc0 message=0x12e6c60 prog=0x12e9fd0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.206+00005692: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=23
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.206+00005692: debug : remoteDispatchDomainLookupByNameHelper:3084 : server=0x12ded20 client=0x12e8cc0 msg=0x12e6c60 rerr=0x7f3b19113b80 args=0x7f3af40009a0 ret=0x7f3af4000ce0
>2012-11-09 06:00:40.206+00005683: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-09 06:00:40.206+00005692: debug : virDomainLookupByName:2117 : conn=0x7f3af0181df0, name=test
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-09 06:00:40.206+00005692: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f3af4000ca0 classname=virDomain
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-09 06:00:40.206+00005692: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f3af0181df0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-09 06:00:40.206+00005692: debug : virDomainFree:2281 : dom=0x7f3af4000ca0, (VM: name=test, uuid=aa5d3f4d-ac22-c76a-aa95-53bfb224f47a)
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-11-09 06:00:40.206+00005692: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3af4000ca0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-09 06:00:40.206+00005692: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f3af4000ca0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=24 e=25 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=23 e=25 d=0
>2012-11-09 06:00:40.206+00005692: debug : virDomainDispose:225 : release domain 0x7f3af4000ca0 test aa5d3f4d-ac22-c76a-aa95-53bfb224f47a
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=13, f=22 e=1 d=0
>2012-11-09 06:00:40.206+00005692: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3af0181df0
>2012-11-09 06:00:40.206+00005692: debug : virNetMessageEncodePayload:358 : Encode length as 56
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=14, f=19 e=1 d=0
>2012-11-09 06:00:40.206+00005692: debug : virNetServerClientSendMessageLocked:1116 : msg=0x12e6c60 proc=23 len=56 offset=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 6 timers
>2012-11-09 06:00:40.206+00005692: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x12e8cc0 len=56 prog=536903814 vers=1 proc=23 type=1 status=0 serial=4
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440843209
>2012-11-09 06:00:40.206+00005692: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12e85d0 tx=0x12e6c60
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440844024
>2012-11-09 06:00:40.206+00005692: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440845206
>2012-11-09 06:00:40.206+00005692: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=3
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:346 : Timeout at 1352440843209 due in 3003 ms
>2012-11-09 06:00:40.206+00005683: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3003
>2012-11-09 06:00:40.206+00005692: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-09 06:00:40.206+00005692: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x12e9fd0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-09 06:00:40.206+00005692: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x12e8cc0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchTimeouts:410 : Dispatch 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=9 w=13
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=10 w=14
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.206+00005683: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=24 e=25 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=23 e=25 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=13, f=22 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=14, f=19 e=1 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=5 d=0
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 6 timers
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440843209
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440844024
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440845206
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCalculateTimeout:346 : Timeout at 1352440843209 due in 3003 ms
>2012-11-09 06:00:40.206+00005683: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3003
>2012-11-09 06:00:40.206+00005683: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchTimeouts:410 : Dispatch 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=9 w=13
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=10 w=14
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2012-11-09 06:00:40.206+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=2
>2012-11-09 06:00:40.206+00005683: debug : virNetMessageFree:73 : msg=0x12e6c60 nfds=0 cb=(nil)
>2012-11-09 06:00:40.206+00005683: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12e85d0 tx=(nil)
>2012-11-09 06:00:40.206+00005683: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2012-11-09 06:00:40.206+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.206+00005683: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-09 06:00:40.206+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.207+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d2012-11-09 06:00:40.679+00005686: debug : virNetServerProgramSendError:150 : prog=536903814 ver=1 proc=261 type=1 serial=5 msg=0x12e7860 rerr=0x7f3b1cd19b80
>2012-11-09 06:00:40.679+00005686: debug : virNetMessageEncodePayload:358 : Encode length as 168
>2012-11-09 06:00:40.679+00005686: debug : virNetServerClientSendMessageLocked:1116 : msg=0x12e7860 proc=261 len=168 offset=0
>2012-11-09 06:00:40.679+00005686: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x12e7ca0 len=168 prog=536903814 vers=1 proc=261 type=1 status=1 serial=5
>2012-11-09 06:00:40.679+00005686: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12e7d80 tx=0x12e7860
>2012-11-09 06:00:40.679+00005686: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-11-09 06:00:40.679+00005686: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=3
>2012-11-09 06:00:40.679+00005686: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-09 06:00:40.679+00005686: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x12e9fd0
>2012-11-09 06:00:40.679+00005686: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x12e7ca0
>tPollCalculateTimeout:326 : Got a timeout scheduled for 1352440843209
>2012-11-09 06:00:40.207+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440844024
>2012-11-09 06:00:40.207+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440845206
>2012-11-09 06:00:40.207+00005683: debug : virEventPollCalculateTimeout:346 : Timeout at 1352440843209 due in 3002 ms
>2012-11-09 06:00:40.207+00005683: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3002
>2012-11-09 06:00:40.207+00005683: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchTimeouts:410 : Dispatch 6
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=9 w=13
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=10 w=14
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=1
>2012-11-09 06:00:40.207+00005683: debug : virNetMessageDecodeLength:152 : Got length, now need 56 total (52 more)
>2012-11-09 06:00:40.207+00005683: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12e85d0 tx=(nil)
>2012-11-09 06:00:40.207+00005683: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-09 06:00:40.207+00005683: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2012-11-09 06:00:40.207+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.207+00005683: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x12e8cc0 len=56 prog=536903814 vers=1 proc=12 type=0 status=0 serial=5
>2012-11-09 06:00:40.207+00005683: debug : virKeepAliveCheckMessage:384 : ka=0x12e6fe0, client=0x12e8cc0, msg=0x12e85d0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=13 frequency=5000
>2012-11-09 06:00:40.207+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.207+00005683: debug : virObjectRef:168 : OBJECT_REF: obj=0x12e8cc0
>2012-11-09 06:00:40.207+00005683: debug : virNetServerDispatchNewMessage:218 : server=0x12ded20 client=0x12e8cc0 message=0x12e85d0
>2012-11-09 06:00:40.207+00005683: debug : virObjectRef:168 : OBJECT_REF: obj=0x12e9fd0
>2012-11-09 06:00:40.207+00005683: debug : virNetMessageNew:45 : msg=0x12e80b0 tracked=1
>2012-11-09 06:00:40.207+00005683: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12e80b0 tx=(nil)
>2012-11-09 06:00:40.207+00005693: debug : virNetServerHandleJob:189 : server=0x12ded20 client=0x12e8cc0 message=0x12e85d0 prog=0x12e9fd0
>2012-11-09 06:00:40.207+00005683: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-09 06:00:40.207+00005683: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2012-11-09 06:00:40.207+00005693: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=12
>2012-11-09 06:00:40.207+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.207+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.207+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.207+00005683: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-09 06:00:40.207+00005693: debug : remoteDispatchDomainDestroyHelper:1254 : server=0x12ded20 client=0x12e8cc0 msg=0x12e85d0 rerr=0x7f3b18712b80 args=0x7f3afc000c40 ret=0x7f3afc0008c0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.207+00005693: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f3afc000c00 classname=virDomain
>2012-11-09 06:00:40.207+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.207+00005693: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f3af0181df0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-09 06:00:40.207+00005693: debug : virDomainDestroy:2172 : dom=0x7f3afc000c00, (VM: name=test, uuid=aa5d3f4d-ac22-c76a-aa95-53bfb224f47a)
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-09 06:00:40.207+00005693: debug : qemuProcessKill:3959 : vm=test pid=5784 flags=1
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=24 e=25 d=0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=23 e=25 d=0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=13, f=22 e=1 d=0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=14, f=19 e=1 d=0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=1 d=0
>2012-11-09 06:00:40.207+00005683: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 6 timers
>2012-11-09 06:00:40.207+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440843209
>2012-11-09 06:00:40.207+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440844024
>2012-11-09 06:00:40.207+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440845207
>2012-11-09 06:00:40.207+00005683: debug : virEventPollCalculateTimeout:346 : Timeout at 1352440843209 due in 3002 ms
>2012-11-09 06:00:40.207+00005683: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3002
>2012-11-09 06:00:40.207+00005683: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchTimeouts:410 : Dispatch 6
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2012-11-09 06:00:40.207+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=1
>2012-11-09 06:00:40.207+00005683: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f3b08003230
>2012-11-09 06:00:40.207+00005683: debug : qemuMonitorIOProcess:353 : QEMU_MONITOR_IO_PROCESS: mon=0x7f3b08003230 buf={"timestamp": {"seconds": 1352440840, "microseconds": 207686}, "event": "SHUTDOWN"}
> len=85
>2012-11-09 06:00:40.207+00005683: debug : qemuMonitorJSONIOProcessLine:150 : Line [{"timestamp": {"seconds": 1352440840, "microseconds": 207686}, "event": "SHUTDOWN"}]
>2012-11-09 06:00:40.207+00005683: debug : virJSONValueFromString:975 : string={"timestamp": {"seconds": 1352440840, "microseconds": 207686}, "event": "SHUTDOWN"}
>2012-11-09 06:00:40.207+00005683: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd9282a20
>2012-11-09 06:00:40.207+00005683: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd9282a20 key=0x12e74c2
>2012-11-09 06:00:40.207+00005683: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd9282a20
>2012-11-09 06:00:40.207+00005683: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd9282a20 key=0x12e74d0
>2012-11-09 06:00:40.207+00005683: debug : virJSONParserHandleNumber:792 : parser=0x7fffd9282a20 str=(null)
>2012-11-09 06:00:40.207+00005683: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd9282a20 key=0x12e74e7
>2012-11-09 06:00:40.207+00005683: debug : virJSONParserHandleNumber:792 : parser=0x7fffd9282a20 str=(null)
>2012-11-09 06:00:40.207+00005683: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd9282a20
>2012-11-09 06:00:40.207+00005683: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd9282a20 key=0x12e7500
>2012-11-09 06:00:40.207+00005683: debug : virJSONParserHandleString:813 : parser=0x7fffd9282a20 str=0x12e7509
>2012-11-09 06:00:40.207+00005683: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd9282a20
>2012-11-09 06:00:40.207+00005683: debug : virJSONValueFromString:1019 : result=0x12ca290
>2012-11-09 06:00:40.207+00005683: debug : qemuMonitorJSONIOProcessLine:165 : QEMU_MONITOR_RECV_EVENT: mon=0x7f3b08003230 event={"timestamp": {"seconds": 1352440840, "microseconds": 207686}, "event": "SHUTDOWN"}
>2012-11-09 06:00:40.207+00005683: debug : qemuMonitorJSONIOProcessEvent:122 : mon=0x7f3b08003230 obj=0x12ca290
>2012-11-09 06:00:40.207+00005683: debug : qemuMonitorJSONIOProcessEvent:136 : handle SHUTDOWN handler=0x4b6400 data=(nil)
>2012-11-09 06:00:40.207+00005683: debug : qemuMonitorEmitShutdown:987 : mon=0x7f3b08003230
>2012-11-09 06:00:40.207+00005683: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f3b08003230
>2012-11-09 06:00:40.207+00005683: debug : qemuProcessHandleShutdown:637 : vm=0x7f3b1000d360
>2012-11-09 06:00:40.607+00005693: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f3b1000d360
>2012-11-09 06:00:40.607+00005693: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3b1000d360
>2012-11-09 06:00:40.607+00005693: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f3b1000d360
>2012-11-09 06:00:40.607+00005683: debug : qemuProcessHandleShutdown:654 : Transitioned guest test to shutdown state
>2012-11-09 06:00:40.664+00005683: debug : virFileClose:72 : Closed fd 30
>2012-11-09 06:00:40.673+00005683: debug : qemuAgentNotifyEvent:1263 : mon=0x7f3b080033b0 event=1
>2012-11-09 06:00:40.673+00005683: warning : qemuAgentNotifyEvent:1274 : Received unexpected event 1
>2012-11-09 06:00:40.673+00005683: debug : qemuProcessKill:3959 : vm=test pid=5784 flags=2
>2012-11-09 06:00:40.673+00005683: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3b08003230
>2012-11-09 06:00:40.673+00005683: debug : qemuMonitorJSONIOProcess:221 : Total used 85 bytes out of 85 available in buffer
>2012-11-09 06:00:40.673+00005683: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=13
>2012-11-09 06:00:40.673+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.673+00005683: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3b08003230
>2012-11-09 06:00:40.673+00005683: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2012-11-09 06:00:40.673+00005683: debug : virEventPollDispatchHandles:469 : i=9 w=13
>2012-11-09 06:00:40.673+00005683: debug : virEventPollDispatchHandles:469 : i=10 w=14
>2012-11-09 06:00:40.673+00005683: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2012-11-09 06:00:40.673+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.673+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.673+00005683: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-09 06:00:40.673+00005683: debug : virEventPollCleanupTimeouts:501 : Cleanup 6
>2012-11-09 06:00:40.673+00005683: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-09 06:00:40.673+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-09 06:00:40.673+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-09 06:00:40.673+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-09 06:00:40.673+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-09 06:00:40.673+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-09 06:00:40.673+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-11-09 06:00:40.673+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-09 06:00:40.673+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=24 e=25 d=0
>2012-11-09 06:00:40.673+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=23 e=25 d=0
>2012-11-09 06:00:40.673+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=13, f=22 e=1 d=0
>2012-11-09 06:00:40.673+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=14, f=19 e=1 d=0
>2012-11-09 06:00:40.673+00005683: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=1 d=0
>2012-11-09 06:00:40.673+00005683: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 6 timers
>2012-11-09 06:00:40.673+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440843209
>2012-11-09 06:00:40.673+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440844024
>2012-11-09 06:00:40.673+00005683: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352440845207
>2012-11-09 06:00:40.673+00005683: debug : virEventPollCalculateTimeout:346 : Timeout at 1352440843209 due in 2536 ms
>2012-11-09 06:00:40.673+00005683: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=2536
>2012-11-09 06:00:40.673+00005683: debug : virEventPollRunOnce:625 : Poll got 4 event(s)
>2012-11-09 06:00:40.673+00005683: debug : virEventPollDispatchTimeouts:410 : Dispatch 6
>2012-11-09 06:00:40.673+00005683: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-09 06:00:40.673+00005683: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-09 06:00:40.673+00005683: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-09 06:00:40.673+00005683: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-09 06:00:40.673+00005683: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-09 06:00:40.673+00005683: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-09 06:00:40.673+00005683: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-09 06:00:40.673+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
>2012-11-09 06:00:40.679+00005683: debug : virNetlinkEventCallback:346 : dispatching to max 0 clients, called from event watch 6
>2012-11-09 06:00:40.679+00005683: debug : virNetlinkEventCallback:359 : event not handled.
>2012-11-09 06:00:40.679+00005683: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-09 06:00:40.679+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
>2012-11-09 06:00:40.679+00005683: debug : udevEventHandleCallback:1467 : udev action: 'remove'
>2012-11-09 06:00:40.679+00005683: debug : udevRemoveOneDevice:1218 : Failed to find device to remove that has udev name '/sys/devices/virtual/net/vnet0/queues/tx-0'
>2012-11-09 06:00:40.679+00005683: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2012-11-09 06:00:40.679+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=9
>2012-11-09 06:00:40.679+00005683: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f3b08003230
>2012-11-09 06:00:40.679+00005683: error : qemuMonitorIO:613 : internal error End of file from monitor
>2012-11-09 06:00:40.679+00005683: debug : qemuMonitorIO:646 : Error on monitor internal error End of file from monitor
>2012-11-09 06:00:40.679+00005683: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=12
>2012-11-09 06:00:40.679+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.679+00005683: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3b08003230
>2012-11-09 06:00:40.679+00005683: debug : qemuMonitorIO:669 : Triggering EOF callback
>2012-11-09 06:00:40.679+00005683: debug : qemuProcessHandleMonitorEOF:282 : Received EOF on 0x7f3b1000d360 'test'
>2012-11-09 06:00:40.679+00005683: debug : qemuProcessHandleMonitorEOF:290 : Domain is being destroyed, EOF is expected
>2012-11-09 06:00:40.679+00005683: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2012-11-09 06:00:40.679+00005683: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=9
>2012-11-09 06:00:40.679+00005683: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f3b080033b0
>2012-11-09 06:00:40.679+00005683: error : qemuAgentIO:642 : internal error End of file from monitor
>2012-11-09 06:00:40.679+00005683: debug : qemuAgentIO:675 : Error on monitor internal error End of file from monitor
>2012-11-09 06:00:40.679+00005683: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=12
>2012-11-09 06:00:40.679+00005683: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 603646048
>2012-11-09 06:00:40.679+00005683: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3b080033b0
>2012-11-09 06:00:40.679+00005683: debug : qemuAgentIO:698 : Triggering EOF callback
>2012-11-09 06:00:40.679+00005683: debug : qemuProcessHandleAgentEOF:125 : Received EOF from agent on 0x7f3b1000d360 'test'
>2012-11-09 06:00:40.679+00005683: debug : qemuAgentClose:814 : mon=0x7f3b080033b0
>2012-11-09 06:00:40.679+00005686: debug : qemuAgentSend:900 : Send command resulted in error internal error End of file from monitor
>2012-11-09 06:00:40.679+00005686: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=12
>2012-11-09 06:00:40.679+00005686: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-09 06:00:40.679+00005686: debug : qemuAgentCommand:1023 : Receive command reply ret=-1 rxObject=(nil)
>2012-11-09 06:00:40.679+00005686: debug : qemuDomainObjEndJob:936 : Stopping job: modify (async=none)
>2012-11-09 06:00:40.679+00005686: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3b1000d360
>2012-11-09 06:00:40.679+00005688: debug : qemuDomainObjBeginJobInternal:807 : Starting job: modify (async=none)
>2012-11-09 06:00:40.679+00005686: debug : virDomainFree:2281 : dom=0x7f3af80008e0, (VM: name=test, uuid=aa5d3f4d-ac22-c76a-aa95-53bfb224f47a)
>2012-11-09 06:00:40.679+00005686: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3af80008e0
>2012-11-09 06:00:40.679+00005686: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f3af80008e0
>2012-11-09 06:00:40.679+00005686: debug : virDomainDispose:225 : release domain 0x7f3af80008e0 test aa5d3f4d-ac22-c76a-aa95-53bfb224f47a
>2012-11-09 06:00:40.679+00005686: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3af0000a90
>
>
>     ====== end of log =====
>

Actions: View

Attachments on bug 872420: 636969 | 636989 | 641268 | 649590