[libvirt] libvirtd crash upon virsh destroy
Scott Sullivan
ssullivan at liquidweb.com
Tue Dec 4 15:38:07 UTC 2012
Running libvirt v1.0.0, with f0e72b2f5c675f927d04545dc5095f9e5998f171
applied. The problem (which I cannot reproduce reliably), is that
libvirtd crashes sometimes on a destroy (sequence of events and crash
log below).
I was wondering if anyone had any insights as to the cause of this
crash, or even if any commits after v1.0.0 might address this. If I can
provide anymore information to help debug this I would be happy to try
and provide it. At the time of this crash, the instance being destroyed
did not have any RBD attachments, it was just running off its local LVM.
Sequence of events:
1.) virsh destroy ID
End of file while reading data: Input/output error ; error: Failed to
reconnect to the hypervisor
(libvirtd at this point has crashed)
2.) Resulting crash entry from /var/log/libvirt/libvirtd.log:
2012-11-26 17:37:13.239+0000: 10314: error : qemuMonitorIO:614 :
internal error End of file from monitor
2012-11-27 16:48:56.195+0000: 10314: error : qemuMonitorIO:614 :
internal error End of file from monitor
Caught Segmentation violation dumping internal log buffer:
====== start of log =====
^@patchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1
events=1
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.078+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=8, f=20 e=25 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=5 d=0
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941072
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941072 due in 4994 ms
2012-11-27 16:48:56.078+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=4994
2012-11-27 16:48:56.078+000010314: debug : virEventPollRunOnce:625 :
Poll got 1 event(s)
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE:
watch=50563 events=2
2012-11-27 16:48:56.078+000010314: debug : virNetMessageFree:73 :
msg=0x888520 nfds=0 cb=(nil)
2012-11-27 16:48:56.078+000010314: debug :
virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x88cd10
tx=(nil)
2012-11-27 16:48:56.078+000010314: debug :
virNetServerClientCalculateHandleMode:184 : mode=1
2012-11-27 16:48:56.078+000010314: debug : virEventPollUpdateHandle:146
: EVENT_POLL_UPDATE_HANDLE: watch=50563 events=1
2012-11-27 16:48:56.078+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.078+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=8, f=20 e=25 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941072
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941072 due in 4994 ms
2012-11-27 16:48:56.078+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=4994
2012-11-27 16:48:56.078+000010314: debug : virEventPollRunOnce:625 :
Poll got 1 event(s)
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE:
watch=50563 events=1
2012-11-27 16:48:56.078+000010314: debug : virNetMessageDecodeLength:152
: Got length, now need 28 total (24 more)
2012-11-27 16:48:56.078+000010314: debug :
virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x88cd10
tx=(nil)
2012-11-27 16:48:56.078+000010314: debug :
virNetServerClientCalculateHandleMode:184 : mode=1
2012-11-27 16:48:56.078+000010314: debug : virEventPollUpdateHandle:146
: EVENT_POLL_UPDATE_HANDLE: watch=50563 events=1
2012-11-27 16:48:56.078+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.078+000010314: debug :
virNetServerClientDispatchRead:1007 : RPC_SERVER_CLIENT_MSG_RX:
client=0x88d980 len=28 prog=536903814 vers=1 proc=110 type=0 status=0
serial=3
2012-11-27 16:48:56.078+000010314: debug : virKeepAliveCheckMessage:384
: ka=0x887c00, client=0x88d980, msg=0x88cd10
2012-11-27 16:48:56.078+000010314: debug : virEventPollUpdateTimeout:254
: EVENT_POLL_UPDATE_TIMEOUT: timer=101086 frequency=5000
2012-11-27 16:48:56.078+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.078+000010314: debug : virObjectRef:168 :
OBJECT_REF: obj=0x88d980
2012-11-27 16:48:56.078+000010314: debug :
virNetServerDispatchNewMessage:219 : server=0x879d80 client=0x88d980
message=0x88cd10
2012-11-27 16:48:56.078+000010314: debug : virObjectRef:168 :
OBJECT_REF: obj=0x882540
2012-11-27 16:48:56.078+000010314: debug : virNetMessageNew:45 :
msg=0x87af70 tracked=1
2012-11-27 16:48:56.078+000010314: debug :
virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x87af70
tx=(nil)
2012-11-27 16:48:56.078+000010314: debug :
virNetServerClientCalculateHandleMode:184 : mode=1
2012-11-27 16:48:56.078+000010318: debug : virNetServerHandleJob:190 :
server=0x879d80 client=0x88d980 message=0x88cd10 prog=0x882540
2012-11-27 16:48:56.078+000010314: debug : virEventPollUpdateHandle:146
: EVENT_POLL_UPDATE_HANDLE: watch=50563 events=1
2012-11-27 16:48:56.078+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.078+000010318: debug :
virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0
serial=3 proc=110
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.078+000010318: debug :
remoteDispatchGetURIHelper:7297 : server=0x879d80 client=0x88d980
msg=0x88cd10 rerr=0x7f642e214be0 args=0x7f6400000cf0 ret=0x7f6400000c80
2012-11-27 16:48:56.078+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.078+000010318: debug : virConnectGetURI:1711 :
conn=0x7f641c0728f0
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.078+000010318: debug :
virNetMessageEncodePayload:358 : Encode length as 48
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.078+000010318: debug :
virNetServerClientSendMessageLocked:1251 : msg=0x88cd10 proc=110 len=48
offset=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.078+000010318: debug :
virNetServerClientSendMessageLocked:1259 :
RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x88d980 len=48 prog=536903814
vers=1 proc=110 type=1 status=0 serial=3
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.078+000010318: debug :
virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x87af70
tx=0x88cd10
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.078+000010318: debug :
virNetServerClientCalculateHandleMode:184 : mode=3
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.078+000010318: debug : virEventPollUpdateHandle:146
: EVENT_POLL_UPDATE_HANDLE: watch=50563 events=3
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=8, f=20 e=25 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941078
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941078 due in 5000 ms
2012-11-27 16:48:56.078+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=5000
2012-11-27 16:48:56.078+000010318: debug :
virEventPollInterruptLocked:701 : Interrupting
2012-11-27 16:48:56.078+000010318: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x882540
2012-11-27 16:48:56.078+000010318: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x88d980
2012-11-27 16:48:56.078+000010314: debug : virEventPollRunOnce:625 :
Poll got 1 event(s)
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1
events=1
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.078+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=8, f=20 e=25 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.078+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=5 d=0
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941078
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941078 due in 5000 ms
2012-11-27 16:48:56.078+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=5000
2012-11-27 16:48:56.078+000010314: debug : virEventPollRunOnce:625 :
Poll got 1 event(s)
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.078+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE:
watch=50563 events=2
2012-11-27 16:48:56.078+000010314: debug : virNetMessageFree:73 :
msg=0x88cd10 nfds=0 cb=(nil)
2012-11-27 16:48:56.078+000010314: debug :
virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x87af70
tx=(nil)
2012-11-27 16:48:56.078+000010314: debug :
virNetServerClientCalculateHandleMode:184 : mode=1
2012-11-27 16:48:56.078+000010314: debug : virEventPollUpdateHandle:146
: EVENT_POLL_UPDATE_HANDLE: watch=50563 events=1
2012-11-27 16:48:56.078+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.078+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.078+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=8, f=20 e=25 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941078
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941078 due in 4999 ms
2012-11-27 16:48:56.079+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=4999
2012-11-27 16:48:56.079+000010314: debug : virEventPollRunOnce:625 :
Poll got 1 event(s)
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE:
watch=50563 events=1
2012-11-27 16:48:56.079+000010314: debug : virNetMessageDecodeLength:152
: Got length, now need 40 total (36 more)
2012-11-27 16:48:56.079+000010314: debug :
virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x87af70
tx=(nil)
2012-11-27 16:48:56.079+000010314: debug :
virNetServerClientCalculateHandleMode:184 : mode=1
2012-11-27 16:48:56.079+000010314: debug : virEventPollUpdateHandle:146
: EVENT_POLL_UPDATE_HANDLE: watch=50563 events=1
2012-11-27 16:48:56.079+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.079+000010314: debug :
virNetServerClientDispatchRead:1007 : RPC_SERVER_CLIENT_MSG_RX:
client=0x88d980 len=40 prog=536903814 vers=1 proc=23 type=0 status=0
serial=4
2012-11-27 16:48:56.079+000010314: debug : virKeepAliveCheckMessage:384
: ka=0x887c00, client=0x88d980, msg=0x87af70
2012-11-27 16:48:56.079+000010314: debug : virEventPollUpdateTimeout:254
: EVENT_POLL_UPDATE_TIMEOUT: timer=101086 frequency=5000
2012-11-27 16:48:56.079+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.079+000010314: debug : virObjectRef:168 :
OBJECT_REF: obj=0x88d980
2012-11-27 16:48:56.079+000010314: debug :
virNetServerDispatchNewMessage:219 : server=0x879d80 client=0x88d980
message=0x87af70
2012-11-27 16:48:56.079+000010314: debug : virObjectRef:168 :
OBJECT_REF: obj=0x882540
2012-11-27 16:48:56.079+000010314: debug : virNetMessageNew:45 :
msg=0x888590 tracked=1
2012-11-27 16:48:56.079+000010314: debug :
virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x888590
tx=(nil)
2012-11-27 16:48:56.079+000010314: debug :
virNetServerClientCalculateHandleMode:184 : mode=1
2012-11-27 16:48:56.079+000010314: debug : virEventPollUpdateHandle:146
: EVENT_POLL_UPDATE_HANDLE: watch=50563 events=1
2012-11-27 16:48:56.079+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.079+000010314: debug :
virNetServerClientDispatchRead:1007 : RPC_SERVER_CLIENT_MSG_RX:
client=0x88d980 len=40 prog=536903814 vers=1 proc=23 type=0 status=0
serial=4
2012-11-27 16:48:56.079+000010314: debug : virKeepAliveCheckMessage:384
: ka=0x887c00, client=0x88d980, msg=0x87af70
2012-11-27 16:48:56.079+000010314: debug : virEventPollUpdateTimeout:254
: EVENT_POLL_UPDATE_TIMEOUT: timer=101086 frequency=5000
2012-11-27 16:48:56.079+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.079+000010314: debug : virObjectRef:168 :
OBJECT_REF: obj=0x88d980
2012-11-27 16:48:56.079+000010314: debug :
virNetServerDispatchNewMessage:219 : server=0x879d80 client=0x88d980
message=0x87af70
2012-11-27 16:48:56.079+000010314: debug : virObjectRef:168 :
OBJECT_REF: obj=0x882540
2012-11-27 16:48:56.079+000010314: debug : virNetMessageNew:45 :
msg=0x888590 tracked=1
2012-11-27 16:48:56.079+000010314: debug :
virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x888590
tx=(nil)
2012-11-27 16:48:56.079+000010314: debug :
virNetServerClientCalculateHandleMode:184 : mode=1
2012-11-27 16:48:56.079+000010314: debug : virEventPollUpdateHandle:146
: EVENT_POLL_UPDATE_HANDLE: watch=50563 events=1
2012-11-27 16:48:56.079+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.079+000010315: debug : virNetServerHandleJob:190 :
server=0x879d80 client=0x88d980 message=0x87af70 prog=0x882540
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.079+000010315: debug :
virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0
serial=4 proc=23
2012-11-27 16:48:56.079+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.079+000010315: debug :
remoteDispatchDomainLookupByNameHelper:3084 : server=0x879d80
client=0x88d980 msg=0x87af70 rerr=0x7f6430017be0 args=0x7f640800f550
ret=0x7f640800f610
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.079+000010315: debug : virDomainLookupByName:2128 :
conn=0x7f641c0728f0, name=4274X8
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.079+000010315: debug : virObjectNew:110 :
OBJECT_NEW: obj=0x7f640800f5d0 classname=virDomain
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.079+000010315: debug : virObjectRef:168 :
OBJECT_REF: obj=0x7f641c0728f0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=8, f=20 e=25 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.079+000010315: debug : virDomainFree:2292 :
dom=0x7f640800f5d0, (VM: name=4274X8,
uuid=3b14a4c4-19e0-11e2-ad58-8ccdadf125ba)
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=1 d=0
2012-11-27 16:48:56.079+000010315: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x7f640800f5d0
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.079+000010315: debug : virObjectUnref:137 :
OBJECT_DISPOSE: obj=0x7f640800f5d0
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941079
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941079 due in 5000 ms
2012-11-27 16:48:56.079+000010315: debug : virDomainDispose:225 :
release domain 0x7f640800f5d0 4274X8 3b14a4c4-19e0-11e2-ad58-8ccdadf125ba
2012-11-27 16:48:56.079+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=5000
2012-11-27 16:48:56.079+000010315: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x7f641c0728f0
2012-11-27 16:48:56.079+000010315: debug :
virNetMessageEncodePayload:358 : Encode length as 60
2012-11-27 16:48:56.079+000010315: debug :
virNetServerClientSendMessageLocked:1251 : msg=0x87af70 proc=23 len=60
offset=0
2012-11-27 16:48:56.079+000010315: debug :
virNetServerClientSendMessageLocked:1259 :
RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x88d980 len=60 prog=536903814
vers=1 proc=23 type=1 status=0 serial=4
2012-11-27 16:48:56.079+000010315: debug :
virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x888590
tx=0x87af70
2012-11-27 16:48:56.079+000010315: debug :
virNetServerClientCalculateHandleMode:184 : mode=3
2012-11-27 16:48:56.079+000010315: debug : virEventPollUpdateHandle:146
: EVENT_POLL_UPDATE_HANDLE: watch=50563 events=3
2012-11-27 16:48:56.079+000010315: debug :
virEventPollInterruptLocked:701 : Interrupting
2012-11-27 16:48:56.079+000010315: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x882540
2012-11-27 16:48:56.079+000010315: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x88d980
2012-11-27 16:48:56.079+000010314: debug : virEventPollRunOnce:625 :
Poll got 1 event(s)
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1
events=1
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.079+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=8, f=20 e=25 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=5 d=0
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941079
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941079 due in 5000 ms
2012-11-27 16:48:56.079+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=5000
2012-11-27 16:48:56.079+000010314: debug : virEventPollRunOnce:625 :
Poll got 1 event(s)
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE:
watch=50563 events=2
2012-11-27 16:48:56.079+000010314: debug : virNetMessageFree:73 :
msg=0x87af70 nfds=0 cb=(nil)
2012-11-27 16:48:56.079+000010314: debug :
virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x888590
tx=(nil)
2012-11-27 16:48:56.079+000010314: debug :
virNetServerClientCalculateHandleMode:184 : mode=1
2012-11-27 16:48:56.079+000010314: debug : virEventPollUpdateHandle:146
: EVENT_POLL_UPDATE_HANDLE: watch=50563 events=1
2012-11-27 16:48:56.079+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.079+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=8, f=20 e=25 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.079+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=1 d=0
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941079
2012-11-27 16:48:56.079+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941079 due in 5000 ms
2012-11-27 16:48:56.079+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=5000
2012-11-27 16:48:56.079+000010314: debug : virEventPollRunOnce:625 :
Poll got 1 event(s)
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.079+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.080+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.080+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.080+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.080+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.080+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.080+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE:
watch=50563 events=1
2012-11-27 16:48:56.080+000010314: debug : virNetMessageDecodeLength:152
: Got length, now need 60 total (56 more)
2012-11-27 16:48:56.080+000010314: debug :
virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x888590
tx=(nil)
2012-11-27 16:48:56.080+000010314: debug :
virNetServerClientCalculateHandleMode:184 : mode=1
2012-11-27 16:48:56.080+000010314: debug : virEventPollUpdateHandle:146
: EVENT_POLL_UPDATE_HANDLE: watch=50563 events=1
2012-11-27 16:48:56.080+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.080+000010314: debug :
virNetServerClientDispatchRead:1007 : RPC_SERVER_CLIENT_MSG_RX:
client=0x88d980 len=60 prog=536903814 vers=1 proc=12 type=0 status=0
serial=5
2012-11-27 16:48:56.080+000010314: debug : virKeepAliveCheckMessage:384
: ka=0x887c00, client=0x88d980, msg=0x888590
2012-11-27 16:48:56.080+000010314: debug : virEventPollUpdateTimeout:254
: EVENT_POLL_UPDATE_TIMEOUT: timer=101086 frequency=5000
2012-11-27 16:48:56.080+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.080+000010314: debug : virObjectRef:168 :
OBJECT_REF: obj=0x88d980
2012-11-27 16:48:56.080+000010314: debug :
virNetServerDispatchNewMessage:219 : server=0x879d80 client=0x88d980
message=0x888590
2012-11-27 16:48:56.080+000010314: debug : virObjectRef:168 :
OBJECT_REF: obj=0x882540
2012-11-27 16:48:56.080+000010314: debug : virNetMessageNew:45 :
msg=0x88cd10 tracked=1
2012-11-27 16:48:56.080+000010314: debug :
virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x88cd10
tx=(nil)
2012-11-27 16:48:56.080+000010314: debug :
virNetServerClientCalculateHandleMode:184 : mode=1
2012-11-27 16:48:56.080+000010314: debug : virEventPollUpdateHandle:146
: EVENT_POLL_UPDATE_HANDLE: watch=50563 events=1
2012-11-27 16:48:56.080+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.080+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.080+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.080+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.080+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.080+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.080+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.080+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.080+000010319: debug : virNetServerHandleJob:190 :
server=0x879d80 client=0x88d980 message=0x888590 prog=0x882540
2012-11-27 16:48:56.080+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.080+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.080+000010319: debug :
virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0
serial=5 proc=12
2012-11-27 16:48:56.080+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.080+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.080+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.080+000010319: debug :
remoteDispatchDomainDestroyHelper:1254 : server=0x879d80 client=0x88d980
msg=0x888590 rerr=0x7f642d813be0 args=0x7f64180021c0 ret=0x7f6418002bc0
2012-11-27 16:48:56.080+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=8, f=20 e=25 d=0
2012-11-27 16:48:56.080+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.080+000010319: debug : virObjectNew:110 :
OBJECT_NEW: obj=0x7f6418002180 classname=virDomain
2012-11-27 16:48:56.080+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=1 d=0
2012-11-27 16:48:56.080+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.080+000010319: debug : virObjectRef:168 :
OBJECT_REF: obj=0x7f641c0728f0
2012-11-27 16:48:56.080+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941080
2012-11-27 16:48:56.080+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941080 due in 5000 ms
2012-11-27 16:48:56.080+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=5000
2012-11-27 16:48:56.080+000010319: debug : virDomainDestroy:2183 :
dom=0x7f6418002180, (VM: name=4274X8,
uuid=3b14a4c4-19e0-11e2-ad58-8ccdadf125ba)
2012-11-27 16:48:56.080+000010319: debug : qemuProcessKill:3941 :
vm=4274X8 pid=22404 flags=1
2012-11-27 16:48:56.080+000010319: debug : virProcessKillPainfully:253 :
vpid=22404 force=1
2012-11-27 16:48:56.097+000010314: debug : virEventPollRunOnce:625 :
Poll got 1 event(s)
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=6
events=1
2012-11-27 16:48:56.097+000010314: debug : virNetlinkEventCallback:346 :
dispatching to max 0 clients, called from event watch 6
2012-11-27 16:48:56.097+000010314: debug : virNetlinkEventCallback:359 :
event not handled.
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.097+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=8, f=20 e=25 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941080
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941080 due in 4983 ms
2012-11-27 16:48:56.097+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=4983
2012-11-27 16:48:56.097+000010314: debug : virEventPollRunOnce:625 :
Poll got 1 event(s)
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=6
events=1
2012-11-27 16:48:56.097+000010314: debug : virNetlinkEventCallback:346 :
dispatching to max 0 clients, called from event watch 6
2012-11-27 16:48:56.097+000010314: debug : virNetlinkEventCallback:359 :
event not handled.
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.097+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=8, f=20 e=25 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941080
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941080 due in 4983 ms
2012-11-27 16:48:56.097+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=4983
2012-11-27 16:48:56.097+000010314: debug : virEventPollRunOnce:625 :
Poll got 2 event(s)
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=6
events=1
2012-11-27 16:48:56.097+000010314: debug : virNetlinkEventCallback:346 :
dispatching to max 0 clients, called from event watch 6
2012-11-27 16:48:56.097+000010314: debug : virNetlinkEventCallback:359 :
event not handled.
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=7
events=1
2012-11-27 16:48:56.097+000010314: debug : udevEventHandleCallback:1467
: udev action: 'remove'
2012-11-27 16:48:56.097+000010314: debug : udevRemoveOneDevice:1218 :
Failed to find device to remove that has udev name
'/sys/devices/virtual/net/4274X8_0/queues/tx-0'
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.097+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941080
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941080 due in 4983 ms
2012-11-27 16:48:56.097+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=4983
2012-11-27 16:48:56.097+000010314: debug : virEventPollRunOnce:625 :
Poll got 1 event(s)
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=7
events=1
2012-11-27 16:48:56.097+000010314: debug : udevEventHandleCallback:1467
: udev action: 'remove'
2012-11-27 16:48:56.097+000010314: debug : udevRemoveOneDevice:1218 :
Failed to find device to remove that has udev name
'/sys/devices/virtual/net/4274X8_0/queues/rx-0'
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.097+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.097+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=8, f=20 e=25 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.097+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=1 d=0
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941080
2012-11-27 16:48:56.097+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941080 due in 4983 ms
2012-11-27 16:48:56.097+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=4983
2012-11-27 16:48:56.126+000010314: debug : virEventPollRunOnce:625 :
Poll got 1 event(s)
2012-11-27 16:48:56.126+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.126+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.126+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.126+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.126+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.126+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.126+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.126+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.126+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.126+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=7
events=1
2012-11-27 16:48:56.126+000010314: debug : udevEventHandleCallback:1467
: udev action: 'remove'
2012-11-27 16:48:56.126+000010314: debug : udevRemoveOneDevice:1214 :
Removing device 'net_4274X8_0_fe_54_00_4b_82_15' with sysfs path
'/sys/devices/virtual/net/4274X8_0'
2012-11-27 16:48:56.126+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.126+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.126+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.126+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.126+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.126+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.126+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.126+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.126+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.126+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.126+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.126+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.126+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.126+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.126+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.126+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=8, f=20 e=25 d=0
2012-11-27 16:48:56.126+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.126+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=9 w=50563, f=18 e=1 d=0
2012-11-27 16:48:56.126+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.126+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941080
2012-11-27 16:48:56.126+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941080 due in 4954 ms
2012-11-27 16:48:56.126+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=10 timeout=4954
2012-11-27 16:48:56.194+000010314: debug : virEventPollRunOnce:625 :
Poll got 1 event(s)
2012-11-27 16:48:56.194+000010314: debug :
virEventPollDispatchTimeouts:410 : Dispatch 2
2012-11-27 16:48:56.194+000010314: debug :
virEventPollDispatchHandles:455 : Dispatch 10
2012-11-27 16:48:56.194+000010314: debug :
virEventPollDispatchHandles:469 : i=0 w=1
2012-11-27 16:48:56.194+000010314: debug :
virEventPollDispatchHandles:469 : i=1 w=2
2012-11-27 16:48:56.194+000010314: debug :
virEventPollDispatchHandles:469 : i=2 w=3
2012-11-27 16:48:56.194+000010314: debug :
virEventPollDispatchHandles:469 : i=3 w=4
2012-11-27 16:48:56.194+000010314: debug :
virEventPollDispatchHandles:469 : i=4 w=5
2012-11-27 16:48:56.194+000010314: debug :
virEventPollDispatchHandles:469 : i=5 w=6
2012-11-27 16:48:56.194+000010314: debug :
virEventPollDispatchHandles:469 : i=6 w=7
2012-11-27 16:48:56.194+000010314: debug :
virEventPollDispatchHandles:469 : i=7 w=8
2012-11-27 16:48:56.194+000010314: debug :
virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8
events=9
2012-11-27 16:48:56.194+000010314: debug : virObjectRef:168 :
OBJECT_REF: obj=0x7f6418000a10
2012-11-27 16:48:56.195+000010314: error : qemuMonitorIO:614 : internal
error End of file from monitor
2012-11-27 16:48:56.195+000010314: debug : qemuMonitorIO:647 : Error on
monitor internal error End of file from monitor
2012-11-27 16:48:56.195+000010314: debug : virEventPollUpdateHandle:146
: EVENT_POLL_UPDATE_HANDLE: watch=8 events=12
2012-11-27 16:48:56.195+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.195+000010314: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x7f6418000a10
2012-11-27 16:48:56.195+000010314: debug : qemuMonitorIO:670 :
Triggering EOF callback
2012-11-27 16:48:56.195+000010314: debug :
qemuProcessHandleMonitorEOF:283 : Received EOF on 0x7f64200072f0 '4274X8'
2012-11-27 16:48:56.280+000010319: debug : virObjectRef:168 :
OBJECT_REF: obj=0x7f64200072f0
2012-11-27 16:48:56.280+000010314: debug : qemuProcessStop:3992 :
Shutting down VM '4274X8' pid=22404 flags=0
2012-11-27 16:48:56.280+000010314: debug : virFileClose:72 : Closed fd 22
2012-11-27 16:48:56.280+000010314: debug : qemuMonitorClose:838 :
QEMU_MONITOR_CLOSE: mon=0x7f6418000a10 refs=2
2012-11-27 16:48:56.280+000010314: debug : virEventPollRemoveHandle:175
: EVENT_POLL_REMOVE_HANDLE: watch=8
2012-11-27 16:48:56.280+000010314: debug : virEventPollRemoveHandle:188
: mark delete 7 20
2012-11-27 16:48:56.280+000010314: debug :
virEventPollInterruptLocked:697 : Skip interrupt, 1 904570880
2012-11-27 16:48:56.280+000010314: debug : virFileClose:72 : Closed fd 20
2012-11-27 16:48:56.280+000010314: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x7f6418000a10
2012-11-27 16:48:56.280+000010314: debug : qemuProcessKill:3941 :
vm=4274X8 pid=22404 flags=5
2012-11-27 16:48:56.280+000010314: debug : virProcessKillPainfully:253 :
vpid=22404 force=1
2012-11-27 16:48:56.280+000010314: debug : virObjectRef:168 :
OBJECT_REF: obj=0x7f64200072f0
2012-11-27 16:48:56.280+000010314: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x7f64200072f0
2012-11-27 16:48:56.280+000010314: debug : qemuDomainCleanupRun:1996 :
driver=0x7f642001bd70, vm=4274X8
2012-11-27 16:48:56.280+000010314: debug :
qemuProcessAutoDestroyRemove:4463 : vm=4274X8
2012-11-27 16:48:56.280+000010314: debug :
qemuDriverCloseCallbackUnset:661 : vm=4274X8,
uuid=3b14a4c4-19e0-11e2-ad58-8ccdadf125ba, cb=0x7f6428cd7800
2012-11-27 16:48:56.280+000010314: debug :
virSecurityDACRestoreSecurityAllLabel:726 : Restoring security label on
4274X8 migrated=0
2012-11-27 16:48:56.280+000010314: info :
virSecurityDACRestoreSecurityFileLabel:315 : Restoring DAC user and
group on '/dev/LVM/4274X8'
2012-11-27 16:48:56.280+000010314: info : virSecurityDACSetOwnership:271
: Setting DAC user and group on '/dev/dm-4' to '0:0'
2012-11-27 16:48:56.280+000010314: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x7f64200a24a0
2012-11-27 16:48:56.280+000010314: debug : virObjectUnref:137 :
OBJECT_DISPOSE: obj=0x7f64200a24a0
2012-11-27 16:48:56.280+000010314: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x7f64200072f0
2012-11-27 16:48:56.280+000010314: debug :
virEventPollDispatchHandles:469 : i=8 w=42213
2012-11-27 16:48:56.280+000010314: debug :
virEventPollDispatchHandles:469 : i=9 w=50563
2012-11-27 16:48:56.280+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.280+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 10
2012-11-27 16:48:56.280+000010314: debug :
virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=8
2012-11-27 16:48:56.280+000010314: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x7f6418000a10
2012-11-27 16:48:56.280+000010314: debug : virObjectUnref:137 :
OBJECT_DISPOSE: obj=0x7f6418000a10
2012-11-27 16:48:56.280+000010314: debug : qemuMonitorDispose:247 :
mon=0x7f6418000a10
2012-11-27 16:48:56.280+000010314: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x7f64200072f0
2012-11-27 16:48:56.280+000010314: debug : virEventRunDefaultImpl:244 :
running default event implementation
2012-11-27 16:48:56.280+000010314: debug :
virEventPollCleanupTimeouts:501 : Cleanup 2
2012-11-27 16:48:56.280+000010314: debug :
virEventPollCleanupHandles:549 : Cleanup 9
2012-11-27 16:48:56.280+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=0 w=1, f=7 e=1 d=0
2012-11-27 16:48:56.280+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=1 w=2, f=9 e=1 d=0
2012-11-27 16:48:56.280+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=2 w=3, f=12 e=1 d=0
2012-11-27 16:48:56.281+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=3 w=4, f=13 e=1 d=0
2012-11-27 16:48:56.281+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=4 w=5, f=6 e=1 d=0
2012-11-27 16:48:56.281+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=5 w=6, f=14 e=1 d=0
2012-11-27 16:48:56.281+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=6 w=7, f=16 e=1 d=0
2012-11-27 16:48:56.281+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=7 w=42213, f=24 e=25 d=0
2012-11-27 16:48:56.281+000010314: debug : virEventPollMakePollFDs:378 :
Prepare n=8 w=50563, f=18 e=1 d=0
2012-11-27 16:48:56.281+000010314: debug :
virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-11-27 16:48:56.281+000010314: debug :
virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1354034941080
2012-11-27 16:48:56.281+000010314: debug :
virEventPollCalculateTimeout:346 : Timeout at 1354034941080 due in 4799 ms
2012-11-27 16:48:56.281+000010314: debug : virEventPollRunOnce:614 :
EVENT_POLL_RUN: nhandles=9 timeout=4799
2012-11-27 16:48:56.281+000010319: debug : virObjectUnref:135 :
OBJECT_UNREF: obj=0x7f64200072f0
2012-11-27 16:48:56.281+000010319: debug : virObjectUnref:137 :
OBJECT_DISPOSE: obj=0x7f64200072f0
2012-11-27 16:48:56.281+000010319: debug : virDomainObjDispose:1746 :
obj=0x7f64200072f0
====== end of log =====
More information about the libvir-list
mailing list