Attachment 602476 Details for Bug 845966 – libvirtd log

libvirtd log

libvirtd.log (text/plain), 65.65 KB, created by Huang Wenlong on 2012-08-06 10:17:14 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Huang Wenlong

Created: 2012-08-06 10:17:14 UTC

Size: 65.65 KB

patch

obsolete

>
>2012-08-06 09:50:00.494+0000: 6209: info : libvirt version: 0.10.0, package: 0rc0.el6 (Red Hat, Inc. <http://bugzilla.redhat.com/bugzilla>, 2012-08-02-03:44:27, x86-007.build.bos.redhat.com)
>2012-08-06 09:50:00.494+0000: 6209: error : qemuMonitorOpenUnix:308 : failed to connect to monitor socket: No such process
>2012-08-06 09:51:06.730+0000: 6125: warning : qemuAgentNotifyEvent:1274 : Received unexpected event 1
>2012-08-06 09:51:06.820+0000: 6125: error : qemuMonitorIO:616 : internal error End of file from monitor
>2012-08-06 09:57:32.406+0000: 6125: warning : qemuAgentNotifyEvent:1274 : Received unexpected event 1
>2012-08-06 09:57:32.504+0000: 6125: error : qemuMonitorIO:616 : internal error End of file from monitor
>2012-08-06 10:02:45.320+0000: 7593: info : libvirt version: 0.10.0, package: 0rc0.el6 (Red Hat, Inc. <http://bugzilla.redhat.com/bugzilla>, 2012-08-02-03:44:27, x86-007.build.bos.redhat.com)
>2012-08-06 10:02:45.320+0000: 7593: error : qemuMonitorOpenUnix:308 : failed to connect to monitor socket: No such process
>2012-08-06 10:05:38.610+0000: 7507: warning : qemuAgentNotifyEvent:1274 : Received unexpected event 1
>2012-08-06 10:05:38.694+0000: 7507: error : qemuMonitorIO:616 : internal error End of file from monitor
>Caught Segmentation violation dumping internal log buffer:
>
>
>    ====== start of log =====
>
>=1
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.475+00007507: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=5 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=22 e=25 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=20 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543475
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543194
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCalculateTimeout:346 : Timeout at 1344247543194 due in 4719 ms
>2012-08-06 10:05:38.475+00007507: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4719
>2012-08-06 10:05:38.475+00007507: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=7 events=2
>2012-08-06 10:05:38.475+00007507: debug : virNetMessageFree:73 : msg=0xba2520 nfds=0 cb=(nil)
>2012-08-06 10:05:38.475+00007507: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xba1380 tx=(nil)
>2012-08-06 10:05:38.475+00007507: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-06 10:05:38.475+00007507: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
>2012-08-06 10:05:38.475+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.475+00007507: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=22 e=25 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=20 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543475
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543194
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCalculateTimeout:346 : Timeout at 1344247543194 due in 4719 ms
>2012-08-06 10:05:38.475+00007507: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4719
>2012-08-06 10:05:38.475+00007507: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
>2012-08-06 10:05:38.475+00007507: debug : virNetMessageDecodeLength:152 : Got length, now need 56 total (52 more)
>2012-08-06 10:05:38.475+00007507: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xba1380 tx=(nil)
>2012-08-06 10:05:38.475+00007507: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-06 10:05:38.475+00007507: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
>2012-08-06 10:05:38.475+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.475+00007507: debug : virNetServerClientDispatchRead:876 : RPC_SERVER_CLIENT_MSG_RX: client=0xba1ed0 len=56 prog=536903814 vers=1 proc=16 type=0 status=0 serial=1840
>2012-08-06 10:05:38.475+00007507: debug : virKeepAliveCheckMessage:399 : ka=0xba1fa0, client=0xba1ed0, msg=0xba1380
>2012-08-06 10:05:38.475+00007507: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000
>2012-08-06 10:05:38.475+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.475+00007507: debug : virNetServerDispatchNewMessage:208 : server=0xb9acb0 client=0xba1ed0 message=0xba1380
>2012-08-06 10:05:38.475+00007507: debug : virNetServerProgramRef:84 : prog=0xba5f30 refs=4
>2012-08-06 10:05:38.475+00007507: debug : virNetMessageNew:45 : msg=0xba17d0 tracked=1
>2012-08-06 10:05:38.475+00007507: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xba17d0 tx=(nil)
>2012-08-06 10:05:38.475+00007510: debug : virNetServerHandleJob:176 : server=0xb9acb0 client=0xba1ed0 message=0xba1380 prog=0xba5f30
>2012-08-06 10:05:38.475+00007507: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-06 10:05:38.475+00007507: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
>2012-08-06 10:05:38.475+00007510: debug : virNetServerProgramDispatch:266 : prog=536903814 ver=1 type=0 status=0 serial=1840 proc=16
>2012-08-06 10:05:38.475+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-06 10:05:38.475+00007507: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.475+00007510: debug : remoteDispatchDomainGetInfoHelper:1693 : server=0xb9acb0 client=0xba1ed0 msg=0xba1380 rerr=0x7f59e93a7b80 args=0x7f59b4000ab0 ret=0x7f59b4000b10
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.475+00007507: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-06 10:05:38.475+00007510: debug : virDomainGetInfo:4202 : dom=0x7f59b4000950, (VM: name=ga, uuid=d6e43b84-5340-9505-d3d7-e5bb22744ade), info=0x7f59e93a7ad0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.475+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-08-06 10:05:38.475+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=22 e=25 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=20 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543475
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543194
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCalculateTimeout:346 : Timeout at 1344247543194 due in 4718 ms
>2012-08-06 10:05:38.476+00007507: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4718
>2012-08-06 10:05:38.476+00007510: debug : qemudGetProcessInfo:1176 : Got status for 7706/0 user=1060 sys=749 cpu=2 rss=155095
>2012-08-06 10:05:38.476+00007510: debug : virDomainFree:2282 : dom=0x7f59b4000950, (VM: name=ga, uuid=d6e43b84-5340-9505-d3d7-e5bb22744ade)
>2012-08-06 10:05:38.476+00007510: debug : virUnrefDomain:275 : unref domain 0x7f59b4000950 ga 1
>2012-08-06 10:05:38.476+00007510: debug : virReleaseDomain:236 : release domain 0x7f59b4000950 ga d6e43b84-5340-9505-d3d7-e5bb22744ade
>2012-08-06 10:05:38.476+00007510: debug : virReleaseDomain:244 : unref connection 0x7f59d8000ae0 2
>2012-08-06 10:05:38.476+00007510: debug : virNetMessageEncodePayload:358 : Encode length as 60
>2012-08-06 10:05:38.476+00007510: debug : virNetServerClientSendMessageLocked:1120 : msg=0xba1380 proc=16 len=60 offset=0
>2012-08-06 10:05:38.476+00007510: debug : virNetServerClientSendMessageLocked:1128 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0xba1ed0 len=60 prog=536903814 vers=1 proc=16 type=1 status=0 serial=1840
>2012-08-06 10:05:38.476+00007510: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xba17d0 tx=0xba1380
>2012-08-06 10:05:38.476+00007510: debug : virNetServerClientCalculateHandleMode:166 : mode=3
>2012-08-06 10:05:38.476+00007510: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=7 events=3
>2012-08-06 10:05:38.476+00007510: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-08-06 10:05:38.476+00007510: debug : virNetServerProgramFree:524 : prog=0xba5f30 refs=4
>2012-08-06 10:05:38.476+00007510: debug : virNetServerClientFree:583 : RPC_SERVER_CLIENT_FREE: client=0xba1ed0 refs=4
>2012-08-06 10:05:38.476+00007507: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.476+00007507: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=5 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=22 e=25 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=20 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543475
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543194
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCalculateTimeout:346 : Timeout at 1344247543194 due in 4718 ms
>2012-08-06 10:05:38.476+00007507: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4718
>2012-08-06 10:05:38.476+00007507: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=7 events=2
>2012-08-06 10:05:38.476+00007507: debug : virNetMessageFree:73 : msg=0xba1380 nfds=0 cb=(nil)
>2012-08-06 10:05:38.476+00007507: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xba17d0 tx=(nil)
>2012-08-06 10:05:38.476+00007507: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-06 10:05:38.476+00007507: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
>2012-08-06 10:05:38.476+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-06 10:05:38.476+00007507: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.476+00007507: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=22 e=25 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=20 e=1 d=0
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543475
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543194
>2012-08-06 10:05:38.476+00007507: debug : virEventPollCalculateTimeout:346 : Timeout at 1344247543194 due in 4718 ms
>2012-08-06 10:05:38.476+00007507: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4718
>2012-08-06 10:05:38.477+00007507: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
>2012-08-06 10:05:38.477+00007507: debug : virNetMessageDecodeLength:152 : Got length, now need 60 total (56 more)
>2012-08-06 10:05:38.477+00007507: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xba17d0 tx=(nil)
>2012-08-06 10:05:38.477+00007507: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-06 10:05:38.477+00007507: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
>2012-08-06 10:05:38.477+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.477+00007507: debug : virNetServerClientDispatchRead:876 : RPC_SERVER_CLIENT_MSG_RX: client=0xba1ed0 len=60 prog=536903814 vers=1 proc=14 type=0 status=0 serial=1841
>2012-08-06 10:05:38.477+00007507: debug : virKeepAliveCheckMessage:399 : ka=0xba1fa0, client=0xba1ed0, msg=0xba17d0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000
>2012-08-06 10:05:38.477+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.477+00007507: debug : virNetServerDispatchNewMessage:208 : server=0xb9acb0 client=0xba1ed0 message=0xba17d0
>2012-08-06 10:05:38.477+00007507: debug : virNetServerProgramRef:84 : prog=0xba5f30 refs=4
>2012-08-06 10:05:38.477+00007507: debug : virNetMessageNew:45 : msg=0xba24b0 tracked=1
>2012-08-06 10:05:38.477+00007507: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xba24b0 tx=(nil)
>2012-08-06 10:05:38.477+00007507: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-06 10:05:38.477+00007508: debug : virNetServerHandleJob:176 : server=0xb9acb0 client=0xba1ed0 message=0xba17d0 prog=0xba5f30
>2012-08-06 10:05:38.477+00007507: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
>2012-08-06 10:05:38.477+00007508: debug : virNetServerProgramDispatch:266 : prog=536903814 ver=1 type=0 status=0 serial=1841 proc=14
>2012-08-06 10:05:38.477+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-06 10:05:38.477+00007508: debug : remoteDispatchDomainGetXMLDescHelper:2323 : server=0xb9acb0 client=0xba1ed0 msg=0xba17d0 rerr=0x7f59ea7a9b80 args=0x7f59d80c9e30 ret=0x7f59d80ca1b0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.477+00007507: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-06 10:05:38.477+00007508: debug : virDomainGetXMLDesc:4352 : dom=0x7f59d80c0c30, (VM: name=ga, uuid=d6e43b84-5340-9505-d3d7-e5bb22744ade), flags=1
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=22 e=25 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=20 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543477
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543194
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCalculateTimeout:346 : Timeout at 1344247543194 due in 4717 ms
>2012-08-06 10:05:38.477+00007507: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4717
>2012-08-06 10:05:38.477+00007508: debug : virDomainFree:2282 : dom=0x7f59d80c0c30, (VM: name=ga, uuid=d6e43b84-5340-9505-d3d7-e5bb22744ade)
>2012-08-06 10:05:38.477+00007508: debug : virUnrefDomain:275 : unref domain 0x7f59d80c0c30 ga 1
>2012-08-06 10:05:38.477+00007508: debug : virReleaseDomain:236 : release domain 0x7f59d80c0c30 ga d6e43b84-5340-9505-d3d7-e5bb22744ade
>2012-08-06 10:05:38.477+00007508: debug : virReleaseDomain:244 : unref connection 0x7f59d8000ae0 2
>2012-08-06 10:05:38.477+00007508: debug : virNetMessageEncodePayload:358 : Encode length as 3180
>2012-08-06 10:05:38.477+00007508: debug : virNetServerClientSendMessageLocked:1120 : msg=0xba17d0 proc=14 len=3180 offset=0
>2012-08-06 10:05:38.477+00007508: debug : virNetServerClientSendMessageLocked:1128 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0xba1ed0 len=3180 prog=536903814 vers=1 proc=14 type=1 status=0 serial=1841
>2012-08-06 10:05:38.477+00007508: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xba24b0 tx=0xba17d0
>2012-08-06 10:05:38.477+00007508: debug : virNetServerClientCalculateHandleMode:166 : mode=3
>2012-08-06 10:05:38.477+00007508: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=7 events=3
>2012-08-06 10:05:38.477+00007508: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-08-06 10:05:38.477+00007508: debug : virNetServerProgramFree:524 : prog=0xba5f30 refs=4
>2012-08-06 10:05:38.477+00007508: debug : virNetServerClientFree:583 : RPC_SERVER_CLIENT_FREE: client=0xba1ed0 refs=4
>2012-08-06 10:05:38.477+00007507: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-06 10:05:38.4772012-08-06 10:05:38.695+00007507: debug : qemuProcessKill:3878 : vm=ga pid=7706 flags=5
>events=1
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.477+00007507: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=5 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=22 e=25 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=20 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543477
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543194
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCalculateTimeout:346 : Timeout at 1344247543194 due in 4717 ms
>2012-08-06 10:05:38.477+00007507: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4717
>2012-08-06 10:05:38.477+00007507: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=7 events=2
>2012-08-06 10:05:38.477+00007507: debug : virNetMessageFree:73 : msg=0xba17d0 nfds=0 cb=(nil)
>2012-08-06 10:05:38.477+00007507: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xba24b0 tx=(nil)
>2012-08-06 10:05:38.477+00007507: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-06 10:05:38.477+00007507: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
>2012-08-06 10:05:38.477+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-06 10:05:38.477+00007507: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.477+00007507: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=22 e=25 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=20 e=1 d=0
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543477
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543194
>2012-08-06 10:05:38.477+00007507: debug : virEventPollCalculateTimeout:346 : Timeout at 1344247543194 due in 4717 ms
>2012-08-06 10:05:38.477+00007507: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4717
>2012-08-06 10:05:38.562+00007507: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-06 10:05:38.562+00007507: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-06 10:05:38.562+00007507: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-06 10:05:38.562+00007507: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-06 10:05:38.562+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.562+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.562+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.562+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.562+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.562+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.562+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.562+00007507: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2012-08-06 10:05:38.562+00007507: debug : qemuMonitorRef:243 : QEMU_MONITOR_REF: mon=0x7f59d80ca510 refs=3
>2012-08-06 10:05:38.562+00007507: debug : qemuMonitorIOProcess:369 : QEMU_MONITOR_IO_PROCESS: mon=0x7f59d80ca510 buf={"timestamp": {"seconds": 1344247538, "microseconds": 562333}, "event": "SHUTDOWN"}
> len=85
>2012-08-06 10:05:38.562+00007507: debug : qemuMonitorJSONIOProcessLine:146 : Line [{"timestamp": {"seconds": 1344247538, "microseconds": 562333}, "event": "SHUTDOWN"}]
>2012-08-06 10:05:38.562+00007507: debug : virJSONValueFromString:975 : string={"timestamp": {"seconds": 1344247538, "microseconds": 562333}, "event": "SHUTDOWN"}
>2012-08-06 10:05:38.562+00007507: debug : virJSONParserHandleStartMap:852 : parser=0x7fff71506b10
>2012-08-06 10:05:38.562+00007507: debug : virJSONParserHandleMapKey:833 : parser=0x7fff71506b10 key=0xba1452
>2012-08-06 10:05:38.562+00007507: debug : virJSONParserHandleStartMap:852 : parser=0x7fff71506b10
>2012-08-06 10:05:38.562+00007507: debug : virJSONParserHandleMapKey:833 : parser=0x7fff71506b10 key=0xba1460
>2012-08-06 10:05:38.562+00007507: debug : virJSONParserHandleNumber:792 : parser=0x7fff71506b10 str=(null)
>2012-08-06 10:05:38.562+00007507: debug : virJSONParserHandleMapKey:833 : parser=0x7fff71506b10 key=0xba1477
>2012-08-06 10:05:38.562+00007507: debug : virJSONParserHandleNumber:792 : parser=0x7fff71506b10 str=(null)
>2012-08-06 10:05:38.562+00007507: debug : virJSONParserHandleEndMap:881 : parser=0x7fff71506b10
>2012-08-06 10:05:38.562+00007507: debug : virJSONParserHandleMapKey:833 : parser=0x7fff71506b10 key=0xba1490
>2012-08-06 10:05:38.562+00007507: debug : virJSONParserHandleString:813 : parser=0x7fff71506b10 str=0xba1499
>2012-08-06 10:05:38.562+00007507: debug : virJSONParserHandleEndMap:881 : parser=0x7fff71506b10
>2012-08-06 10:05:38.562+00007507: debug : virJSONValueFromString:1019 : result=0xb791e0
>2012-08-06 10:05:38.562+00007507: debug : qemuMonitorJSONIOProcessLine:161 : QEMU_MONITOR_RECV_EVENT: mon=0x7f59d80ca510 event={"timestamp": {"seconds": 1344247538, "microseconds": 562333}, "event": "SHUTDOWN"}
>2012-08-06 10:05:38.562+00007507: debug : qemuMonitorJSONIOProcessEvent:118 : mon=0x7f59d80ca510 obj=0xb791e0
>2012-08-06 10:05:38.562+00007507: debug : qemuMonitorJSONIOProcessEvent:132 : handle SHUTDOWN handler=0x480450 data=(nil)
>2012-08-06 10:05:38.562+00007507: debug : qemuMonitorEmitShutdown:949 : mon=0x7f59d80ca510
>2012-08-06 10:05:38.562+00007507: debug : qemuMonitorRef:243 : QEMU_MONITOR_REF: mon=0x7f59d80ca510 refs=4
>2012-08-06 10:05:38.562+00007507: debug : qemuProcessHandleShutdown:638 : vm=0x7f59dc0abdf0
>2012-08-06 10:05:38.562+00007507: debug : qemuProcessHandleShutdown:655 : Transitioned guest ga to shutdown state
>2012-08-06 10:05:38.610+00007507: debug : virFileClose:72 : Closed fd 25
>2012-08-06 10:05:38.610+00007507: debug : qemuAgentNotifyEvent:1263 : mon=0x7f59d80ca680 event=1
>2012-08-06 10:05:38.610+00007507: warning : qemuAgentNotifyEvent:1274 : Received unexpected event 1
>2012-08-06 10:05:38.610+00007507: debug : qemuProcessKill:3878 : vm=ga pid=7706 flags=2
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorUnref:252 : QEMU_MONITOR_UNREF: mon=0x7f59d80ca510 refs=3
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorJSONIOProcess:217 : Total used 85 bytes out of 85 available in buffer
>2012-08-06 10:05:38.611+00007507: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=13
>2012-08-06 10:05:38.611+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorUnref:252 : QEMU_MONITOR_UNREF: mon=0x7f59d80ca510 refs=2
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.611+00007507: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=22 e=25 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=20 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543477
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543194
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCalculateTimeout:346 : Timeout at 1344247543194 due in 4583 ms
>2012-08-06 10:05:38.611+00007507: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4583
>2012-08-06 10:05:38.611+00007507: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorRef:243 : QEMU_MONITOR_REF: mon=0x7f59d80ca510 refs=3
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorIOProcess:369 : QEMU_MONITOR_IO_PROCESS: mon=0x7f59d80ca510 buf={"timestamp": {"seconds": 1344247538, "microseconds": 562477}, "event": "STOP"}
>{"timestamp": {"seconds": 1344247538, "microseconds": 611153}, "event": "SHUTDOWN"}
> len=166
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorJSONIOProcessLine:146 : Line [{"timestamp": {"seconds": 1344247538, "microseconds": 562477}, "event": "STOP"}]
>2012-08-06 10:05:38.611+00007507: debug : virJSONValueFromString:975 : string={"timestamp": {"seconds": 1344247538, "microseconds": 562477}, "event": "STOP"}
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleStartMap:852 : parser=0x7fff71506b10
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleMapKey:833 : parser=0x7fff71506b10 key=0xba1452
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleStartMap:852 : parser=0x7fff71506b10
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleMapKey:833 : parser=0x7fff71506b10 key=0xba1460
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleNumber:792 : parser=0x7fff71506b10 str=(null)
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleMapKey:833 : parser=0x7fff71506b10 key=0xba1477
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleNumber:792 : parser=0x7fff71506b10 str=(null)
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleEndMap:881 : parser=0x7fff71506b10
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleMapKey:833 : parser=0x7fff71506b10 key=0xba1490
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleString:813 : parser=0x7fff71506b10 str=0xba1499
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleEndMap:881 : parser=0x7fff71506b10
>2012-08-06 10:05:38.611+00007507: debug : virJSONValueFromString:1019 : result=0xb78950
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorJSONIOProcessLine:161 : QEMU_MONITOR_RECV_EVENT: mon=0x7f59d80ca510 event={"timestamp": {"seconds": 1344247538, "microseconds": 562477}, "event": "STOP"}
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorJSONIOProcessEvent:118 : mon=0x7f59d80ca510 obj=0xb78950
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorJSONIOProcessEvent:132 : handle STOP handler=0x480440 data=(nil)
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorEmitStop:979 : mon=0x7f59d80ca510
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorRef:243 : QEMU_MONITOR_REF: mon=0x7f59d80ca510 refs=4
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorUnref:252 : QEMU_MONITOR_UNREF: mon=0x7f59d80ca510 refs=3
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorJSONIOProcessLine:146 : Line [{"timestamp": {"seconds": 1344247538, "microseconds": 611153}, "event": "SHUTDOWN"}]
>2012-08-06 10:05:38.611+00007507: debug : virJSONValueFromString:975 : string={"timestamp": {"seconds": 1344247538, "microseconds": 611153}, "event": "SHUTDOWN"}
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleStartMap:852 : parser=0x7fff71506b10
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleMapKey:833 : parser=0x7fff71506b10 key=0xba1452
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleStartMap:852 : parser=0x7fff71506b10
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleMapKey:833 : parser=0x7fff71506b10 key=0xba1460
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleNumber:792 : parser=0x7fff71506b10 str=(null)
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleMapKey:833 : parser=0x7fff71506b10 key=0xba1477
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleNumber:792 : parser=0x7fff71506b10 str=(null)
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleEndMap:881 : parser=0x7fff71506b10
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleMapKey:833 : parser=0x7fff71506b10 key=0xba1490
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleString:813 : parser=0x7fff71506b10 str=0xba1499
>2012-08-06 10:05:38.611+00007507: debug : virJSONParserHandleEndMap:881 : parser=0x7fff71506b10
>2012-08-06 10:05:38.611+00007507: debug : virJSONValueFromString:1019 : result=0xb789e0
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorJSONIOProcessLine:161 : QEMU_MONITOR_RECV_EVENT: mon=0x7f59d80ca510 event={"timestamp": {"seconds": 1344247538, "microseconds": 611153}, "event": "SHUTDOWN"}
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorJSONIOProcessEvent:118 : mon=0x7f59d80ca510 obj=0xb789e0
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorJSONIOProcessEvent:132 : handle SHUTDOWN handler=0x480450 data=(nil)
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorEmitShutdown:949 : mon=0x7f59d80ca510
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorRef:243 : QEMU_MONITOR_REF: mon=0x7f59d80ca510 refs=4
>2012-08-06 10:05:38.611+00007507: debug : qemuProcessHandleShutdown:638 : vm=0x7f59dc0abdf0
>2012-08-06 10:05:38.611+00007507: debug : qemuProcessHandleShutdown:645 : Ignoring repeated SHUTDOWN event from domain ga
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorUnref:252 : QEMU_MONITOR_UNREF: mon=0x7f59d80ca510 refs=3
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorJSONIOProcess:217 : Total used 166 bytes out of 166 available in buffer
>2012-08-06 10:05:38.611+00007507: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=13
>2012-08-06 10:05:38.611+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.611+00007507: debug : qemuMonitorUnref:252 : QEMU_MONITOR_UNREF: mon=0x7f59d80ca510 refs=2
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-06 10:05:38.611+00007507: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.611+00007507: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=22 e=25 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=20 e=1 d=0
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543477
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543194
>2012-08-06 10:05:38.611+00007507: debug : virEventPollCalculateTimeout:346 : Timeout at 1344247543194 due in 4583 ms
>2012-08-06 10:05:38.611+00007507: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4583
>2012-08-06 10:05:38.636+00007507: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-06 10:05:38.636+00007507: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-06 10:05:38.636+00007507: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-06 10:05:38.636+00007507: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-06 10:05:38.636+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.636+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.636+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.636+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.636+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.636+00007507: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
>2012-08-06 10:05:38.642+00007507: debug : udevEventHandleCallback:1467 : udev action: 'remove'
>2012-08-06 10:05:38.642+00007507: debug : udevRemoveOneDevice:1218 : Failed to find device to remove that has udev name '/sys/devices/virtual/net/vnet0/queues/tx-0'
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.643+00007507: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=22 e=25 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=20 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543477
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543194
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCalculateTimeout:346 : Timeout at 1344247543194 due in 4551 ms
>2012-08-06 10:05:38.643+00007507: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4551
>2012-08-06 10:05:38.643+00007507: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
>2012-08-06 10:05:38.643+00007507: debug : udevEventHandleCallback:1467 : udev action: 'remove'
>2012-08-06 10:05:38.643+00007507: debug : udevRemoveOneDevice:1218 : Failed to find device to remove that has udev name '/sys/devices/virtual/net/vnet0/queues/rx-0'
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-06 10:05:38.643+00007507: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.643+00007507: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=22 e=25 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=20 e=1 d=0
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543477
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543194
>2012-08-06 10:05:38.643+00007507: debug : virEventPollCalculateTimeout:346 : Timeout at 1344247543194 due in 4551 ms
>2012-08-06 10:05:38.643+00007507: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4551
>2012-08-06 10:05:38.669+00007507: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-06 10:05:38.669+00007507: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-06 10:05:38.669+00007507: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-06 10:05:38.669+00007507: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-06 10:05:38.669+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.669+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.669+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.669+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.669+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.669+00007507: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
>2012-08-06 10:05:38.669+00007507: debug : udevEventHandleCallback:1467 : udev action: 'remove'
>2012-08-06 10:05:38.669+00007507: debug : udevRemoveOneDevice:1214 : Removing device 'net_vnet0_fe_54_00_06_37_8d' with sysfs path '/sys/devices/virtual/net/vnet0'
>2012-08-06 10:05:38.669+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.669+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.669+00007507: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-06 10:05:38.669+00007507: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-06 10:05:38.669+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.669+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.669+00007507: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-06 10:05:38.669+00007507: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-06 10:05:38.669+00007507: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-08-06 10:05:38.669+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-06 10:05:38.669+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-06 10:05:38.669+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-06 10:05:38.669+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-06 10:05:38.669+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-08-06 10:05:38.669+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-08-06 10:05:38.669+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-08-06 10:05:38.669+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=22 e=25 d=0
>2012-08-06 10:05:38.669+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2012-08-06 10:05:38.669+00007507: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=20 e=1 d=0
>2012-08-06 10:05:38.669+00007507: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-06 10:05:38.669+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543477
>2012-08-06 10:05:38.669+00007507: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1344247543194
>2012-08-06 10:05:38.669+00007507: debug : virEventPollCalculateTimeout:346 : Timeout at 1344247543194 due in 4525 ms
>2012-08-06 10:05:38.669+00007507: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4525
>2012-08-06 10:05:38.694+00007507: debug : virEventPollRunOnce:625 : Poll got 2 event(s)
>2012-08-06 10:05:38.694+00007507: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-06 10:05:38.694+00007507: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-06 10:05:38.694+00007507: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-06 10:05:38.694+00007507: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-06 10:05:38.694+00007507: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-06 10:05:38.694+00007507: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-06 10:05:38.694+00007507: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-06 10:05:38.694+00007507: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-06 10:05:38.694+00007507: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-06 10:05:38.694+00007507: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-06 10:05:38.694+00007507: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=9
>2012-08-06 10:05:38.694+00007507: debug : qemuMonitorRef:243 : QEMU_MONITOR_REF: mon=0x7f59d80ca510 refs=3
>2012-08-06 10:05:38.694+00007507: error : qemuMonitorIO:616 : internal error End of file from monitor
>2012-08-06 10:05:38.694+00007507: debug : qemuMonitorIO:649 : Error on monitor internal error End of file from monitor
>2012-08-06 10:05:38.694+00007507: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=12
>2012-08-06 10:05:38.694+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.694+00007507: debug : qemuMonitorUnref:252 : QEMU_MONITOR_UNREF: mon=0x7f59d80ca510 refs=2
>2012-08-06 10:05:38.694+00007507: debug : qemuMonitorIO:672 : Triggering EOF callback
>2012-08-06 10:05:38.694+00007507: debug : qemuProcessHandleMonitorEOF:288 : Received EOF on 0x7f59dc0abdf0 'ga'
>2012-08-06 10:05:38.694+00007507: debug : qemuProcessStop:3981 : Shutting down VM 'ga' pid=7706 flags=0
>2012-08-06 10:05:38.695+00007507: debug : virFileClose:72 : Closed fd 25
>2012-08-06 10:05:38.695+00007507: debug : qemuAgentClose:824 : mon=0x7f59d80ca680
>2012-08-06 10:05:38.695+00007507: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=9
>2012-08-06 10:05:38.695+00007507: debug : virEventPollRemoveHandle:188 : mark delete 8 21
>2012-08-06 10:05:38.695+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.695+00007507: debug : virFileClose:72 : Closed fd 21
>2012-08-06 10:05:38.695+00007507: debug : qemuAgentUnref:170 : 2
>2012-08-06 10:05:38.695+00007507: debug : qemuMonitorClose:801 : QEMU_MONITOR_CLOSE: mon=0x7f59d80ca510 refs=2
>2012-08-06 10:05:38.695+00007507: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=8
>2012-08-06 10:05:38.695+00007507: debug : virEventPollRemoveHandle:188 : mark delete 7 22
>2012-08-06 10:05:38.695+00007507: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -261896096
>2012-08-06 10:05:38.695+00007511: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=13
>2012-08-06 10:05:38.695+00007511: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-08-06 10:05:38.695+00007507: debug : virFileClose:72 : Closed fd 22
>2012-08-06 10:05:38.695+00007507: debug : qemuMonitorUnref:252 : QEMU_MONITOR_UNREF: mon=0x7f59d80ca510 refs=1
>2012-08-06 10:05:38.695+00007511: debug : qemuAgentCommand:1023 : Receive command reply ret=0 rxObject=(nil)
>2012-08-06 10:05:38.695+00007511: debug : qemuAgentCommand:1030 : Woken up by event 2
>
>
>     ====== end of log =====
>
>2012-08-06 10:05:46.384+0000: 7958: info : libvirt version: 0.10.0, package: 0rc0.el6 (Red Hat, Inc. <http://bugzilla.redhat.com/bugzilla>, 2012-08-02-03:44:27, x86-007.build.bos.redhat.com)
>2012-08-06 10:05:46.384+0000: 7958: error : qemuMonitorOpenUnix:308 : failed to connect to monitor socket: No such process

Actions: View

Attachments on bug 845966: 602475 | 602476