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 837398 Details for
Bug 1043069
CVE-2013-6458 libvirtd crashes when swapping disks in qemu guest multiple times - qemuMonitorJSONGetBlockStatsInfo segfault [rhel-6.6]
[?]
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.
libvirtd.log file
libvirtd_segfault.log (text/plain), 102.42 KB, created by
Alexandre M
on 2013-12-16 20:21:03 UTC
(
hide
)
Description:
libvirtd.log file
Filename:
MIME Type:
Creator:
Alexandre M
Created:
2013-12-16 20:21:03 UTC
Size:
102.42 KB
patch
obsolete
>2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553937 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553937 due in 4999 ms >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.938+0000: 29313: debug : virEventPollInterruptLocked:701 : Interrupting >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.938+0000: 29313: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1ee3000 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.938+0000: 29313: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1ee19d0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=5 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553937 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553937 due in 4999 ms >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollRunOnce:625 : Poll got 2 event(s) >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=2 >2013-12-12 08:39:08.938+0000: 29304: debug : virNetMessageFree:73 : msg=0x1ee4e00 nfds=0 cb=(nil) >2013-12-12 08:39:08.938+0000: 29304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee1ef0 tx=(nil) >2013-12-12 08:39:08.938+0000: 29304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=1 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553937 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553937 due in 4999 ms >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.938+0000: 29304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.939+0000: 29304: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more) >2013-12-12 08:39:08.939+0000: 29304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee1ef0 tx=(nil) >2013-12-12 08:39:08.939+0000: 29304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.939+0000: 29304: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1ee19d0 len=28 prog=536903814 vers=1 proc=157 type=0 status=0 serial=129477 >2013-12-12 08:39:08.939+0000: 29304: debug : virKeepAliveCheckMessage:384 : ka=0x1ee2000, client=0x1ee19d0, msg=0x1ee1ef0 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=23 frequency=5000 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.939+0000: 29304: debug : virObjectRef:168 : OBJECT_REF: obj=0x1ee19d0 >2013-12-12 08:39:08.939+0000: 29304: debug : virNetServerDispatchNewMessage:218 : server=0x1edb0f0 client=0x1ee19d0 message=0x1ee1ef0 >2013-12-12 08:39:08.939+0000: 29304: debug : virObjectRef:168 : OBJECT_REF: obj=0x1ee3000 >2013-12-12 08:39:08.939+0000: 29304: debug : virNetMessageNew:45 : msg=0x1edee20 tracked=1 >2013-12-12 08:39:08.939+0000: 29304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1edee20 tx=(nil) >2013-12-12 08:39:08.939+0000: 29307: debug : virNetServerHandleJob:189 : server=0x1edb0f0 client=0x1ee19d0 message=0x1ee1ef0 prog=0x1ee3000 >2013-12-12 08:39:08.939+0000: 29304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.939+0000: 29307: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=129477 proc=157 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.939+0000: 29307: debug : remoteDispatchGetLibVersionHelper:7093 : server=0x1edb0f0 client=0x1ee19d0 msg=0x1ee1ef0 rerr=0x7fbff9766b80 args=0x7fbfc405da00 ret=0x7fbfc40091b0 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.939+0000: 29307: debug : virConnectGetLibVersion:1615 : conn=0x7fbfec007ab0, libVir=0x7fbff9766b08 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.939+0000: 29307: debug : virNetMessageEncodePayload:358 : Encode length as 36 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.939+0000: 29307: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1ee1ef0 proc=157 len=36 offset=0 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.939+0000: 29307: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1ee19d0 len=36 prog=536903814 vers=1 proc=157 type=1 status=0 serial=129477 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.939+0000: 29307: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1edee20 tx=0x1ee1ef0 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.939+0000: 29307: debug : virNetServerClientCalculateHandleMode:183 : mode=3 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.939+0000: 29307: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=3 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=1 d=0 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553939 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553939 due in 5000 ms >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=5000 >2013-12-12 08:39:08.939+0000: 29307: debug : virEventPollInterruptLocked:701 : Interrupting >2013-12-12 08:39:08.939+0000: 29307: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1ee3000 >2013-12-12 08:39:08.939+0000: 29307: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1ee19d0 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.939+0000: 29304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.939+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=5 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553939 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553939 due in 4999 ms >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=2 >2013-12-12 08:39:08.940+0000: 29304: debug : virNetMessageFree:73 : msg=0x1ee1ef0 nfds=0 cb=(nil) >2013-12-12 08:39:08.940+0000: 29304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1edee20 tx=(nil) >2013-12-12 08:39:08.940+0000: 29304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=1 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553939 >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553939 due in 4999 ms >2013-12-12 08:39:08.940+0000: 29304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.941+0000: 29304: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more) >2013-12-12 08:39:08.941+0000: 29304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1edee20 tx=(nil) >2013-12-12 08:39:08.941+0000: 29304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.941+0000: 29304: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1ee19d0 len=28 prog=536903814 vers=1 proc=51 type=0 status=0 serial=129478 >2013-12-12 08:39:08.941+0000: 29304: debug : virKeepAliveCheckMessage:384 : ka=0x1ee2000, client=0x1ee19d0, msg=0x1edee20 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=23 frequency=5000 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.941+0000: 29304: debug : virObjectRef:168 : OBJECT_REF: obj=0x1ee19d0 >2013-12-12 08:39:08.941+0000: 29304: debug : virNetServerDispatchNewMessage:218 : server=0x1edb0f0 client=0x1ee19d0 message=0x1edee20 >2013-12-12 08:39:08.941+0000: 29304: debug : virObjectRef:168 : OBJECT_REF: obj=0x1ee3000 >2013-12-12 08:39:08.941+0000: 29304: debug : virNetMessageNew:45 : msg=0x1ee03b0 tracked=1 >2013-12-12 08:39:08.941+0000: 29310: debug : virNetServerHandleJob:189 : server=0x1edb0f0 client=0x1ee19d0 message=0x1edee20 prog=0x1ee3000 >2013-12-12 08:39:08.941+0000: 29304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee03b0 tx=(nil) >2013-12-12 08:39:08.941+0000: 29310: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=129478 proc=51 >2013-12-12 08:39:08.941+0000: 29304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.941+0000: 29310: debug : remoteDispatchNumOfDomainsHelper:10575 : server=0x1edb0f0 client=0x1ee19d0 msg=0x1edee20 rerr=0x7fbff7963b80 args=0x7fbfd8005a90 ret=0x7fbfd8005a20 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.941+0000: 29310: debug : virConnectNumOfDomains:1855 : conn=0x7fbfec007ab0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.941+0000: 29310: debug : virNetMessageEncodePayload:358 : Encode length as 32 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.941+0000: 29310: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1edee20 proc=51 len=32 offset=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.941+0000: 29310: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1ee19d0 len=32 prog=536903814 vers=1 proc=51 type=1 status=0 serial=129478 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.941+0000: 29310: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee03b0 tx=0x1edee20 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29310: debug : virNetServerClientCalculateHandleMode:183 : mode=3 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29310: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=3 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553941 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553941 due in 5000 ms >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=5000 >2013-12-12 08:39:08.941+0000: 29310: debug : virEventPollInterruptLocked:701 : Interrupting >2013-12-12 08:39:08.941+0000: 29310: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1ee3000 >2013-12-12 08:39:08.941+0000: 29310: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1ee19d0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.941+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=5 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553941 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553941 due in 4999 ms >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=2 >2013-12-12 08:39:08.942+0000: 29304: debug : virNetMessageFree:73 : msg=0x1edee20 nfds=0 cb=(nil) >2013-12-12 08:39:08.942+0000: 29304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee03b0 tx=(nil) >2013-12-12 08:39:08.942+0000: 29304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=1 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553941 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553941 due in 4999 ms >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.942+0000: 29304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.942+0000: 29304: debug : virNetMessageDecodeLength:152 : Got length, now need 32 total (28 more) >2013-12-12 08:39:08.942+0000: 29304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee03b0 tx=(nil) >2013-12-12 08:39:08.942+0000: 29304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 15:39:56.785+0000: 29304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >Caught Segmentation violation2013-12-12 15:39:56.785+0000: 29304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 > dumping internal log buffer: > > > ====== start of log ===== > >00029304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=2 >2013-12-12 08:39:08.936+000029304: debug : virNetMessageFree:73 : msg=0x1ee2eb0 nfds=0 cb=(nil) >2013-12-12 08:39:08.936+000029304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee4e00 tx=(nil) >2013-12-12 08:39:08.936+000029304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.936+000029304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.936+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.936+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=22 events=1 >2013-12-12 08:39:08.936+000029304: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fbfec277950 >2013-12-12 08:39:08.936+000029304: debug : qemuMonitorIOProcess:354 : QEMU_MONITOR_IO_PROCESS: mon=0x7fbfec277950 buf={"return": [{"device": "drive-virtio-disk0", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 624819712, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 15062281227, "wr_highest_offset": 16341233152, "wr_total_time_ns": 248529949234, "wr_bytes": 5336109568, "rd_total_time_ns": 213134069200, "flush_operations": 106674, "wr_operations": 864319, "rd_bytes": 1941015552, "rd_operations": 457497}}, {"device": "drive-virtio-disk287105055", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 357637632, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 0, "wr_highest_offset": 357637632, "wr_total_time_ns": 8712092, "wr_bytes": 69632, "rd_total_time_ns": 109700571, "flush_operations": 0, "wr_operations": 17, "rd_bytes": 1304576, "rd_operations": 154}}], "id": "libvirt-20007"} > len=1063 >2013-12-12 08:39:08.936+000029304: debug : qemuMonitorJSONIOProcessLine:154 : Line [{"return": [{"device": "drive-virtio-disk0", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 624819712, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 15062281227, "wr_highest_offset": 16341233152, "wr_total_time_ns": 248529949234, "wr_bytes": 5336109568, "rd_total_time_ns": 213134069200, "flush_operations": 106674, "wr_operations": 864319, "rd_bytes": 1941015552, "rd_operations": 457497}}, {"device": "drive-virtio-disk287105055", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 357637632, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 0, "wr_highest_offset": 357637632, "wr_total_time_ns": 8712092, "wr_bytes": 69632, "rd_total_time_ns": 109700571, "flush_operations": 0, "wr_operations": 17, "rd_bytes": 1304576, "rd_operations": 154}}], "id": "libvirt-20007"}] >2013-12-12 08:39:08.936+000029304: debug : virJSONValueFromString:975 : string={"return": [{"device": "drive-virtio-disk0", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 624819712, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 15062281227, "wr_highest_offset": 16341233152, "wr_total_time_ns": 248529949234, "wr_bytes": 5336109568, "rd_total_time_ns": 213134069200, "flush_operations": 106674, "wr_operations": 864319, "rd_bytes": 1941015552, "rd_operations": 457497}}, {"device": "drive-virtio-disk287105055", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 357637632, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 0, "wr_highest_offset": 357637632, "wr_total_time_ns": 8712092, "wr_bytes": 69632, "rd_total_time_ns": 109700571, "flush_operations": 0, "wr_operations": 17, "rd_bytes": 1304576, "rd_operations": 154}}], "id": "libvirt-20007"} >2013-12-12 08:39:08.936+000029304: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.936+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8842 >2013-12-12 08:39:08.936+000029304: debug : virJSONParserHandleStartArray:905 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.936+000029304: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.936+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee884e >2013-12-12 08:39:08.936+000029304: debug : virJSONParserHandleString:813 : parser=0x7fffd1fa6db0 str=0x1ee8858 >2013-12-12 08:39:08.936+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee886e >2013-12-12 08:39:08.936+000029304: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8879 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8883 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee889d >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee88bd >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee88d4 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee88e3 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee88fa >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8911 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8925 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8934 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee894a >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8954 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8978 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee899a >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee89bc >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee89d4 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee89f6 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8a12 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8a2b >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8a43 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8a5f >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleString:813 : parser=0x7fffd1fa6db0 str=0x1ee8a69 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8a87 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8a92 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8a9c >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8ab6 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8ad6 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8aed >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8afc >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8b13 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8b2a >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8b3e >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8b4d >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8b63 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8b6d >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8b87 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8ba7 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8bc4 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8bd7 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8bf6 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8c0d >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8c22 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8c37 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleNumber:792 : parser=0x7fffd1fa6db0 str=(null) >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleEndArray:931 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd1fa6db0 key=0x1ee8c50 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleString:813 : parser=0x7fffd1fa6db0 str=0x1ee8c56 >2013-12-12 08:39:08.937+000029304: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd1fa6db0 >2013-12-12 08:39:08.937+000029304: debug : virJSONValueFromString:1019 : result=0x1ebead0 >2013-12-12 08:39:08.937+000029304: debug : qemuMonitorJSONIOProcessLine:174 : QEMU_MONITOR_RECV_REPLY: mon=0x7fbfec277950 reply={"return": [{"device": "drive-virtio-disk0", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 624819712, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 15062281227, "wr_highest_offset": 16341233152, "wr_total_time_ns": 248529949234, "wr_bytes": 5336109568, "rd_total_time_ns": 213134069200, "flush_operations": 106674, "wr_operations": 864319, "rd_bytes": 1941015552, "rd_operations": 457497}}, {"device": "drive-virtio-disk287105055", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 357637632, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 0, "wr_highest_offset": 357637632, "wr_total_time_ns": 8712092, "wr_bytes": 69632, "rd_total_time_ns": 109700571, "flush_operations": 0, "wr_operations": 17, "rd_bytes": 1304576, "rd_operations": 154}}], "id": "libvirt-20007"} >2013-12-12 08:39:08.937+000029304: debug : qemuMonitorJSONIOProcess:225 : Total used 1063 bytes out of 1063 available in buffer >2013-12-12 08:39:08.937+000029304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=22 events=13 >2013-12-12 08:39:08.937+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.937+000029304: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fbfec277950 >2013-12-12 08:39:08.937+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.937+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.937+000029304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.937+000029308: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=22 events=13 >2013-12-12 08:39:08.937+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.937+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.937+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.937+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.937+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.937+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.937+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.937+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.937+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.937+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.937+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.937+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=1 d=0 >2013-12-12 08:39:08.937+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.937+000029304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.937+000029304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553936 >2013-12-12 08:39:08.937+000029304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553936 due in 4999 ms >2013-12-12 08:39:08.937+000029304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.937+000029304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.937+000029308: debug : virEventPollInterruptLocked:701 : Interrupting >2013-12-12 08:39:08.937+000029304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.937+000029308: debug : qemuMonitorJSONCommandWithFd:272 : Receive command reply ret=0 rxObject=0x1ebead0 >2013-12-12 08:39:08.937+000029304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.937+000029304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.937+000029304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.937+000029304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.937+000029304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.937+000029304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.937+000029304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.937+000029304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.937+000029304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.937+000029304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.937+000029304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.937+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.937+000029304: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more) >2013-12-12 08:39:08.937+000029304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee4e00 tx=(nil) >2013-12-12 08:39:08.937+000029304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.937+000029304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.937+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.937+000029304: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1ee19d0 len=28 prog=536903814 vers=1 proc=51 type=0 status=0 serial=129476 >2013-12-12 08:39:08.937+000029304: debug : virKeepAliveCheckMessage:384 : ka=0x1ee2000, client=0x1ee19d0, msg=0x1ee4e00 >2013-12-12 08:39:08.937+000029304: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=23 frequency=5000 >2013-12-12 08:39:08.937+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.937+000029304: debug : virObjectRef:168 : OBJECT_REF: obj=0x1ee19d0 >2013-12-12 08:39:08.937+000029304: debug : virNetServerDispatchNewMessage:218 : server=0x1edb0f0 client=0x1ee19d0 message=0x1ee4e00 >2013-12-12 08:39:08.937+000029304: debug : virObjectRef:168 : OBJECT_REF: obj=0x1ee3000 >2013-12-12 08:39:08.937+000029304: debug : virNetMessageNew:45 : msg=0x1ee1ef0 tracked=1 >2013-12-12 08:39:08.937+000029304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee1ef0 tx=(nil) >2013-12-12 08:39:08.937+000029313: debug : virNetServerHandleJob:189 : server=0x1edb0f0 client=0x1ee19d0 message=0x1ee4e00 prog=0x1ee3000 >2013-12-12 08:39:08.937+000029304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.937+000029313: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=129476 proc=51 >2013-12-12 08:39:08.938+000029304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.938+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.938+000029313: debug : remoteDispatchNumOfDomainsHelper:10575 : server=0x1edb0f0 client=0x1ee19d0 msg=0x1ee4e00 rerr=0x7fbff5b60b80 args=0x7fbfdc0bf740 ret=0x7fbfdc0bf6d0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.938+000029313: debug : virConnectNumOfDomains:1855 : conn=0x7fbfec007ab0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.938+000029304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.938+000029313: debug : virNetMessageEncodePayload:358 : Encode length as 32 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.938+000029313: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1ee4e00 proc=51 len=32 offset=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.938+000029313: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1ee19d0 len=32 prog=536903814 vers=1 proc=51 type=1 status=0 serial=129476 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.938+000029313: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee1ef0 tx=0x1ee4e00 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.938+000029313: debug : virNetServerClientCalculateHandleMode:183 : mode=3 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.938+000029313: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=3 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.938+000029304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553937 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553937 due in 4999 ms >2013-12-12 08:39:08.938+000029304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.938+000029313: debug : virEventPollInterruptLocked:701 : Interrupting >2013-12-12 08:39:08.938+000029304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.938+000029313: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1ee3000 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.938+000029313: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1ee19d0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.938+000029304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.938+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=5 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.938+000029304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553937 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553937 due in 4999 ms >2013-12-12 08:39:08.938+000029304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.938+000029304: debug : virEventPollRunOnce:625 : Poll got 2 event(s) >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=2 >2013-12-12 08:39:08.938+000029304: debug : virNetMessageFree:73 : msg=0x1ee4e00 nfds=0 cb=(nil) >2013-12-12 08:39:08.938+000029304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee1ef0 tx=(nil) >2013-12-12 08:39:08.938+000029304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.938+000029304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.938+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.938+000029304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.938+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=1 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.938+000029304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553937 >2013-12-12 08:39:08.938+000029304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553937 due in 4999 ms >2013-12-12 08:39:08.938+000029304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.938+000029304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.938+000029304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.939+000029304: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more) >2013-12-12 08:39:08.939+000029304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee1ef0 tx=(nil) >2013-12-12 08:39:08.939+000029304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.939+000029304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.939+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.939+000029304: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1ee19d0 len=28 prog=536903814 vers=1 proc=157 type=0 status=0 serial=129477 >2013-12-12 08:39:08.939+000029304: debug : virKeepAliveCheckMessage:384 : ka=0x1ee2000, client=0x1ee19d0, msg=0x1ee1ef0 >2013-12-12 08:39:08.939+000029304: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=23 frequency=5000 >2013-12-12 08:39:08.939+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.939+000029304: debug : virObjectRef:168 : OBJECT_REF: obj=0x1ee19d0 >2013-12-12 08:39:08.939+000029304: debug : virNetServerDispatchNewMessage:218 : server=0x1edb0f0 client=0x1ee19d0 message=0x1ee1ef0 >2013-12-12 08:39:08.939+000029304: debug : virObjectRef:168 : OBJECT_REF: obj=0x1ee3000 >2013-12-12 08:39:08.939+000029304: debug : virNetMessageNew:45 : msg=0x1edee20 tracked=1 >2013-12-12 08:39:08.939+000029304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1edee20 tx=(nil) >2013-12-12 08:39:08.939+000029307: debug : virNetServerHandleJob:189 : server=0x1edb0f0 client=0x1ee19d0 message=0x1ee1ef0 prog=0x1ee3000 >2013-12-12 08:39:08.939+000029304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.939+000029307: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=129477 proc=157 >2013-12-12 08:39:08.939+000029304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.939+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.939+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.939+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.939+000029307: debug : remoteDispatchGetLibVersionHelper:7093 : server=0x1edb0f0 client=0x1ee19d0 msg=0x1ee1ef0 rerr=0x7fbff9766b80 args=0x7fbfc405da00 ret=0x7fbfc40091b0 >2013-12-12 08:39:08.939+000029304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.939+000029307: debug : virConnectGetLibVersion:1615 : conn=0x7fbfec007ab0, libVir=0x7fbff9766b08 >2013-12-12 08:39:08.939+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.939+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.939+000029307: debug : virNetMessageEncodePayload:358 : Encode length as 36 >2013-12-12 08:39:08.939+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.939+000029307: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1ee1ef0 proc=157 len=36 offset=0 >2013-12-12 08:39:08.939+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.939+000029307: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1ee19d0 len=36 prog=536903814 vers=1 proc=157 type=1 status=0 serial=129477 >2013-12-12 08:39:08.939+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.939+000029307: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1edee20 tx=0x1ee1ef0 >2013-12-12 08:39:08.939+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.939+000029307: debug : virNetServerClientCalculateHandleMode:183 : mode=3 >2013-12-12 08:39:08.939+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.939+000029307: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=3 >2013-12-12 08:39:08.939+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.939+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.939+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.939+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.939+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=1 d=0 >2013-12-12 08:39:08.939+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.939+000029304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.939+000029304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553939 >2013-12-12 08:39:08.939+000029304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553939 due in 5000 ms >2013-12-12 08:39:08.939+000029304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=5000 >2013-12-12 08:39:08.939+000029307: debug : virEventPollInterruptLocked:701 : Interrupting >2013-12-12 08:39:08.939+000029307: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1ee3000 >2013-12-12 08:39:08.939+000029307: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1ee19d0 >2013-12-12 08:39:08.939+000029304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.939+000029304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.939+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.939+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.939+000029304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.939+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.940+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=5 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.940+000029304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553939 >2013-12-12 08:39:08.940+000029304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553939 due in 4999 ms >2013-12-12 08:39:08.940+000029304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.940+000029304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=2 >2013-12-12 08:39:08.940+000029304: debug : virNetMessageFree:73 : msg=0x1ee1ef0 nfds=0 cb=(nil) >2013-12-12 08:39:08.940+000029304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1edee20 tx=(nil) >2013-12-12 08:39:08.940+000029304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.940+000029304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.940+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.940+000029304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.940+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.940+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.940+000029304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.940+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.940+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=1 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.940+000029304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.940+000029304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553939 >2013-12-12 08:39:08.940+000029304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553939 due in 4999 ms >2013-12-12 08:39:08.940+000029304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.941+000029304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.941+000029304: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more) >2013-12-12 08:39:08.941+000029304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1edee20 tx=(nil) >2013-12-12 08:39:08.941+000029304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.941+000029304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.941+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.941+000029304: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1ee19d0 len=28 prog=536903814 vers=1 proc=51 type=0 status=0 serial=129478 >2013-12-12 08:39:08.941+000029304: debug : virKeepAliveCheckMessage:384 : ka=0x1ee2000, client=0x1ee19d0, msg=0x1edee20 >2013-12-12 08:39:08.941+000029304: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=23 frequency=5000 >2013-12-12 08:39:08.941+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.941+000029304: debug : virObjectRef:168 : OBJECT_REF: obj=0x1ee19d0 >2013-12-12 08:39:08.941+000029304: debug : virNetServerDispatchNewMessage:218 : server=0x1edb0f0 client=0x1ee19d0 message=0x1edee20 >2013-12-12 08:39:08.941+000029304: debug : virObjectRef:168 : OBJECT_REF: obj=0x1ee3000 >2013-12-12 08:39:08.941+000029304: debug : virNetMessageNew:45 : msg=0x1ee03b0 tracked=1 >2013-12-12 08:39:08.941+000029310: debug : virNetServerHandleJob:189 : server=0x1edb0f0 client=0x1ee19d0 message=0x1edee20 prog=0x1ee3000 >2013-12-12 08:39:08.941+000029304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee03b0 tx=(nil) >2013-12-12 08:39:08.941+000029310: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=129478 proc=51 >2013-12-12 08:39:08.941+000029304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.941+000029304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.941+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.941+000029310: debug : remoteDispatchNumOfDomainsHelper:10575 : server=0x1edb0f0 client=0x1ee19d0 msg=0x1edee20 rerr=0x7fbff7963b80 args=0x7fbfd8005a90 ret=0x7fbfd8005a20 >2013-12-12 08:39:08.941+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.941+000029310: debug : virConnectNumOfDomains:1855 : conn=0x7fbfec007ab0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.941+000029310: debug : virNetMessageEncodePayload:358 : Encode length as 32 >2013-12-12 08:39:08.941+000029304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.941+000029310: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1edee20 proc=51 len=32 offset=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.941+000029310: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1ee19d0 len=32 prog=536903814 vers=1 proc=51 type=1 status=0 serial=129478 >2013-12-12 08:39:08.941+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.941+000029310: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee03b0 tx=0x1edee20 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.941+000029310: debug : virNetServerClientCalculateHandleMode:183 : mode=3 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.941+000029310: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=3 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.941+000029304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553941 >2013-12-12 08:39:08.941+000029304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553941 due in 5000 ms >2013-12-12 08:39:08.941+000029304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=5000 >2013-12-12 08:39:08.941+000029310: debug : virEventPollInterruptLocked:701 : Interrupting >2013-12-12 08:39:08.941+000029310: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1ee3000 >2013-12-12 08:39:08.941+000029310: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1ee19d0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.941+000029304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.941+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.941+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.941+000029304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.941+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.941+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.941+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=5 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.942+000029304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553941 >2013-12-12 08:39:08.942+000029304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553941 due in 4999 ms >2013-12-12 08:39:08.942+000029304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.942+000029304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=2 >2013-12-12 08:39:08.942+000029304: debug : virNetMessageFree:73 : msg=0x1edee20 nfds=0 cb=(nil) >2013-12-12 08:39:08.942+000029304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee03b0 tx=(nil) >2013-12-12 08:39:08.942+000029304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 08:39:08.942+000029304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.942+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=10 w=22 >2013-12-12 08:39:08.942+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.942+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.942+000029304: debug : virEventRunDefaultImpl:244 : running default event implementation >2013-12-12 08:39:08.942+000029304: debug : virEventPollCleanupTimeouts:501 : Cleanup 5 >2013-12-12 08:39:08.942+000029304: debug : virEventPollCleanupHandles:549 : Cleanup 11 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=9, f=20 e=1 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=16, f=23 e=1 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=21, f=17 e=1 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=22, f=28 e=25 d=0 >2013-12-12 08:39:08.942+000029304: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 5 timers >2013-12-12 08:39:08.942+000029304: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1386837553941 >2013-12-12 08:39:08.942+000029304: debug : virEventPollCalculateTimeout:346 : Timeout at 1386837553941 due in 4999 ms >2013-12-12 08:39:08.942+000029304: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=4999 >2013-12-12 08:39:08.942+000029304: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchTimeouts:410 : Dispatch 5 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=6 w=7 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=7 w=9 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=8 w=16 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:469 : i=9 w=21 >2013-12-12 08:39:08.942+000029304: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=1 >2013-12-12 08:39:08.942+000029304: debug : virNetMessageDecodeLength:152 : Got length, now need 32 total (28 more) >2013-12-12 08:39:08.942+000029304: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1ee03b0 tx=(nil) >2013-12-12 08:39:08.942+000029304: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2013-12-12 15:39:56.785+000029304: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=21 events=1 >2013-12-12 15:39:56.785+000029304: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 154052704 > > > ====== end of log ===== > >2013-12-12 15:40:18.556+0000: 10711: info : libvirt version: 0.10.2, package: 29.el6.1 (CentOS BuildSystem <http://bugs.centos.org>, 2013-12-05-10:42:29, c6b10.bsys.dev.centos.org)
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=837398">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 1043069
:
836510
|
837179
|
837180
|
837181
|
837182
| 837398 |
862098