Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 939925 Details for
Bug 1145028
send-key does not crash windows guest even when it should
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
RHEL7 debug log
logsendkey.text (text/plain), 159.97 KB, created by
Luyao Huang
on 2014-09-22 08:54:01 UTC
(
hide
)
Description:
RHEL7 debug log
Filename:
MIME Type:
Creator:
Luyao Huang
Created:
2014-09-22 08:54:01 UTC
Size:
159.97 KB
patch
obsolete
>2014-09-22 07:19:34.685+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.685+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 >2014-09-22 07:19:34.685+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 10 >2014-09-22 07:19:34.685+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.685+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.685+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.685+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=3 events=1 >2014-09-22 07:19:34.686+0000: 9152: debug : virNetSocketNew:163 : localAddr=0x7fff42ee19c0 remoteAddr=0x7fff42ee1a50 fd=22 errfd=-1 pid=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virObjectNew:202 : OBJECT_NEW: obj=0x7fc42c405130 classname=virNetSocket >2014-09-22 07:19:34.686+0000: 9152: debug : virNetSocketNew:213 : RPC_SOCKET_NEW: sock=0x7fc42c405130 fd=22 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0 >2014-09-22 07:19:34.686+0000: 9152: debug : virNetServerClientNew:418 : sock=0x7fc42c405130 auth=2 tls=(nil) >2014-09-22 07:19:34.686+0000: 9152: debug : virObjectNew:202 : OBJECT_NEW: obj=0x7fc42c40a460 classname=virNetServerClient >2014-09-22 07:19:34.686+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c405130 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollAddTimeout:254 : EVENT_POLL_ADD_TIMEOUT: timer=3 frequency=-1 cb=0x7fc429ebae80 opaque=0x7fc42c40a460 ff=(nil) >2014-09-22 07:19:34.686+0000: 9152: debug : virNetMessageNew:46 : msg=0x7fc42c40a550 tracked=1 >2014-09-22 07:19:34.686+0000: 9152: debug : virNetServerClientNewInternal:388 : RPC_SERVER_CLIENT_NEW: client=0x7fc42c40a460 sock=0x7fc42c405130 >2014-09-22 07:19:34.686+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a550 tx=(nil) >2014-09-22 07:19:34.686+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.686+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.686+0000: 9152: debug : virNetServerClientRegisterEvent:208 : Registering client event callback 1 >2014-09-22 07:19:34.686+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c405130 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollAddHandle:140 : EVENT_POLL_ADD_HANDLE: watch=13 fd=22 events=1 cb=0x7fc429ec34d0 opaque=0x7fc42c405130 ff=0x7fc429ec3480 >2014-09-22 07:19:34.686+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.686+0000: 9152: debug : virKeepAliveNew:199 : client=0x7fc42c40a460, interval=5, count=5 >2014-09-22 07:19:34.686+0000: 9152: debug : virObjectNew:202 : OBJECT_NEW: obj=0x7fc42c40a9a0 classname=virKeepAlive >2014-09-22 07:19:34.686+0000: 9152: debug : virKeepAliveNew:218 : RPC_KEEPALIVE_NEW: ka=0x7fc42c40a9a0 client=0x7fc42c40a460 >2014-09-22 07:19:34.686+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.686+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.686+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c405130 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollCleanupTimeouts:540 : EVENT_POLL_PURGE_TIMEOUT: timer=1 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.686+0000: 9152: debug : virNetMessageDecodeLength:151 : Got length, now need 28 total (24 more) >2014-09-22 07:19:34.686+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a550 tx=(nil) >2014-09-22 07:19:34.686+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.686+0000: 9152: debug : virNetServerClientDispatchRead:1212 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fc42c40a460 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0 >2014-09-22 07:19:34.686+0000: 9152: debug : virKeepAliveCheckMessage:377 : ka=0x7fc42c40a9a0, client=0x7fc42c40a460, msg=0x7fc42c40a550 >2014-09-22 07:19:34.686+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.686+0000: 9152: debug : virNetServerDispatchNewMessage:220 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a550 >2014-09-22 07:19:34.686+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.686+0000: 9152: debug : virNetMessageNew:46 : msg=0x7fc42c40b8a0 tracked=1 >2014-09-22 07:19:34.686+0000: 9156: debug : virNetServerHandleJob:191 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a550 prog=0x7fc42c3fff40 >2014-09-22 07:19:34.686+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40b8a0 tx=(nil) >2014-09-22 07:19:34.686+0000: 9156: debug : virNetServerProgramDispatch:287 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66 >2014-09-22 07:19:34.686+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2014-09-22 07:19:34.686+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.688+0000: 9156: debug : virFileClose:99 : Closed fd 24 >2014-09-22 07:19:34.688+0000: 9156: debug : virObjectNew:202 : OBJECT_NEW: obj=0x7fc3f8000990 classname=virIdentity >2014-09-22 07:19:34.688+0000: 9156: debug : virIdentitySetAttr:285 : ident=0x7fc3f8000990 attribute=0 value=root >2014-09-22 07:19:34.688+0000: 9156: debug : virIdentitySetAttr:285 : ident=0x7fc3f8000990 attribute=1 value=0 >2014-09-22 07:19:34.688+0000: 9156: debug : virIdentitySetAttr:285 : ident=0x7fc3f8000990 attribute=2 value=root >2014-09-22 07:19:34.688+0000: 9156: debug : virIdentitySetAttr:285 : ident=0x7fc3f8000990 attribute=3 value=0 >2014-09-22 07:19:34.689+0000: 9156: debug : virIdentitySetAttr:285 : ident=0x7fc3f8000990 attribute=4 value=12833 >2014-09-22 07:19:34.689+0000: 9156: debug : virIdentitySetAttr:285 : ident=0x7fc3f8000990 attribute=5 value=263204 >2014-09-22 07:19:34.689+0000: 9156: debug : virIdentitySetAttr:285 : ident=0x7fc3f8000990 attribute=8 value=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 >2014-09-22 07:19:34.689+0000: 9156: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.689+0000: 9156: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.689+0000: 9156: debug : remoteDispatchAuthListHelper:18 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 msg=0x7fc42c40a550 rerr=0x7fc4199e3c80 args=0x7fc3f8000c80 ret=0x7fc3f8000ca0 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.689+0000: 9156: debug : virFileClose:99 : Closed fd 24 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.689+0000: 9156: info : remoteDispatchAuthList:2781 : Bypass polkit auth for privileged client pid:12833,uid:0 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.689+0000: 9156: debug : virNetServerCheckLimits:1078 : Considering re-enabling services: nclients=1 nclients_max=5000 nclients_unauth=0 nclients_unauth_max=20 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.689+0000: 9156: debug : virNetServerCheckLimits:1083 : Re-enabling services >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.689+0000: 9156: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=3 events=1 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2014-09-22 07:19:34.689+0000: 9156: debug : virEventPollInterruptLocked:729 : Interrupting >2014-09-22 07:19:34.689+0000: 9156: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=4 events=1 >2014-09-22 07:19:34.689+0000: 9156: debug : virEventPollInterruptLocked:729 : Interrupting >2014-09-22 07:19:34.689+0000: 9156: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.689+0000: 9156: debug : virNetMessageEncodePayload:376 : Encode length as 36 >2014-09-22 07:19:34.689+0000: 9156: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.689+0000: 9156: debug : virNetServerClientSendMessageLocked:1461 : msg=0x7fc42c40a550 proc=66 len=36 offset=0 >2014-09-22 07:19:34.689+0000: 9156: debug : virNetServerClientSendMessageLocked:1469 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fc42c40a460 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0 >2014-09-22 07:19:34.689+0000: 9156: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40b8a0 tx=0x7fc42c40a550 >2014-09-22 07:19:34.689+0000: 9156: debug : virNetServerClientCalculateHandleMode:192 : mode=3 >2014-09-22 07:19:34.689+0000: 9156: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=3 >2014-09-22 07:19:34.689+0000: 9156: debug : virEventPollInterruptLocked:729 : Interrupting >2014-09-22 07:19:34.689+0000: 9156: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.689+0000: 9156: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.689+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=5 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 2 event(s) >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=2 >2014-09-22 07:19:34.690+0000: 9152: debug : virNetMessageFree:74 : msg=0x7fc42c40a550 nfds=0 cb=(nil) >2014-09-22 07:19:34.690+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40b8a0 tx=(nil) >2014-09-22 07:19:34.690+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 2 event(s) >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.690+0000: 9152: debug : virNetMessageDecodeLength:151 : Got length, now need 32 total (28 more) >2014-09-22 07:19:34.690+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40b8a0 tx=(nil) >2014-09-22 07:19:34.690+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.690+0000: 9152: debug : virNetServerClientDispatchRead:1212 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fc42c40a460 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1 >2014-09-22 07:19:34.690+0000: 9152: debug : virKeepAliveCheckMessage:377 : ka=0x7fc42c40a9a0, client=0x7fc42c40a460, msg=0x7fc42c40b8a0 >2014-09-22 07:19:34.690+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.690+0000: 9152: debug : virNetServerDispatchNewMessage:220 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40b8a0 >2014-09-22 07:19:34.690+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.690+0000: 9152: debug : virNetMessageNew:46 : msg=0x7fc42c40a780 tracked=1 >2014-09-22 07:19:34.690+0000: 9157: debug : virNetServerHandleJob:191 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40b8a0 prog=0x7fc42c3fff40 >2014-09-22 07:19:34.690+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a780 tx=(nil) >2014-09-22 07:19:34.690+0000: 9157: debug : virNetServerProgramDispatch:287 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=60 >2014-09-22 07:19:34.690+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2014-09-22 07:19:34.690+0000: 9157: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.690+0000: 9157: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.690+0000: 9157: debug : remoteDispatchConnectSupportsFeatureHelper:2389 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 msg=0x7fc42c40b8a0 rerr=0x7fc4191e2c80 args=0x7fc3fc000900 ret=0x7fc3fc0009a0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2014-09-22 07:19:34.690+0000: 9157: debug : virKeepAliveStart:279 : RPC_KEEPALIVE_START: ka=0x7fc42c40a9a0 client=0x7fc42c40a460 interval=0 count=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2014-09-22 07:19:34.690+0000: 9157: debug : virEventPollInterruptLocked:729 : Interrupting >2014-09-22 07:19:34.690+0000: 9157: debug : virEventPollAddTimeout:254 : EVENT_POLL_ADD_TIMEOUT: timer=4 frequency=5000 cb=0x7fc429ebf510 opaque=0x7fc42c40a9a0 ff=0x7fc429dae6e0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.690+0000: 9157: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a9a0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.690+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.690+0000: 9157: debug : virNetMessageEncodePayload:376 : Encode length as 32 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.690+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2014-09-22 07:19:34.690+0000: 9157: debug : virNetServerClientSendMessageLocked:1461 : msg=0x7fc42c40b8a0 proc=60 len=32 offset=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.690+0000: 9157: debug : virNetServerClientSendMessageLocked:1469 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fc42c40a460 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.690+0000: 9157: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a780 tx=0x7fc42c40b8a0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.690+0000: 9157: debug : virNetServerClientCalculateHandleMode:192 : mode=3 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.690+0000: 9157: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=3 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.690+0000: 9157: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140480503949440 >2014-09-22 07:19:34.690+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.690+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=5 d=0 >2014-09-22 07:19:34.690+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379690 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379690 now=1411370374691 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379690 due in 4999 ms >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=2 >2014-09-22 07:19:34.691+0000: 9152: debug : virNetMessageFree:74 : msg=0x7fc42c40b8a0 nfds=0 cb=(nil) >2014-09-22 07:19:34.691+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a780 tx=(nil) >2014-09-22 07:19:34.691+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379690 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379690 now=1411370374691 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379690 due in 4999 ms >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.691+0000: 9152: debug : virNetMessageDecodeLength:151 : Got length, now need 40 total (36 more) >2014-09-22 07:19:34.691+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a780 tx=(nil) >2014-09-22 07:19:34.691+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.691+0000: 9152: debug : virNetServerClientDispatchRead:1212 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fc42c40a460 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2 >2014-09-22 07:19:34.691+0000: 9152: debug : virKeepAliveCheckMessage:377 : ka=0x7fc42c40a9a0, client=0x7fc42c40a460, msg=0x7fc42c40a780 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1411370379691 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.691+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.691+0000: 9152: debug : virNetServerDispatchNewMessage:220 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a780 >2014-09-22 07:19:34.691+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.691+0000: 9152: debug : virNetMessageNew:46 : msg=0x7fc42c40a0f0 tracked=1 >2014-09-22 07:19:34.691+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a0f0 tx=(nil) >2014-09-22 07:19:34.691+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.691+0000: 9162: debug : virNetServerHandleJob:191 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a780 prog=0x7fc42c3fff40 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.691+0000: 9162: debug : virNetServerProgramDispatch:287 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=1 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.691+0000: 9162: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.691+0000: 9162: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.691+0000: 9162: debug : remoteDispatchConnectOpenHelper:2367 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 msg=0x7fc42c40a780 rerr=0x7fc4169ddc80 args=0x7fc3f4000900 ret=0x7fc3f40009a0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.691+0000: 9162: debug : remoteDispatchConnectOpen:1189 : priv=0x7fc42c40a3e0 conn=(nil) >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.691+0000: 9162: debug : virConnectOpen:1315 : name= >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9162: debug : virObjectNew:202 : OBJECT_NEW: obj=0x7fc3f4000c50 classname=virConnect >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9162: debug : virObjectNew:202 : OBJECT_NEW: obj=0x7fc3f4000930 classname=virConnectCloseCallbackData >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379691 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379691 now=1411370374691 >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379691 due in 5000 ms >2014-09-22 07:19:34.691+0000: 9162: debug : virConnectGetConfigFile:920 : Loading config file '/etc/libvirt/libvirt.conf' >2014-09-22 07:19:34.691+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=5000 >2014-09-22 07:19:34.691+0000: 9162: debug : virConfReadFile:764 : filename=/etc/libvirt/libvirt.conf >2014-09-22 07:19:34.691+0000: 9162: debug : virFileClose:99 : Closed fd 24 >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1103 : no name, allowing driver auto-select >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1145 : trying driver 0 (Test) ... >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1152 : driver 0 Test returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1145 : trying driver 1 (ESX) ... >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1152 : driver 1 ESX returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1145 : trying driver 2 (remote) ... >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1152 : driver 2 remote returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1145 : trying driver 3 (QEMU) ... >2014-09-22 07:19:34.691+0000: 9162: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc40c0f0ba0 >2014-09-22 07:19:34.691+0000: 9162: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c4018a0 >2014-09-22 07:19:34.691+0000: 9162: debug : virAccessManagerCheckConnect:218 : manager=0x7fc42c4018a0(name=stack) driver=QEMU perm=0 >2014-09-22 07:19:34.691+0000: 9162: debug : virAccessManagerCheckConnect:218 : manager=0x7fc42c3f56f0(name=none) driver=QEMU perm=0 >2014-09-22 07:19:34.691+0000: 9162: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c4018a0 >2014-09-22 07:19:34.691+0000: 9162: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc40c0f0ba0 >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1152 : driver 3 QEMU returned SUCCESS >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1176 : network driver 0 Test returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1176 : network driver 1 ESX returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1176 : network driver 2 remote returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1176 : network driver 3 Network returned SUCCESS >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1192 : interface driver 0 Test returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1192 : interface driver 1 ESX returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1192 : interface driver 2 remote returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc40c003620 >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1192 : interface driver 3 netcf returned SUCCESS >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1209 : storage driver 0 Test returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1209 : storage driver 1 ESX returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1209 : storage driver 2 remote returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1209 : storage driver 3 storage returned SUCCESS >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1226 : node driver 0 Test returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1226 : node driver 1 ESX returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1226 : node driver 2 remote returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1226 : node driver 3 udevNodeDeviceDriver returned SUCCESS >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1243 : secret driver 0 Test returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1243 : secret driver 1 ESX returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1243 : secret driver 2 remote returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1243 : secret driver 3 secret returned SUCCESS >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1260 : nwfilter driver 0 Test returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1260 : nwfilter driver 1 ESX returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1260 : nwfilter driver 2 remote returned DECLINED >2014-09-22 07:19:34.691+0000: 9162: debug : do_open:1260 : nwfilter driver 3 nwfilter returned SUCCESS >2014-09-22 07:19:34.691+0000: 9162: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.691+0000: 9162: debug : virNetMessageEncodePayload:376 : Encode length as 28 >2014-09-22 07:19:34.691+0000: 9162: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.691+0000: 9162: debug : virNetServerClientSendMessageLocked:1461 : msg=0x7fc42c40a780 proc=1 len=28 offset=0 >2014-09-22 07:19:34.691+0000: 9162: debug : virNetServerClientSendMessageLocked:1469 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fc42c40a460 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2 >2014-09-22 07:19:34.692+0000: 9162: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a0f0 tx=0x7fc42c40a780 >2014-09-22 07:19:34.692+0000: 9162: debug : virNetServerClientCalculateHandleMode:192 : mode=3 >2014-09-22 07:19:34.692+0000: 9162: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=3 >2014-09-22 07:19:34.692+0000: 9162: debug : virEventPollInterruptLocked:729 : Interrupting >2014-09-22 07:19:34.692+0000: 9162: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.692+0000: 9162: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=5 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379691 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379691 now=1411370374692 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379691 due in 4999 ms >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=2 >2014-09-22 07:19:34.692+0000: 9152: debug : virNetMessageFree:74 : msg=0x7fc42c40a780 nfds=0 cb=(nil) >2014-09-22 07:19:34.692+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a0f0 tx=(nil) >2014-09-22 07:19:34.692+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379691 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379691 now=1411370374692 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379691 due in 4999 ms >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.692+0000: 9152: debug : virNetMessageDecodeLength:151 : Got length, now need 28 total (24 more) >2014-09-22 07:19:34.692+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a0f0 tx=(nil) >2014-09-22 07:19:34.692+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.692+0000: 9152: debug : virNetServerClientDispatchRead:1212 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fc42c40a460 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3 >2014-09-22 07:19:34.692+0000: 9152: debug : virKeepAliveCheckMessage:377 : ka=0x7fc42c40a9a0, client=0x7fc42c40a460, msg=0x7fc42c40a0f0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1411370379692 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.692+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.692+0000: 9152: debug : virNetServerDispatchNewMessage:220 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a0f0 >2014-09-22 07:19:34.692+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.692+0000: 9152: debug : virNetMessageNew:46 : msg=0x7fc42c40a5c0 tracked=1 >2014-09-22 07:19:34.692+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a5c0 tx=(nil) >2014-09-22 07:19:34.692+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.692+0000: 9153: debug : virNetServerHandleJob:191 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a0f0 prog=0x7fc42c3fff40 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.692+0000: 9153: debug : virNetServerProgramDispatch:287 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.692+0000: 9153: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.692+0000: 9153: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.692+0000: 9153: debug : remoteDispatchConnectGetURIHelper:926 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 msg=0x7fc42c40a0f0 rerr=0x7fc41b1e6c80 args=0x7fc40c277330 ret=0x7fc40c274f70 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.692+0000: 9153: debug : virConnectGetURI:1668 : conn=0x7fc3f4000c50 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379692 >2014-09-22 07:19:34.692+0000: 9153: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379692 now=1411370374692 >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379692 due in 5000 ms >2014-09-22 07:19:34.692+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=5000 >2014-09-22 07:19:34.692+0000: 9153: debug : virNetMessageEncodePayload:376 : Encode length as 48 >2014-09-22 07:19:34.693+0000: 9153: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.693+0000: 9153: debug : virNetServerClientSendMessageLocked:1461 : msg=0x7fc42c40a0f0 proc=110 len=48 offset=0 >2014-09-22 07:19:34.693+0000: 9153: debug : virNetServerClientSendMessageLocked:1469 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fc42c40a460 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3 >2014-09-22 07:19:34.693+0000: 9153: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a5c0 tx=0x7fc42c40a0f0 >2014-09-22 07:19:34.693+0000: 9153: debug : virNetServerClientCalculateHandleMode:192 : mode=3 >2014-09-22 07:19:34.693+0000: 9153: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=3 >2014-09-22 07:19:34.693+0000: 9153: debug : virEventPollInterruptLocked:729 : Interrupting >2014-09-22 07:19:34.693+0000: 9153: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2014-09-22 07:19:34.693+0000: 9153: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=5 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379692 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379692 now=1411370374693 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379692 due in 4999 ms >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=2 >2014-09-22 07:19:34.693+0000: 9152: debug : virNetMessageFree:74 : msg=0x7fc42c40a0f0 nfds=0 cb=(nil) >2014-09-22 07:19:34.693+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a5c0 tx=(nil) >2014-09-22 07:19:34.693+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379692 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379692 now=1411370374693 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379692 due in 4999 ms >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.693+0000: 9152: debug : virNetMessageDecodeLength:151 : Got length, now need 32 total (28 more) >2014-09-22 07:19:34.693+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a5c0 tx=(nil) >2014-09-22 07:19:34.693+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.693+0000: 9152: debug : virNetServerClientDispatchRead:1212 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fc42c40a460 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=4 >2014-09-22 07:19:34.693+0000: 9152: debug : virKeepAliveCheckMessage:377 : ka=0x7fc42c40a9a0, client=0x7fc42c40a460, msg=0x7fc42c40a5c0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1411370379693 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.693+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.693+0000: 9152: debug : virNetServerDispatchNewMessage:220 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a5c0 >2014-09-22 07:19:34.693+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.693+0000: 9152: debug : virNetMessageNew:46 : msg=0x7fc42c40a860 tracked=1 >2014-09-22 07:19:34.693+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a860 tx=(nil) >2014-09-22 07:19:34.693+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.693+0000: 9155: debug : virNetServerHandleJob:191 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a5c0 prog=0x7fc42c3fff40 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.693+0000: 9155: debug : virNetServerProgramDispatch:287 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=60 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.693+0000: 9155: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.693+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379693 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379693 now=1411370374694 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379693 due in 4999 ms >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2014-09-22 07:19:34.693+0000: 9155: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.694+0000: 9155: debug : remoteDispatchConnectSupportsFeatureHelper:2389 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 msg=0x7fc42c40a5c0 rerr=0x7fc41a1e4c80 args=0x7fc3f0000900 ret=0x7fc3f00009a0 >2014-09-22 07:19:34.694+0000: 9155: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.694+0000: 9155: debug : virNetMessageEncodePayload:376 : Encode length as 32 >2014-09-22 07:19:34.694+0000: 9155: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.694+0000: 9155: debug : virNetServerClientSendMessageLocked:1461 : msg=0x7fc42c40a5c0 proc=60 len=32 offset=0 >2014-09-22 07:19:34.694+0000: 9155: debug : virNetServerClientSendMessageLocked:1469 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fc42c40a460 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=4 >2014-09-22 07:19:34.694+0000: 9155: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a860 tx=0x7fc42c40a5c0 >2014-09-22 07:19:34.694+0000: 9155: debug : virNetServerClientCalculateHandleMode:192 : mode=3 >2014-09-22 07:19:34.694+0000: 9155: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=3 >2014-09-22 07:19:34.694+0000: 9155: debug : virEventPollInterruptLocked:729 : Interrupting >2014-09-22 07:19:34.694+0000: 9155: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.694+0000: 9155: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.694+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.694+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=5 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379693 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379693 now=1411370374695 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379693 due in 4998 ms >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4998 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=2 >2014-09-22 07:19:34.695+0000: 9152: debug : virNetMessageFree:74 : msg=0x7fc42c40a5c0 nfds=0 cb=(nil) >2014-09-22 07:19:34.695+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a860 tx=(nil) >2014-09-22 07:19:34.695+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379693 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379693 now=1411370374695 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379693 due in 4998 ms >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4998 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.695+0000: 9152: debug : virNetMessageDecodeLength:151 : Got length, now need 36 total (32 more) >2014-09-22 07:19:34.695+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a860 tx=(nil) >2014-09-22 07:19:34.695+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.695+0000: 9152: debug : virNetServerClientDispatchRead:1212 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fc42c40a460 len=36 prog=536903814 vers=1 proc=23 type=0 status=0 serial=5 >2014-09-22 07:19:34.695+0000: 9152: debug : virKeepAliveCheckMessage:377 : ka=0x7fc42c40a9a0, client=0x7fc42c40a460, msg=0x7fc42c40a860 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1411370379695 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.695+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.695+0000: 9152: debug : virNetServerDispatchNewMessage:220 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a860 >2014-09-22 07:19:34.695+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.695+0000: 9152: debug : virNetMessageNew:46 : msg=0x7fc42c40a080 tracked=1 >2014-09-22 07:19:34.695+0000: 9156: debug : virNetServerHandleJob:191 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a860 prog=0x7fc42c3fff40 >2014-09-22 07:19:34.695+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a080 tx=(nil) >2014-09-22 07:19:34.695+0000: 9156: debug : virNetServerProgramDispatch:287 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=23 >2014-09-22 07:19:34.695+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.695+0000: 9156: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.695+0000: 9156: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.695+0000: 9156: debug : remoteDispatchDomainLookupByNameHelper:5318 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 msg=0x7fc42c40a860 rerr=0x7fc4199e3c80 args=0x7fc3f8000c20 ret=0x7fc3f8000930 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.695+0000: 9156: debug : virDomainLookupByName:2138 : conn=0x7fc3f4000c50, name=win7 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9156: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c4018a0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9156: debug : virAccessManagerCheckDomain:234 : manager=0x7fc42c4018a0(name=stack) driver=QEMU domain=0x7fc40c1c2b30 perm=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9156: debug : virAccessManagerCheckDomain:234 : manager=0x7fc42c3f56f0(name=none) driver=QEMU domain=0x7fc40c1c2b30 perm=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9156: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c4018a0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9156: debug : virObjectNew:202 : OBJECT_NEW: obj=0x7fc3f8000be0 classname=virDomain >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9156: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f4000c50 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.695+0000: 9156: debug : virDomainFree:2287 : dom=0x7fc3f8000be0, (VM: name=win7, uuid=7fe6b3c2-9b2e-4dbd-852f-4c2250101789) >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9156: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000be0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9156: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7fc3f8000be0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.695+0000: 9156: debug : virDomainDispose:252 : release domain 0x7fc3f8000be0 win7 7fe6b3c2-9b2e-4dbd-852f-4c2250101789 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.695+0000: 9156: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f4000c50 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.695+0000: 9156: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.695+0000: 9156: debug : virNetMessageEncodePayload:376 : Encode length as 56 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379695 >2014-09-22 07:19:34.695+0000: 9156: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379695 now=1411370374695 >2014-09-22 07:19:34.695+0000: 9156: debug : virNetServerClientSendMessageLocked:1461 : msg=0x7fc42c40a860 proc=23 len=56 offset=0 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379695 due in 5000 ms >2014-09-22 07:19:34.695+0000: 9156: debug : virNetServerClientSendMessageLocked:1469 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fc42c40a460 len=56 prog=536903814 vers=1 proc=23 type=1 status=0 serial=5 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=5000 >2014-09-22 07:19:34.695+0000: 9156: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a080 tx=0x7fc42c40a860 >2014-09-22 07:19:34.695+0000: 9156: debug : virNetServerClientCalculateHandleMode:192 : mode=3 >2014-09-22 07:19:34.695+0000: 9156: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=3 >2014-09-22 07:19:34.695+0000: 9156: debug : virEventPollInterruptLocked:729 : Interrupting >2014-09-22 07:19:34.695+0000: 9156: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.695+0000: 9156: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.695+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=5 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379695 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379695 now=1411370374696 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379695 due in 4999 ms >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=2 >2014-09-22 07:19:34.696+0000: 9152: debug : virNetMessageFree:74 : msg=0x7fc42c40a860 nfds=0 cb=(nil) >2014-09-22 07:19:34.696+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a080 tx=(nil) >2014-09-22 07:19:34.696+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379695 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379695 now=1411370374696 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379695 due in 4999 ms >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.696+0000: 9152: debug : virNetMessageDecodeLength:151 : Got length, now need 84 total (80 more) >2014-09-22 07:19:34.696+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a080 tx=(nil) >2014-09-22 07:19:34.696+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.696+0000: 9152: debug : virNetServerClientDispatchRead:1212 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fc42c40a460 len=84 prog=536903814 vers=1 proc=226 type=0 status=0 serial=6 >2014-09-22 07:19:34.696+0000: 9152: debug : virKeepAliveCheckMessage:377 : ka=0x7fc42c40a9a0, client=0x7fc42c40a460, msg=0x7fc42c40a080 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1411370379696 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.696+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.696+0000: 9152: debug : virNetServerDispatchNewMessage:220 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a080 >2014-09-22 07:19:34.696+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.696+0000: 9152: debug : virNetMessageNew:46 : msg=0x7fc42c40a630 tracked=1 >2014-09-22 07:19:34.696+0000: 9157: debug : virNetServerHandleJob:191 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a080 prog=0x7fc42c3fff40 >2014-09-22 07:19:34.696+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a630 tx=(nil) >2014-09-22 07:19:34.696+0000: 9157: debug : virNetServerProgramDispatch:287 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=226 >2014-09-22 07:19:34.696+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.696+0000: 9157: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.696+0000: 9157: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.696+0000: 9157: debug : remoteDispatchDomainSendKeyHelper:7460 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 msg=0x7fc42c40a080 rerr=0x7fc4191e2c80 args=0x7fc3fc0009a0 ret=0x7fc3fc000900 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.696+0000: 9157: debug : virObjectNew:202 : OBJECT_NEW: obj=0x7fc3fc0008c0 classname=virDomain >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.696+0000: 9157: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f4000c50 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9157: debug : virDomainSendKey:9298 : dom=0x7fc3fc0008c0, (VM: name=win7, uuid=7fe6b3c2-9b2e-4dbd-852f-4c2250101789), codeset=0, holdtime=0, nkeycodes=3, flags=0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9157: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c4018a0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9157: debug : virAccessManagerCheckDomain:234 : manager=0x7fc42c4018a0(name=stack) driver=QEMU domain=0x7fc40c1c2b30 perm=17 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9157: debug : virAccessManagerCheckDomain:234 : manager=0x7fc42c3f56f0(name=none) driver=QEMU domain=0x7fc40c1c2b30 perm=17 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c4018a0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9157: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc40c0f0ba0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.696+0000: 9157: debug : qemuDomainObjBeginJobInternal:1288 : Starting job: modify (async=none vm=0x7fc40c1c0f90 name=win7) >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9157: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc40c1c0f90 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9157: debug : qemuDomainObjBeginJobInternal:1329 : Started job: modify (async=none vm=0x7fc40c1c0f90 name=win7) >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.696+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc40c0f0ba0 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.696+0000: 9157: debug : qemuDomainObjEnterMonitorInternal:1530 : Entering monitor (mon=0x7fc404000c30 vm=0x7fc40c1c0f90 name=win7) >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.696+0000: 9157: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc404000c30 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.696+0000: 9157: debug : qemuMonitorSendKey:3339 : mon=0x7fc404000c30, holdtime=0, nkeycodes=3 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379696 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379696 now=1411370374696 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379696 due in 5000 ms >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToString:1270 : object=0x7fc3fc000f30 >2014-09-22 07:19:34.696+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=5000 >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc000f30 type=0 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc000fe0 type=2 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc001060 type=0 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc000980 type=1 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc000c20 type=0 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc000c40 type=2 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc000ce0 type=3 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc000d20 type=0 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc000d40 type=2 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc000e60 type=3 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc000e20 type=0 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc000ed0 type=2 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.696+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc000f70 type=3 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.697+0000: 9157: debug : virJSONValueToStringOne:1199 : object=0x7fc3fc001110 type=2 gen=0x7fc3fc0011d0 >2014-09-22 07:19:34.697+0000: 9157: debug : virJSONValueToString:1303 : result={"execute":"send-key","arguments":{"keys":[{"type":"number","data":157},{"type":"number","data":70},{"type":"number","data":70}]},"id":"libvirt-10"} >2014-09-22 07:19:34.697+0000: 9157: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"send-key","arguments":{"keys":[{"type":"number","data":157},{"type":"number","data":70},{"type":"number","data":70}]},"id":"libvirt-10"}' for write with FD -1 >2014-09-22 07:19:34.697+0000: 9157: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=15 >2014-09-22 07:19:34.697+0000: 9157: debug : virEventPollInterruptLocked:729 : Interrupting >2014-09-22 07:19:34.697+0000: 9157: debug : qemuMonitorSend:976 : QEMU_MONITOR_SEND_MSG: mon=0x7fc404000c30 msg={"execute":"send-key","arguments":{"keys":[{"type":"number","data":157},{"type":"number","data":70},{"type":"number","data":70}]},"id":"libvirt-10"} > fd=-1 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=29 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379696 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379696 now=1411370374697 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379696 due in 4999 ms >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=2 >2014-09-22 07:19:34.697+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc404000c30 >2014-09-22 07:19:34.697+0000: 9152: debug : qemuMonitorIOWrite:507 : QEMU_MONITOR_IO_WRITE: mon=0x7fc404000c30 buf={"execute":"send-key","arguments":{"keys":[{"type":"number","data":157},{"type":"number","data":70},{"type":"number","data":70}]},"id":"libvirt-10"} > len=150 ret=150 errno=2 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=13 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.697+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc404000c30 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379696 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379696 now=1411370374697 >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379696 due in 4999 ms >2014-09-22 07:19:34.697+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=1 >2014-09-22 07:19:34.701+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc404000c30 >2014-09-22 07:19:34.701+0000: 9152: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7fc404000c30 buf={"return": {}, "id": "libvirt-10"} > len=36 >2014-09-22 07:19:34.701+0000: 9152: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": {}, "id": "libvirt-10"}] >2014-09-22 07:19:34.701+0000: 9152: debug : virJSONValueFromString:1136 : string={"return": {}, "id": "libvirt-10"} >2014-09-22 07:19:34.701+0000: 9152: debug : virJSONParserHandleStartMap:1013 : parser=0x7fff42ee1920 >2014-09-22 07:19:34.701+0000: 9152: debug : virJSONParserHandleMapKey:993 : parser=0x7fff42ee1920 key=0x7fc42c405302 >2014-09-22 07:19:34.701+0000: 9152: debug : virJSONParserHandleStartMap:1013 : parser=0x7fff42ee1920 >2014-09-22 07:19:34.701+0000: 9152: debug : virJSONParserHandleEndMap:1042 : parser=0x7fff42ee1920 >2014-09-22 07:19:34.701+0000: 9152: debug : virJSONParserHandleMapKey:993 : parser=0x7fff42ee1920 key=0x7fc42c405310 >2014-09-22 07:19:34.701+0000: 9152: debug : virJSONParserHandleString:971 : parser=0x7fff42ee1920 str=0x7fc42c405316 >2014-09-22 07:19:34.701+0000: 9152: debug : virJSONParserHandleEndMap:1042 : parser=0x7fff42ee1920 >2014-09-22 07:19:34.701+0000: 9152: debug : virJSONValueFromString:1187 : result=0x7fc42c3846a0 >2014-09-22 07:19:34.701+0000: 9152: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7fc404000c30 reply={"return": {}, "id": "libvirt-10"} >2014-09-22 07:19:34.701+0000: 9152: debug : qemuMonitorJSONIOProcess:248 : Total used 36 bytes out of 36 available in buffer >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=13 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.701+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc404000c30 >2014-09-22 07:19:34.701+0000: 9157: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=13 >2014-09-22 07:19:34.701+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.703+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.703+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.703+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.703+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.703+0000: 9157: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140480503949440 >2014-09-22 07:19:34.703+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.703+0000: 9157: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7fc42c3846a0 >2014-09-22 07:19:34.703+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.703+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.703+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc404000c30 >2014-09-22 07:19:34.703+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.703+0000: 9157: debug : qemuDomainObjExitMonitorInternal:1553 : Exited monitor (mon=0x7fc404000c30 vm=0x7fc40c1c0f90 name=win7) >2014-09-22 07:19:34.703+0000: 9157: debug : qemuDomainObjEndJob:1457 : Stopping job: modify (async=none vm=0x7fc40c1c0f90 name=win7) >2014-09-22 07:19:34.703+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc40c1c0f90 >2014-09-22 07:19:34.703+0000: 9157: debug : virDomainFree:2287 : dom=0x7fc3fc0008c0, (VM: name=win7, uuid=7fe6b3c2-9b2e-4dbd-852f-4c2250101789) >2014-09-22 07:19:34.703+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3fc0008c0 >2014-09-22 07:19:34.703+0000: 9157: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7fc3fc0008c0 >2014-09-22 07:19:34.703+0000: 9157: debug : virDomainDispose:252 : release domain 0x7fc3fc0008c0 win7 7fe6b3c2-9b2e-4dbd-852f-4c2250101789 >2014-09-22 07:19:34.703+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f4000c50 >2014-09-22 07:19:34.703+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.703+0000: 9157: debug : virNetMessageEncodePayload:376 : Encode length as 28 >2014-09-22 07:19:34.703+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.703+0000: 9157: debug : virNetServerClientSendMessageLocked:1461 : msg=0x7fc42c40a080 proc=226 len=28 offset=0 >2014-09-22 07:19:34.703+0000: 9157: debug : virNetServerClientSendMessageLocked:1469 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fc42c40a460 len=28 prog=536903814 vers=1 proc=226 type=1 status=0 serial=6 >2014-09-22 07:19:34.703+0000: 9157: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a630 tx=0x7fc42c40a080 >2014-09-22 07:19:34.703+0000: 9157: debug : virNetServerClientCalculateHandleMode:192 : mode=3 >2014-09-22 07:19:34.703+0000: 9157: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=3 >2014-09-22 07:19:34.703+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379696 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379696 now=1411370374704 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379696 due in 4992 ms >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4992 >2014-09-22 07:19:34.704+0000: 9157: debug : virEventPollInterruptLocked:729 : Interrupting >2014-09-22 07:19:34.704+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.704+0000: 9157: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=5 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379696 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379696 now=1411370374704 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379696 due in 4992 ms >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4992 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=2 >2014-09-22 07:19:34.704+0000: 9152: debug : virNetMessageFree:74 : msg=0x7fc42c40a080 nfds=0 cb=(nil) >2014-09-22 07:19:34.704+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a630 tx=(nil) >2014-09-22 07:19:34.704+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379696 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379696 now=1411370374704 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379696 due in 4992 ms >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=4992 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.704+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.705+0000: 9152: debug : virNetMessageDecodeLength:151 : Got length, now need 28 total (24 more) >2014-09-22 07:19:34.705+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a630 tx=(nil) >2014-09-22 07:19:34.705+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.705+0000: 9152: debug : virNetServerClientDispatchRead:1212 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fc42c40a460 len=28 prog=536903814 vers=1 proc=2 type=0 status=0 serial=7 >2014-09-22 07:19:34.705+0000: 9152: debug : virKeepAliveCheckMessage:377 : ka=0x7fc42c40a9a0, client=0x7fc42c40a460, msg=0x7fc42c40a630 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1411370379705 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.705+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.705+0000: 9152: debug : virNetServerDispatchNewMessage:220 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a630 >2014-09-22 07:19:34.705+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.705+0000: 9152: debug : virNetMessageNew:46 : msg=0x7fc42c40a7f0 tracked=1 >2014-09-22 07:19:34.705+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a7f0 tx=(nil) >2014-09-22 07:19:34.705+0000: 9161: debug : virNetServerHandleJob:191 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 message=0x7fc42c40a630 prog=0x7fc42c3fff40 >2014-09-22 07:19:34.705+0000: 9161: debug : virNetServerProgramDispatch:287 : prog=536903814 ver=1 type=0 status=0 serial=7 proc=2 >2014-09-22 07:19:34.705+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=1 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=1 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.705+0000: 9161: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.705+0000: 9161: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.705+0000: 9161: debug : remoteDispatchConnectCloseHelper:174 : server=0x7fc42c3f5a80 client=0x7fc42c40a460 msg=0x7fc42c40a630 rerr=0x7fc4171dec80 args=0x7fc3e8000900 ret=0x7fc3e80009a0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.705+0000: 9161: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.705+0000: 9161: debug : virNetMessageEncodePayload:376 : Encode length as 28 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9161: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9161: debug : virNetServerClientSendMessageLocked:1461 : msg=0x7fc42c40a630 proc=2 len=28 offset=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9161: debug : virNetServerClientSendMessageLocked:1469 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fc42c40a460 len=28 prog=536903814 vers=1 proc=2 type=1 status=0 serial=7 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9161: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a7f0 tx=0x7fc42c40a630 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9161: debug : virNetServerClientCalculateHandleMode:192 : mode=2 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9161: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=2 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379705 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379705 now=1411370374705 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379705 due in 5000 ms >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=5000 >2014-09-22 07:19:34.705+0000: 9161: debug : virEventPollInterruptLocked:729 : Interrupting >2014-09-22 07:19:34.705+0000: 9161: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c3fff40 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.705+0000: 9161: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=11 w=13, f=22 e=4 d=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1411370379705 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1411370379705 now=1411370374705 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCalculateTimeout:370 : Timeout at 1411370379705 due in 5000 ms >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=11 timeout=5000 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:480 : Dispatch 11 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=2 w=3 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=3 w=4 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=4 w=5 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=5 w=6 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=7 w=8 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=8 w=9 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=9 w=10 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=10 w=11 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:494 : i=11 w=13 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=2 >2014-09-22 07:19:34.705+0000: 9152: debug : virNetMessageFree:74 : msg=0x7fc42c40a630 nfds=0 cb=(nil) >2014-09-22 07:19:34.705+0000: 9152: debug : virNetServerClientCalculateHandleMode:157 : tls=(nil) hs=-1, rx=0x7fc42c40a7f0 tx=(nil) >2014-09-22 07:19:34.705+0000: 9152: debug : virNetServerClientCalculateHandleMode:192 : mode=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=0 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.705+0000: 9152: debug : virNetServerClientClose:951 : client=0x7fc42c40a460 >2014-09-22 07:19:34.705+0000: 9152: debug : virKeepAliveStop:310 : RPC_KEEPALIVE_STOP: ka=0x7fc42c40a9a0 client=0x7fc42c40a460 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollRemoveTimeout:307 : EVENT_POLL_REMOVE_TIMEOUT: timer=4 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140480503949440 >2014-09-22 07:19:34.705+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.705+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a9a0 >2014-09-22 07:19:34.705+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.705+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.705+0000: 9152: debug : daemonRemoveAllClientStreams:488 : stream=(nil) >2014-09-22 07:19:34.705+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollRemoveHandle:186 : EVENT_POLL_REMOVE_HANDLE: watch=13 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollRemoveHandle:199 : mark delete 11 22 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140480503949440 >2014-09-22 07:19:34.705+0000: 9152: debug : virNetMessageFree:74 : msg=0x7fc42c40a7f0 nfds=0 cb=(nil) >2014-09-22 07:19:34.705+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c405130 >2014-09-22 07:19:34.705+0000: 9152: debug : virNetServerCheckLimits:1078 : Considering re-enabling services: nclients=0 nclients_max=5000 nclients_unauth=0 nclients_unauth_max=20 >2014-09-22 07:19:34.705+0000: 9152: debug : virNetServerCheckLimits:1083 : Re-enabling services >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=3 events=1 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140480503949440 >2014-09-22 07:19:34.705+0000: 9152: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=4 events=1 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140480503949440 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventRunDefaultImpl:305 : running default event implementation >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollCleanupTimeouts:540 : EVENT_POLL_PURGE_TIMEOUT: timer=4 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a9a0 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7fc42c40a9a0 >2014-09-22 07:19:34.706+0000: 9152: debug : virKeepAliveDispose:230 : RPC_KEEPALIVE_DISPOSE: ka=0x7fc42c40a9a0 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollCleanupHandles:576 : Cleanup 12 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollCleanupHandles:589 : EVENT_POLL_PURGE_HANDLE: watch=13 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c40a460 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7fc42c40a460 >2014-09-22 07:19:34.706+0000: 9152: debug : virNetServerClientDispose:915 : RPC_SERVER_CLIENT_DISPOSE: client=0x7fc42c40a460 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f8000990 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7fc3f8000990 >2014-09-22 07:19:34.706+0000: 9152: debug : virFileClose:99 : Closed fd 24 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectNew:202 : OBJECT_NEW: obj=0x7fc42c4050c0 classname=virIdentity >2014-09-22 07:19:34.706+0000: 9152: debug : virIdentitySetAttr:285 : ident=0x7fc42c4050c0 attribute=0 value=root >2014-09-22 07:19:34.706+0000: 9152: debug : virIdentitySetAttr:285 : ident=0x7fc42c4050c0 attribute=1 value=0 >2014-09-22 07:19:34.706+0000: 9152: debug : virIdentitySetAttr:285 : ident=0x7fc42c4050c0 attribute=2 value=root >2014-09-22 07:19:34.706+0000: 9152: debug : virIdentitySetAttr:285 : ident=0x7fc42c4050c0 attribute=3 value=0 >2014-09-22 07:19:34.706+0000: 9152: debug : virIdentitySetAttr:285 : ident=0x7fc42c4050c0 attribute=8 value=system_u:system_r:virtd_t:s0-s0:c0.c1023 >2014-09-22 07:19:34.706+0000: 9152: debug : virIdentitySetAttr:285 : ident=0x7fc42c4050c0 attribute=4 value=9152 >2014-09-22 07:19:34.706+0000: 9152: debug : virIdentitySetAttr:285 : ident=0x7fc42c4050c0 attribute=5 value=246174 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectRef:296 : OBJECT_REF: obj=0x7fc42c4050c0 >2014-09-22 07:19:34.706+0000: 9152: debug : virConnectClose:1435 : conn=0x7fc3f4000c50 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f4000c50 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7fc3f4000c50 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc40c003620 >2014-09-22 07:19:34.706+0000: 9152: debug : virCloseCallbacksRun:316 : conn=0x7fc3f4000c50 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc3f4000930 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7fc3f4000930 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c4050c0 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c4050c0 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7fc42c4050c0 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollRemoveTimeout:307 : EVENT_POLL_REMOVE_TIMEOUT: timer=3 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollInterruptLocked:725 : Skip interrupt, 1 140480503949440 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x7fc42c405130 >2014-09-22 07:19:34.706+0000: 9152: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7fc42c405130 >2014-09-22 07:19:34.706+0000: 9152: debug : virNetSocketDispose:1140 : RPC_SOCKET_DISPOSE: sock=0x7fc42c405130 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollRemoveHandle:186 : EVENT_POLL_REMOVE_HANDLE: watch=13 >2014-09-22 07:19:34.706+0000: 9152: debug : virFileClose:99 : Closed fd 22 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=6 e=1 d=0 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=8 e=1 d=0 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=2 w=3, f=11 e=1 d=0 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=3 w=4, f=12 e=1 d=0 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=4 w=5, f=14 e=1 d=0 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=5 w=6, f=13 e=1 d=0 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=6 w=7, f=15 e=0 d=0 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=7 w=8, f=15 e=1 d=0 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=8 w=9, f=19 e=1 d=0 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=9 w=10, f=21 e=25 d=0 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollMakePollFDs:402 : Prepare n=10 w=11, f=23 e=25 d=0 >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2014-09-22 07:19:34.706+0000: 9152: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=10 timeout=-1 >
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=939925">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 1145028
: 939925 |
939926