Attachment 682311 Details for Bug 892079 – libvirtd crash log

libvirtd crash log

libvirtd.log (text/plain), 64.06 KB, created by zhpeng on 2013-01-18 10:32:38 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: zhpeng

Created: 2013-01-18 10:32:38 UTC

Size: 64.06 KB

patch

obsolete

>
>    ====== start of log =====
>
>patch 8
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:469 : i=7 w=9
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:469 : i=8 w=10
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2013-01-18 10:22:26.038+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=2
>2013-01-18 10:22:26.038+000012859: debug : virNetMessageFree:73 : msg=0xf29a60 nfds=0 cb=(nil)
>2013-01-18 10:22:26.038+000012859: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf2cba0 tx=(nil)
>2013-01-18 10:22:26.038+000012859: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-01-18 10:22:26.038+000012859: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2013-01-18 10:22:26.038+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.038+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.038+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-01-18 10:22:26.038+000012859: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-01-18 10:22:26.038+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.038+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-01-18 10:22:26.038+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-01-18 10:22:26.038+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-01-18 10:22:26.038+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-01-18 10:22:26.038+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-01-18 10:22:26.038+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-01-18 10:22:26.038+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-01-18 10:22:26.038+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-01-18 10:22:26.038+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=24 e=25 d=0
>2013-01-18 10:22:26.038+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=10, f=23 e=25 d=0
>2013-01-18 10:22:26.038+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=19 e=1 d=0
>2013-01-18 10:22:26.038+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=22 e=1 d=0
>2013-01-18 10:22:26.038+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=1 d=0
>2013-01-18 10:22:26.038+000012859: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2013-01-18 10:22:26.038+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504548992
>2013-01-18 10:22:26.038+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504547925
>2013-01-18 10:22:26.038+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504549038
>2013-01-18 10:22:26.038+000012859: debug : virEventPollCalculateTimeout:346 : Timeout at 1358504547925 due in 1887 ms
>2013-01-18 10:22:26.038+000012859: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=1887
>2013-01-18 10:22:26.039+000012859: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=7 w=9
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=8 w=10
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=1
>2013-01-18 10:22:26.039+000012859: debug : virNetMessageDecodeLength:152 : Got length, now need 36 total (32 more)
>2013-01-18 10:22:26.039+000012859: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf2cba0 tx=(nil)
>2013-01-18 10:22:26.039+000012859: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.039+000012859: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0xf2ba40 len=36 prog=536903814 vers=1 proc=23 type=0 status=0 serial=4
>2013-01-18 10:22:26.039+000012859: debug : virKeepAliveCheckMessage:384 : ka=0xf2a2d0, client=0xf2ba40, msg=0xf2cba0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=13 frequency=3000
>2013-01-18 10:22:26.039+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.039+000012859: debug : virObjectRef:168 : OBJECT_REF: obj=0xf2ba40
>2013-01-18 10:22:26.039+000012859: debug : virNetServerDispatchNewMessage:218 : server=0xf22ce0 client=0xf2ba40 message=0xf2cba0
>2013-01-18 10:22:26.039+000012859: debug : virObjectRef:168 : OBJECT_REF: obj=0xf2df50
>2013-01-18 10:22:26.039+000012859: debug : virNetMessageNew:45 : msg=0xf2a9d0 tracked=1
>2013-01-18 10:22:26.039+000012859: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf2a9d0 tx=(nil)
>2013-01-18 10:22:26.039+000012860: debug : virNetServerHandleJob:189 : server=0xf22ce0 client=0xf2ba40 message=0xf2cba0 prog=0xf2df50
>2013-01-18 10:22:26.039+000012859: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2013-01-18 10:22:26.039+000012860: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=23
>2013-01-18 10:22:26.039+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-01-18 10:22:26.039+000012859: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-01-18 10:22:26.039+000012860: debug : remoteDispatchDomainLookupByNameHelper:3084 : server=0xf22ce0 client=0xf2ba40 msg=0xf2cba0 rerr=0x7f801796fb80 args=0x7f80080d3c30 ret=0x7f80080d3c90
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.039+000012860: debug : virDomainLookupByName:2117 : conn=0x7f7ff81816f0, name=win7
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-01-18 10:22:26.039+000012860: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f80080d3c50 classname=virDomain
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-01-18 10:22:26.039+000012860: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f7ff81816f0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-01-18 10:22:26.039+000012860: debug : virDomainFree:2281 : dom=0x7f80080d3c50, (VM: name=win7, uuid=5328d409-e0a9-125f-9c8e-f7b49edb72db)
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-01-18 10:22:26.039+000012860: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080d3c50
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=24 e=25 d=0
>2013-01-18 10:22:26.039+000012860: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f80080d3c50
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=10, f=23 e=25 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=19 e=1 d=0
>2013-01-18 10:22:26.039+000012860: debug : virDomainDispose:225 : release domain 0x7f80080d3c50 win7 5328d409-e0a9-125f-9c8e-f7b49edb72db
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=22 e=1 d=0
>2013-01-18 10:22:26.039+000012860: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f7ff81816f0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=1 d=0
>2013-01-18 10:22:26.039+000012860: debug : virNetMessageEncodePayload:358 : Encode length as 56
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2013-01-18 10:22:26.039+000012860: debug : virNetServerClientSendMessageLocked:1116 : msg=0xf2cba0 proc=23 len=56 offset=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504548992
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504547925
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504549039
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:346 : Timeout at 1358504547925 due in 1886 ms
>2013-01-18 10:22:26.039+000012860: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0xf2ba40 len=56 prog=536903814 vers=1 proc=23 type=1 status=0 serial=4
>2013-01-18 10:22:26.039+000012859: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=1886
>2013-01-18 10:22:26.039+000012860: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf2a9d0 tx=0xf2cba0
>2013-01-18 10:22:26.039+000012860: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-01-18 10:22:26.039+000012860: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=3
>2013-01-18 10:22:26.039+000012860: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-01-18 10:22:26.039+000012860: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xf2df50
>2013-01-18 10:22:26.039+000012860: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xf2ba40
>2013-01-18 10:22:26.039+000012859: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=7 w=9
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=8 w=10
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-01-18 10:22:26.039+000012859: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=24 e=25 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=10, f=23 e=25 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=19 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=22 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=5 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504548992
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504547925
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504549039
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:346 : Timeout at 1358504547925 due in 1886 ms
>2013-01-18 10:22:26.039+000012859: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=1886
>2013-01-18 10:22:26.039+000012859: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=7 w=9
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=8 w=10
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=2
>2013-01-18 10:22:26.039+000012859: debug : virNetMessageFree:73 : msg=0xf2cba0 nfds=0 cb=(nil)
>2013-01-18 10:22:26.039+000012859: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf2a9d0 tx=(nil)
>2013-01-18 10:22:26.039+000012859: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-01-18 10:22:26.039+000012859: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=24 e=25 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=10, f=23 e=25 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=19 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=22 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504548992
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504547925
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504549039
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:346 : Timeout at 1358504547925 due in 1886 ms
>2013-01-18 10:22:26.039+000012859: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=1886
>2013-01-18 10:22:26.039+000012859: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=7 w=9
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=8 w=10
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2013-01-18 10:22:26.039+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=1
>2013-01-18 10:22:26.039+000012859: debug : virNetMessageDecodeLength:152 : Got length, now need 56 total (52 more)
>2013-01-18 10:22:26.039+000012859: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf2a9d0 tx=(nil)
>2013-01-18 10:22:26.039+000012859: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.039+000012859: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0xf2ba40 len=56 prog=536903814 vers=1 proc=12 type=0 status=0 serial=5
>2013-01-18 10:22:26.039+000012859: debug : virKeepAliveCheckMessage:384 : ka=0xf2a2d0, client=0xf2ba40, msg=0xf2a9d0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=13 frequency=3000
>2013-01-18 10:22:26.039+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.039+000012859: debug : virObjectRef:168 : OBJECT_REF: obj=0xf2ba40
>2013-01-18 10:22:26.039+000012859: debug : virNetServerDispatchNewMessage:218 : server=0xf22ce0 client=0xf2ba40 message=0xf2a9d0
>2013-01-18 10:22:26.039+000012859: debug : virObjectRef:168 : OBJECT_REF: obj=0xf2df50
>2013-01-18 10:22:26.039+000012859: debug : virNetMessageNew:45 : msg=0xf2d420 tracked=1
>2013-01-18 10:22:26.039+000012859: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf2d420 tx=(nil)
>2013-01-18 10:22:26.039+000012864: debug : virNetServerHandleJob:189 : server=0xf22ce0 client=0xf2ba40 message=0xf2a9d0 prog=0xf2df50
>2013-01-18 10:22:26.039+000012859: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-01-18 10:22:26.039+000012864: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=12
>2013-01-18 10:22:26.039+000012859: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-01-18 10:22:26.039+000012859: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-01-18 10:22:26.039+000012864: debug : remoteDispatchDomainDestroyHelper:1254 : server=0xf22ce0 client=0xf2ba40 msg=0xf2a9d0 rerr=0x7f801516bb80 args=0x7f7ffc001e10 ret=0x7f7ffc001db0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.039+000012864: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f7ffc001dd0 classname=virDomain
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-01-18 10:22:26.039+000012864: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f7ff81816f0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-01-18 10:22:26.039+000012864: debug : virDomainDestroy:2172 : dom=0x7f7ffc001dd0, (VM: name=win7, uuid=5328d409-e0a9-125f-9c8e-f7b49edb72db)
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-01-18 10:22:26.039+000012864: debug : qemuProcessKill:4104 : vm=win7 pid=12954 flags=1
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=24 e=25 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=10, f=23 e=25 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=19 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=22 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=1 d=0
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504548992
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504547925
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504549039
>2013-01-18 10:22:26.039+000012859: debug : virEventPollCalculateTimeout:346 : Timeout at 1358504547925 due in 1886 ms
>2013-01-18 10:22:26.039+000012859: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=1886
>2013-01-18 10:22:26.040+000012859: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-01-18 10:22:26.040+000012859: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2013-01-18 10:22:26.040+000012859: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2013-01-18 10:22:26.040+000012859: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-01-18 10:22:26.040+000012859: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-01-18 10:22:26.040+000012859: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-01-18 10:22:26.040+000012859: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-01-18 10:22:26.040+000012859: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-01-18 10:22:26.040+000012859: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-01-18 10:22:26.040+000012859: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-01-18 10:22:26.040+000012859: debug : virEventPollDispatchHandles:469 : i=7 w=9
>2013-01-18 10:22:26.040+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=1
>2013-01-18 10:22:26.040+000012859: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f80080affc0
>2013-01-18 10:22:26.040+000012859: debug : qemuMonitorIOProcess:353 : QEMU_MONITOR_IO_PROCESS: mon=0x7f80080affc0 buf={"timestamp": {"seconds": 1358504546, "microseconds": 40335}, "event": "SHUTDOWN"}
> len=84
>2013-01-18 10:22:26.040+000012859: debug : qemuMonitorJSONIOProcessLine:152 : Line [{"timestamp": {"seconds": 1358504546, "microseconds": 40335}, "event": "SHUTDOWN"}]
>2013-01-18 10:22:26.040+000012859: debug : virJSONValueFromString:975 : string={"timestamp": {"seconds": 1358504546, "microseconds": 40335}, "event": "SHUTDOWN"}
>2013-01-18 10:22:26.040+000012859: debug : virJSONParserHandleStartMap:852 : parser=0x7fffe9c4ae50
>2013-01-18 10:22:26.040+000012859: debug : virJSONParserHandleMapKey:833 : parser=0x7fffe9c4ae50 key=0xf2d352
>2013-01-18 10:22:26.040+000012859: debug : virJSONParserHandleStartMap:852 : parser=0x7fffe9c4ae50
>2013-01-18 10:22:26.040+000012859: debug : virJSONParserHandleMapKey:833 : parser=0x7fffe9c4ae50 key=0xf2d360
>2013-01-18 10:22:26.040+000012859: debug : virJSONParserHandleNumber:792 : parser=0x7fffe9c4ae50 str=(null)
>2013-01-18 10:22:26.040+000012859: debug : virJSONParserHandleMapKey:833 : parser=0x7fffe9c4ae50 key=0xf2d377
>2013-01-18 10:22:26.040+000012859: debug : virJSONParserHandleNumber:792 : parser=0x7fffe9c4ae50 str=(null)
>2013-01-18 10:22:26.040+000012859: debug : virJSONParserHandleEndMap:881 : parser=0x7fffe9c4ae50
>2013-01-18 10:22:26.040+000012859: debug : virJSONParserHandleMapKey:833 : parser=0x7fffe9c4ae50 key=0xf2d38f
>2013-01-18 10:22:26.040+000012859: debug : virJSONParserHandleString:813 : parser=0x7fffe9c4ae50 str=0xf2d398
>2013-01-18 10:22:26.040+000012859: debug : virJSONParserHandleEndMap:881 : parser=0x7fffe9c4ae50
>2013-01-18 10:22:26.040+000012859: debug : virJSONValueFromString:1019 : result=0xf0dbb0
>2013-01-18 10:22:26.040+000012859: debug : qemuMonitorJSONIOProcessLine:167 : QEMU_MONITOR_RECV_EVENT: mon=0x7f80080affc0 event={"timestamp": {"seconds": 1358504546, "microseconds": 40335}, "event": "SHUTDOWN"}
>2013-01-18 10:22:26.040+000012859: debug : qemuMonitorJSONIOProcessEvent:124 : mon=0x7f80080affc0 obj=0xf0dbb0
>2013-01-18 10:22:26.040+000012859: debug : qemuMonitorJSONIOProcessEvent:138 : handle SHUTDOWN handler=0x4af120 data=(nil)
>2013-01-18 10:22:26.040+000012859: debug : qemuMonitorEmitShutdown:987 : mon=0x7f80080affc0
>2013-01-18 10:22:26.040+000012859: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f80080affc0
>2013-01-18 10:22:26.040+000012859: debug : qemuProcessHandleShutdown:638 : vm=0x7f8008072c40
>2013-01-18 10:22:26.240+000012864: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f8008072c40
>2013-01-18 10:22:26.240+000012864: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f8008072c40
>2013-01-18 10:22:26.240+000012864: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f8008072c40
>2013-01-18 10:22:26.240+000012859: debug : qemuProcessHandleShutdown:655 : Transitioned guest win7 to shutdown state
>2013-01-18 10:22:26.314+000012859: debug : virFileClose:72 : Closed fd 32
>2013-01-18 10:22:26.314+000012859: debug : qemuAgentNotifyEvent:1263 : mon=0x7f80080b00d0 event=1
>2013-01-18 10:22:26.314+000012859: warning : qemuAgentNotifyEvent:1274 : Received unexpected event 1
>2013-01-18 10:22:26.314+000012859: debug : qemuProcessKill:4104 : vm=win7 pid=12954 flags=2
>2013-01-18 10:22:26.314+000012859: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=7 frequency=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.314+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080affc0
>2013-01-18 10:22:26.314+000012859: debug : qemuMonitorJSONIOProcess:223 : Total used 84 bytes out of 84 available in buffer
>2013-01-18 10:22:26.314+000012859: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=13
>2013-01-18 10:22:26.314+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.314+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080affc0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchHandles:469 : i=8 w=10
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2013-01-18 10:22:26.314+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.314+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-01-18 10:22:26.314+000012859: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-01-18 10:22:26.314+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.314+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-01-18 10:22:26.314+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=24 e=25 d=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=10, f=23 e=25 d=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=19 e=1 d=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=22 e=1 d=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=15, f=27 e=1 d=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2013-01-18 10:22:26.314+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504548992
>2013-01-18 10:22:26.314+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504547925
>2013-01-18 10:22:26.314+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504546314
>2013-01-18 10:22:26.314+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504549039
>2013-01-18 10:22:26.314+000012859: debug : virEventPollCalculateTimeout:346 : Timeout at 1358504546314 due in 0 ms
>2013-01-18 10:22:26.314+000012859: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=0
>2013-01-18 10:22:26.314+000012859: debug : virEventPollRunOnce:625 : Poll got 4 event(s)
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchTimeouts:433 : EVENT_POLL_DISPATCH_TIMEOUT: timer=7
>2013-01-18 10:22:26.314+000012859: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=7 frequency=-1
>2013-01-18 10:22:26.314+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.314+000012859: debug : virObjectNew:110 : OBJECT_NEW: obj=0xf2d2e0 classname=virDomain
>2013-01-18 10:22:26.314+000012859: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f7ff8000d50
>2013-01-18 10:22:26.314+000012859: debug : remoteRelayDomainEventLifecycle:128 : Relaying domain lifecycle event 6 0
>2013-01-18 10:22:26.314+000012859: debug : virNetMessageNew:45 : msg=0xf2a260 tracked=0
>2013-01-18 10:22:26.314+000012859: debug : virNetMessageEncodePayload:358 : Encode length as 64
>2013-01-18 10:22:26.314+000012859: debug : remoteDispatchDomainEventSend:3237 : Queue event 107 64
>2013-01-18 10:22:26.314+000012859: debug : virNetServerClientSendMessageLocked:1116 : msg=0xf2a260 proc=107 len=64 offset=0
>2013-01-18 10:22:26.314+000012859: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0xf2b060 len=64 prog=536903814 vers=1 proc=107 type=2 status=0 serial=1
>2013-01-18 10:22:26.314+000012859: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf29810 tx=0xf2a260
>2013-01-18 10:22:26.314+000012859: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-01-18 10:22:26.314+000012859: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2013-01-18 10:22:26.314+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.314+000012859: debug : virDomainFree:2281 : dom=0xf2d2e0, (VM: name=win7, uuid=5328d409-e0a9-125f-9c8e-f7b49edb72db)
>2013-01-18 10:22:26.314+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xf2d2e0
>2013-01-18 10:22:26.314+000012859: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0xf2d2e0
>2013-01-18 10:22:26.314+000012859: debug : virDomainDispose:225 : release domain 0xf2d2e0 win7 5328d409-e0a9-125f-9c8e-f7b49edb72db
>2013-01-18 10:22:26.314+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f7ff8000d50
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-01-18 10:22:26.314+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
>2013-01-18 10:22:26.320+000012859: debug : virNetlinkEventCallback:346 : dispatching to max 0 clients, called from event watch 6
>2013-01-18 10:22:26.320+000012859: debug : virNetlinkEventCallback:359 : event not handled.
>2013-01-18 10:22:26.320+000012859: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-01-18 10:22:26.320+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
>2013-01-18 10:22:26.320+000012859: debug : udevEventHandleCallback:1467 : udev action: 'remove'
>2013-01-18 10:22:26.320+000012859: debug : udevRemoveOneDevice:1218 : Failed to find device to remove that has udev name '/sys/devices/virtual/net/vnet0/queues/tx-0'
>2013-01-18 10:22:26.320+000012859: debug : virEventPollDispatchHandles:469 : i=7 w=9
>2013-01-18 10:22:26.320+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=9
>2013-01-18 10:22:26.320+000012859: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f80080affc0
>2013-01-18 10:22:26.320+000012859: debug : qemuMonitorIOProcess:353 : QEMU_MONITOR_IO_PROCESS: mon=0x7f80080affc0 buf={"timestamp": {"seconds": 1358504546, "microseconds": 41255}, "event": "DEVICE_TRAY_MOVED", "data": {"device": "drive-ide0-1-0", "tray-open": true}}
> len=150
>2013-01-18 10:22:26.320+000012859: debug : qemuMonitorJSONIOProcessLine:152 : Line [{"timestamp": {"seconds": 1358504546, "microseconds": 41255}, "event": "DEVICE_TRAY_MOVED", "data": {"device": "drive-ide0-1-0", "tray-open": true}}]
>2013-01-18 10:22:26.320+000012859: debug : virJSONValueFromString:975 : string={"timestamp": {"seconds": 1358504546, "microseconds": 41255}, "event": "DEVICE_TRAY_MOVED", "data": {"device": "drive-ide0-1-0", "tray-open": true}}
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleStartMap:852 : parser=0x7fffe9c4ae50
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleMapKey:833 : parser=0x7fffe9c4ae50 key=0xf2d272
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleStartMap:852 : parser=0x7fffe9c4ae50
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleMapKey:833 : parser=0x7fffe9c4ae50 key=0xf2d280
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleNumber:792 : parser=0x7fffe9c4ae50 str=(null)
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleMapKey:833 : parser=0x7fffe9c4ae50 key=0xf2d297
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleNumber:792 : parser=0x7fffe9c4ae50 str=(null)
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleEndMap:881 : parser=0x7fffe9c4ae50
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleMapKey:833 : parser=0x7fffe9c4ae50 key=0xf2d2af
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleString:813 : parser=0x7fffe9c4ae50 str=0xf2d2b8
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleMapKey:833 : parser=0x7fffe9c4ae50 key=0xf2d2cd
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleStartMap:852 : parser=0x7fffe9c4ae50
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleMapKey:833 : parser=0x7fffe9c4ae50 key=0xf2d2d6
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleString:813 : parser=0x7fffe9c4ae50 str=0xf2d2e0
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleMapKey:833 : parser=0x7fffe9c4ae50 key=0xf2d2f2
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleBoolean:766 : parser=0x7fffe9c4ae50 boolean=1
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleEndMap:881 : parser=0x7fffe9c4ae50
>2013-01-18 10:22:26.320+000012859: debug : virJSONParserHandleEndMap:881 : parser=0x7fffe9c4ae50
>2013-01-18 10:22:26.320+000012859: debug : virJSONValueFromString:1019 : result=0xf0c0c0
>2013-01-18 10:22:26.320+000012859: debug : qemuMonitorJSONIOProcessLine:167 : QEMU_MONITOR_RECV_EVENT: mon=0x7f80080affc0 event={"timestamp": {"seconds": 1358504546, "microseconds": 41255}, "event": "DEVICE_TRAY_MOVED", "data": {"device": "drive-ide0-1-0", "tray-open": true}}
>2013-01-18 10:22:26.320+000012859: debug : qemuMonitorJSONIOProcessEvent:124 : mon=0x7f80080affc0 obj=0xf0c0c0
>2013-01-18 10:22:26.320+000012859: debug : qemuMonitorJSONIOProcessEvent:138 : handle DEVICE_TRAY_MOVED handler=0x4af1f0 data=0xf2ae60
>2013-01-18 10:22:26.320+000012859: debug : qemuMonitorEmitTrayChange:1095 : mon=0x7f80080affc0
>2013-01-18 10:22:26.320+000012859: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f80080affc0
>2013-01-18 10:22:26.364+000012859: debug : virFileClose:72 : Closed fd 32
>2013-01-18 10:22:26.364+000012859: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=7 frequency=0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.364+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080affc0
>2013-01-18 10:22:26.364+000012859: debug : qemuMonitorJSONIOProcess:223 : Total used 150 bytes out of 150 available in buffer
>2013-01-18 10:22:26.364+000012859: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=13
>2013-01-18 10:22:26.364+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.364+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080affc0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:469 : i=8 w=10
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=9
>2013-01-18 10:22:26.364+000012859: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f80080b00d0
>2013-01-18 10:22:26.364+000012859: error : qemuAgentIO:642 : åé¨éè¯¯ End of file from monitor
>2013-01-18 10:22:26.364+000012859: debug : qemuAgentIO:675 : Error on monitor åé¨éè¯¯ End of file from monitor
>2013-01-18 10:22:26.364+000012859: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=12
>2013-01-18 10:22:26.364+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.364+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080b00d0
>2013-01-18 10:22:26.364+000012859: debug : qemuAgentIO:698 : Triggering EOF callback
>2013-01-18 10:22:26.364+000012859: debug : qemuProcessHandleAgentEOF:125 : Received EOF from agent on 0x7f8008072c40 'win7'
>2013-01-18 10:22:26.364+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080b00d0
>2013-01-18 10:22:26.364+000012859: debug : qemuAgentClose:814 : mon=0x7f80080b00d0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=10
>2013-01-18 10:22:26.364+000012859: debug : virEventPollRemoveHandle:188 : mark delete 8 23
>2013-01-18 10:22:26.364+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.364+000012859: debug : virFileClose:72 : Closed fd 23
>2013-01-18 10:22:26.364+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080b00d0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:469 : i=11 w=15
>2013-01-18 10:22:26.364+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.364+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-01-18 10:22:26.364+000012859: debug : virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=10
>2013-01-18 10:22:26.364+000012862: debug : qemuAgentSend:900 : Send command resulted in error åé¨éè¯¯ End of file from monitor
>2013-01-18 10:22:26.364+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080b00d0
>2013-01-18 10:22:26.364+000012859: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f80080b00d0
>2013-01-18 10:22:26.364+000012859: debug : qemuAgentDispose:169 : mon=0x7f80080b00d0
>2013-01-18 10:22:26.364+000012862: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=12
>2013-01-18 10:22:26.364+000012862: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-01-18 10:22:26.364+000012859: debug : qemuProcessHandleAgentDestroy:171 : Received destroy agent=0x7f80080b00d0 vm=0x7f8008072c40
>2013-01-18 10:22:26.364+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f8008072c40
>2013-01-18 10:22:26.364+000012862: debug : qemuAgentCommand:1023 : Receive command reply ret=-1 rxObject=(nil)
>2013-01-18 10:22:26.364+000012859: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-01-18 10:22:26.364+000012862: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080b00d0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.364+000012862: debug : qemuDomainObjEndJob:937 : Stopping job: modify (async=none)
>2013-01-18 10:22:26.364+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2013-01-18 10:22:26.364+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-01-18 10:22:26.364+000012862: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f8008072c40
>2013-01-18 10:22:26.364+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=24 e=25 d=0
>2013-01-18 10:22:26.364+000012862: debug : virDomainFree:2281 : dom=0x7f7ff40048c0, (VM: name=win7, uuid=5328d409-e0a9-125f-9c8e-f7b49edb72db)
>2013-01-18 10:22:26.364+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=1 d=0
>2013-01-18 10:22:26.364+000012862: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f7ff40048c0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=12, f=22 e=5 d=0
>2013-01-18 10:22:26.364+000012862: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f7ff40048c0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=15, f=27 e=1 d=0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2013-01-18 10:22:26.364+000012862: debug : virDomainDispose:225 : release domain 0x7f7ff40048c0 win7 5328d409-e0a9-125f-9c8e-f7b49edb72db
>2013-01-18 10:22:26.364+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504548992
>2013-01-18 10:22:26.364+000012862: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f7ff80c1400
>2013-01-18 10:22:26.364+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504547925
>2013-01-18 10:22:26.364+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504546364
>2013-01-18 10:22:26.364+000012859: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1358504549039
>2013-01-18 10:22:26.364+000012864: debug : qemuDomainObjBeginJobInternal:808 : Starting job: destroy (async=none)
>2013-01-18 10:22:26.364+000012862: debug : virNetServerProgramSendError:150 : prog=536903814 ver=1 proc=261 type=1 serial=5 msg=0xf2a110 rerr=0x7f801656db80
>2013-01-18 10:22:26.364+000012859: debug : virEventPollCalculateTimeout:346 : Timeout at 1358504546364 due in 0 ms
>2013-01-18 10:22:26.364+000012859: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollRunOnce:625 : Poll got 5 event(s)
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2013-01-18 10:22:26.364+000012862: debug : virNetMessageEncodePayload:358 : Encode length as 164
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchTimeouts:433 : EVENT_POLL_DISPATCH_TIMEOUT: timer=7
>2013-01-18 10:22:26.364+000012859: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=7 frequency=-1
>2013-01-18 10:22:26.364+000012862: debug : virNetServerClientSendMessageLocked:1116 : msg=0xf2a110 proc=261 len=164 offset=0
>2013-01-18 10:22:26.364+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.364+000012862: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xf2df50
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-01-18 10:22:26.364+000012862: debug : virNetMessageFree:73 : msg=0xf2a110 nfds=0 cb=(nil)
>2013-01-18 10:22:26.364+000012862: debug : virNetServerClientClose:620 : client=0xf2cd50
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-01-18 10:22:26.364+000012862: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xf2cd50
>2013-01-18 10:22:26.364+000012862: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0xf2cd50
>2013-01-18 10:22:26.364+000012862: debug : virConnectClose:1449 : conn=0x7f7ff80c1400
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-01-18 10:22:26.364+000012862: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f7ff80c1400
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-01-18 10:22:26.364+000012862: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f7ff80c1400
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
>2013-01-18 10:22:26.364+000012859: debug : virNetlinkEventCallback:346 : dispatching to max 0 clients, called from event watch 6
>2013-01-18 10:22:26.364+000012859: debug : virNetlinkEventCallback:359 : event not handled.
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
>2013-01-18 10:22:26.364+000012859: debug : udevEventHandleCallback:1467 : udev action: 'remove'
>2013-01-18 10:22:26.364+000012859: debug : udevRemoveOneDevice:1218 : Failed to find device to remove that has udev name '/sys/devices/virtual/net/vnet0/queues/rx-0'
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:469 : i=7 w=9
>2013-01-18 10:22:26.364+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=9
>2013-01-18 10:22:26.364+000012859: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f80080affc0
>2013-01-18 10:22:26.364+000012859: error : qemuMonitorIO:613 : åé¨éè¯¯ End of file from monitor
>2013-01-18 10:22:26.364+000012859: debug : qemuMonitorIO:646 : Error on monitor åé¨éè¯¯ End of file from monitor
>2013-01-18 10:22:26.364+000012859: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=12
>2013-01-18 10:22:26.364+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.364+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080affc0
>2013-01-18 10:22:26.364+000012859: debug : qemuMonitorIO:669 : Triggering EOF callback
>2013-01-18 10:22:26.364+000012859: debug : qemuProcessHandleMonitorEOF:283 : Received EOF on 0x7f8008072c40 'win7'
>2013-01-18 10:22:26.414+000012864: debug : virFileClose:72 : Closed fd 23
>2013-01-18 10:22:26.414+000012864: debug : qemuProcessStop:4206 : Shutting down VM 'win7' pid=12954 flags=0
>2013-01-18 10:22:26.414+000012864: debug : virFileClose:72 : Closed fd 23
>2013-01-18 10:22:26.414+000012864: debug : qemuAgentClose:814 : mon=0x7f80080b00d0
>2013-01-18 10:22:26.414+000012864: debug : virFileClose:72 : Closed fd 0
>2013-01-18 10:22:26.414+000012864: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080b00d0
>2013-01-18 10:22:26.414+000012864: debug : qemuMonitorClose:837 : QEMU_MONITOR_CLOSE: mon=0x7f80080affc0 refs=2
>2013-01-18 10:22:26.414+000012864: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=9
>2013-01-18 10:22:26.414+000012864: debug : virEventPollRemoveHandle:188 : mark delete 7 24
>2013-01-18 10:22:26.414+000012864: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-01-18 10:22:26.414+000012864: debug : virFileClose:72 : Closed fd 24
>2013-01-18 10:22:26.414+000012864: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080affc0
>2013-01-18 10:22:26.415+000012864: debug : qemuProcessKill:4104 : vm=win7 pid=12954 flags=5
>2013-01-18 10:22:26.415+000012864: debug : qemuDomainCleanupRun:1995 : driver=0x7f800800fbe0, vm=win7
>2013-01-18 10:22:26.415+000012864: debug : qemuProcessAutoDestroyRemove:4685 : vm=win7
>2013-01-18 10:22:26.415+000012864: debug : qemuDriverCloseCallbackUnset:661 : vm=win7, uuid=5328d409-e0a9-125f-9c8e-f7b49edb72db, cb=0x47e2a0
>2013-01-18 10:22:26.415+000012864: debug : virSecuritySELinuxRestoreSecurityAllLabel:1445 : Restoring security label on win7
>2013-01-18 10:22:26.415+000012864: info : virSecuritySELinuxRestoreSecurityFileLabel:924 : Restoring SELinux context on '/var/lib/libvirt/images/win7-32.img'
>2013-01-18 10:22:26.489+000012864: info : virSecuritySELinuxSetFileconHelper:794 : Setting SELinux context on '/var/lib/libvirt/images/win7-32.img' to 'system_u:object_r:virt_image_t:s0'
>2013-01-18 10:22:26.489+000012864: debug : virSecurityDACRestoreSecurityAllLabel:726 : Restoring security label on win7 migrated=0
>2013-01-18 10:22:26.489+000012864: info : virSecurityDACRestoreSecurityFileLabel:315 : Restoring DAC user and group on '/var/lib/libvirt/images/win7-32.img'
>2013-01-18 10:22:26.489+000012864: info : virSecurityDACSetOwnership:271 : Setting DAC user and group on '/var/lib/libvirt/images/win7-32.img' to '0:0'
>2013-01-18 10:22:26.489+000012864: debug : networkReleaseActualDevice:4019 : Nothing to release to network default
>2013-01-18 10:22:26.489+000012864: debug : networkReleaseActualDevice:4105 : Releasing network default, -1 connections
>2013-01-18 10:22:26.489+000012864: debug : virCgroupNew:617 : New group /libvirt/qemu/win7
>2013-01-18 10:22:26.495+000012864: debug : virCgroupDetect:273 : Detected mount/mapping 0:cpu at /cgroup/cpu in 
>2013-01-18 10:22:26.495+000012864: debug : virCgroupDetect:273 : Detected mount/mapping 1:cpuacct at /cgroup/cpuacct in 
>2013-01-18 10:22:26.495+000012864: debug : virCgroupDetect:273 : Detected mount/mapping 2:cpuset at /cgroup/cpuset in 
>2013-01-18 10:22:26.495+000012864: debug : virCgroupDetect:273 : Detected mount/mapping 3:memory at /cgroup/memory in 
>2013-01-18 10:22:26.495+000012864: debug : virCgroupDetect:273 : Detected mount/mapping 4:devices at /cgroup/devices in 
>2013-01-18 10:22:26.495+000012864: debug : virCgroupDetect:273 : Detected mount/mapping 5:freezer at /cgroup/freezer in 
>2013-01-18 10:22:26.495+000012864: debug : virCgroupDetect:273 : Detected mount/mapping 6:blkio at /cgroup/blkio in 
>2013-01-18 10:22:26.495+000012864: debug : virCgroupMakeGroup:535 : Make group /libvirt/qemu/win7
>2013-01-18 10:22:26.495+000012864: debug : virCgroupMakeGroup:560 : Make controller /cgroup/cpu/libvirt/qemu/win7/
>2013-01-18 10:22:26.495+000012864: debug : virCgroupMakeGroup:560 : Make controller /cgroup/cpuacct/libvirt/qemu/win7/
>2013-01-18 10:22:26.495+000012864: debug : virCgroupMakeGroup:560 : Make controller /cgroup/cpuset/libvirt/qemu/win7/
>2013-01-18 10:22:26.495+000012864: debug : virCgroupMakeGroup:560 : Make controller /cgroup/memory/libvirt/qemu/win7/
>2013-01-18 10:22:26.495+000012864: debug : virCgroupMakeGroup:560 : Make controller /cgroup/devices/libvirt/qemu/win7/
>2013-01-18 10:22:26.495+000012864: debug : virCgroupMakeGroup:560 : Make controller /cgroup/freezer/libvirt/qemu/win7/
>2013-01-18 10:22:26.495+000012864: debug : virCgroupMakeGroup:560 : Make controller /cgroup/blkio/libvirt/qemu/win7/
>2013-01-18 10:22:26.495+000012864: debug : virCgroupRemove:772 : Removing cgroup /cgroup/cpu/libvirt/qemu/win7/ and all child cgroups
>2013-01-18 10:22:26.495+000012864: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpu/libvirt/qemu/win7//emulator
>2013-01-18 10:22:26.503+000012864: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpu/libvirt/qemu/win7//vcpu0
>2013-01-18 10:22:26.511+000012864: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpu/libvirt/qemu/win7/
>2013-01-18 10:22:26.519+000012864: debug : virCgroupRemove:772 : Removing cgroup /cgroup/cpuacct/libvirt/qemu/win7/ and all child cgroups
>2013-01-18 10:22:26.519+000012864: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpuacct/libvirt/qemu/win7//emulator
>2013-01-18 10:22:26.527+000012864: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpuacct/libvirt/qemu/win7//vcpu0
>2013-01-18 10:22:26.535+000012864: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpuacct/libvirt/qemu/win7/
>2013-01-18 10:22:26.543+000012864: debug : virCgroupRemove:772 : Removing cgroup /cgroup/cpuset/libvirt/qemu/win7/ and all child cgroups
>2013-01-18 10:22:26.543+000012864: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpuset/libvirt/qemu/win7//emulator
>2013-01-18 10:22:26.551+000012864: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpuset/libvirt/qemu/win7//vcpu0
>2013-01-18 10:22:26.559+000012864: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpuset/libvirt/qemu/win7/
>2013-01-18 10:22:26.567+000012864: debug : virCgroupRemove:772 : Removing cgroup /cgroup/memory/libvirt/qemu/win7/ and all child cgroups
>2013-01-18 10:22:26.567+000012864: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/memory/libvirt/qemu/win7/
>2013-01-18 10:22:26.579+000012864: debug : virCgroupRemove:772 : Removing cgroup /cgroup/devices/libvirt/qemu/win7/ and all child cgroups
>2013-01-18 10:22:26.579+000012864: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/devices/libvirt/qemu/win7/
>2013-01-18 10:22:26.587+000012864: debug : virCgroupRemove:772 : Removing cgroup /cgroup/freezer/libvirt/qemu/win7/ and all child cgroups
>2013-01-18 10:22:26.587+000012864: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/freezer/libvirt/qemu/win7/
>2013-01-18 10:22:26.595+000012864: debug : virCgroupRemove:772 : Removing cgroup /cgroup/blkio/libvirt/qemu/win7/ and all child cgroups
>2013-01-18 10:22:26.595+000012864: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/blkio/libvirt/qemu/win7/
>2013-01-18 10:22:26.604+000012864: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f8008106e80
>2013-01-18 10:22:26.604+000012864: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f8008106e80
>2013-01-18 10:22:26.606+000012864: debug : qemuDomainObjEndJob:937 : Stopping job: destroy (async=none)
>2013-01-18 10:22:26.606+000012864: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f8008072c40
>2013-01-18 10:22:26.606+000012864: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=7 frequency=0
>2013-01-18 10:22:26.606+000012864: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-01-18 10:22:26.606+000012864: debug : virDomainFree:2281 : dom=0x7f7ffc001dd0, (VM: name=win7, uuid=5328d409-e0a9-125f-9c8e-f7b49edb72db)
>2013-01-18 10:22:26.606+000012864: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f7ffc001dd0
>2013-01-18 10:22:26.606+000012864: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f7ffc001dd0
>2013-01-18 10:22:26.606+000012864: debug : virDomainDispose:225 : release domain 0x7f7ffc001dd0 win7 5328d409-e0a9-125f-9c8e-f7b49edb72db
>2013-01-18 10:22:26.606+000012864: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f7ff81816f0
>2013-01-18 10:22:26.606+000012864: debug : virNetMessageEncodePayload:358 : Encode length as 28
>2013-01-18 10:22:26.606+000012864: debug : virNetServerClientSendMessageLocked:1116 : msg=0xf2a9d0 proc=12 len=28 offset=0
>2013-01-18 10:22:26.606+000012864: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0xf2ba40 len=28 prog=536903814 vers=1 proc=12 type=1 status=0 serial=5
>2013-01-18 10:22:26.606+000012859: debug : qemuProcessHandleMonitorEOF:296 : Domain 0x7f8008072c40 is not active, ignoring EOF
>2013-01-18 10:22:26.606+000012864: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf2d420 tx=0xf2a9d0
>2013-01-18 10:22:26.606+000012864: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-01-18 10:22:26.606+000012864: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=3
>2013-01-18 10:22:26.606+000012859: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2013-01-18 10:22:26.606+000012859: debug : virEventPollDispatchHandles:469 : i=9 w=12
>2013-01-18 10:22:26.606+000012859: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2013-01-18 10:22:26.606+000012864: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-01-18 10:22:26.606+000012864: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xf2df50
>2013-01-18 10:22:26.606+000012864: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xf2ba40
>2013-01-18 10:22:26.606+000012859: debug : virNetMessageFree:73 : msg=0xf2a260 nfds=0 cb=(nil)
>2013-01-18 10:22:26.606+000012859: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf29810 tx=(nil)
>2013-01-18 10:22:26.606+000012859: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-01-18 10:22:26.606+000012862: debug : qemuDriverCloseCallbackRunAll:744 : conn=0x7f7ff80c1400
>2013-01-18 10:22:26.606+000012859: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-01-18 10:22:26.606+000012859: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 494938208
>2013-01-18 10:22:26.606+000012862: debug : virEventPollRemoveTimeout:288 : EVENT_POLL_REMOVE_TIMEOUT: timer=10
>2013-01-18 10:22:26.606+000012859: debug : virEventPollDispatchHandles:469 : i=10 w=15
>2013-01-18 10:22:26.606+000012859: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2013-01-18 10:22:26.606+000012859: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2013-01-18 10:22:26.606+000012859: debug : virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=9
>2013-01-18 10:22:26.606+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f80080affc0
>2013-01-18 10:22:26.606+000012862: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-01-18 10:22:26.606+000012859: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f80080affc0
>2013-01-18 10:22:26.606+000012859: debug : qemuMonitorDispose:246 : mon=0x7f80080affc0
>2013-01-18 10:22:26.606+000012859: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f8008072c40
>
>
>     ====== end of log =====
>

Actions: View

Attachments on bug 892079: 682311