[Date Prev][Date Next]   [Thread Prev][Thread Next]   [Thread Index] [Date Index] [Author Index]

[libvirt] 1.2.0 segfault on Centos 6



Hi,

using libvirt 1.2.0 on a up-to-date Centos6.5 machine leads to occasional segmentation faults (see below). Sometimes it runs for 5 minutes, sometimes for an hour, but after that the result is always the same: segfault after some weird qom-list, that apparently the qemu version on centos doesn't know. Has 1.2.1 a known fix for this?

Franky (debug log of last crash is below the dotted line)

-------------------------------------------------

2014-02-04 15:50:27.351+0000: 9082: error : qemuMonitorJSONCheckError:354 : internal error: unable to execute QEMU command 'qom-list': The command qom-list has not been found
Caught Segmentation violation dumping internal log buffer:


    ====== start of log =====

+0000: 9084: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac58000ac0
2014-02-04 15:50:27.347+0000: 9084: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac58000ac0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventRunDefaultImpl:271 : running default event implementation 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.347+0000: 9084: debug : remoteDispatchDomainLookupByNameHelper:4965 : server=0x7fac7ce77ae0 client=0x7fac7ce86610 msg=0x7fac7ce827f0 rerr=0x7fac6c5b3bc0 args=0x7fac40002730 ret=0x7fac40002790 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.347+0000: 9084: debug : virDomainLookupByName:2269 : conn=0x7fac340018a0, name=bqafm0001ap.frm.meshcore.net 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=5 e=1 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=7 e=1 d=0 2014-02-04 15:50:27.347+0000: 9084: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac7ce760b0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=2 w=3, f=12 e=1 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=3 w=4, f=13 e=1 d=0 2014-02-04 15:50:27.347+0000: 9084: debug : virAccessManagerCheckDomain:231 : manager=0x7fac7ce760b0(name=stack) driver=QEMU domain=0x7fac5c2386c0 perm=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=4 w=5, f=14 e=1 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=5 w=6, f=15 e=1 d=0 2014-02-04 15:50:27.347+0000: 9084: debug : virAccessManagerCheckDomain:231 : manager=0x7fac7ce776f0(name=none) driver=QEMU domain=0x7fac5c2386c0 perm=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=6 w=7, f=16 e=1 d=0 2014-02-04 15:50:27.347+0000: 9084: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac7ce760b0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=7 w=8, f=17 e=1 d=0 2014-02-04 15:50:27.347+0000: 9084: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7fac40002750 classname=virDomain 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=8 w=9, f=19 e=1 d=0 2014-02-04 15:50:27.347+0000: 9084: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac340018a0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=9 w=10, f=23 e=25 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=10 w=11, f=25 e=25 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=11 w=12, f=24 e=25 d=0 2014-02-04 15:50:27.347+0000: 9084: debug : virDomainFree:2433 : dom=0x7fac40002750, (VM: name=bqafm0001ap.frm.meshcore.net, uuid=3b009e73-e095-29fa-8970-f5c2b647d5a0) 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=12 w=13, f=26 e=25 d=0 2014-02-04 15:50:27.347+0000: 9084: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac40002750 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=13 w=14, f=27 e=1 d=0 2014-02-04 15:50:27.347+0000: 9084: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7fac40002750 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=14 w=58, f=28 e=1 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 5 timers 2014-02-04 15:50:27.347+0000: 9084: debug : virDomainDispose:264 : release domain 0x7fac40002750 bqafm0001ap.frm.meshcore.net 3b009e73-e095-29fa-8970-f5c2b647d5a0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529031312 2014-02-04 15:50:27.347+0000: 9084: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac340018a0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529032347 2014-02-04 15:50:27.347+0000: 9084: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac58000ac0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1391529031312 now=1391529027347 2014-02-04 15:50:27.347+0000: 9084: debug : virNetMessageEncodePayload:374 : Encode length as 80 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollCalculateTimeout:362 : Timeout at 1391529031312 due in 3965 ms 2014-02-04 15:50:27.347+0000: 9084: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac58000ac0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=15 timeout=3965 2014-02-04 15:50:27.347+0000: 9084: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7fac7ce827f0 proc=23 len=80 offset=0 2014-02-04 15:50:27.347+0000: 9084: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fac7ce86610 len=80 prog=536903814 vers=1 proc=23 type=1 status=0 serial=4 2014-02-04 15:50:27.347+0000: 9084: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fac7ce86c80 tx=0x7fac7ce827f0 2014-02-04 15:50:27.347+0000: 9084: debug : virNetServerClientCalculateHandleMode:188 : mode=3 2014-02-04 15:50:27.347+0000: 9084: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=58 events=3 2014-02-04 15:50:27.347+0000: 9084: debug : virEventPollInterruptLocked:717 : Interrupting 2014-02-04 15:50:27.347+0000: 9084: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac7ce80030 2014-02-04 15:50:27.347+0000: 9084: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac7ce86610 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollRunOnce:641 : Poll got 1 event(s) 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchTimeouts:426 : Dispatch 5 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:471 : Dispatch 15 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=0 w=1 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=1 w=2 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=2 w=3 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=3 w=4 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=4 w=5 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=5 w=6 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=6 w=7 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=7 w=8 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=8 w=9 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=9 w=10 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=10 w=11 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=11 w=12 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=12 w=13 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=13 w=14 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollDispatchHandles:485 : i=14 w=58 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.347+0000: 9081: debug : virEventRunDefaultImpl:271 : running default event implementation 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=5 e=1 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=7 e=1 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=2 w=3, f=12 e=1 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=3 w=4, f=13 e=1 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=4 w=5, f=14 e=1 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=5 w=6, f=15 e=1 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=6 w=7, f=16 e=1 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=7 w=8, f=17 e=1 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=8 w=9, f=19 e=1 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=9 w=10, f=23 e=25 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=10 w=11, f=25 e=25 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=11 w=12, f=24 e=25 d=0 2014-02-04 15:50:27.347+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=12 w=13, f=26 e=25 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=13 w=14, f=27 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=14 w=58, f=28 e=5 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 5 timers 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529031312 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529032347 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1391529031312 now=1391529027348 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:362 : Timeout at 1391529031312 due in 3964 ms 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=15 timeout=3964 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollRunOnce:641 : Poll got 1 event(s) 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchTimeouts:426 : Dispatch 5 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:471 : Dispatch 15 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=0 w=1 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=1 w=2 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=2 w=3 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=3 w=4 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=4 w=5 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=5 w=6 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=6 w=7 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=7 w=8 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=8 w=9 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=9 w=10 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=10 w=11 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=11 w=12 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=12 w=13 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=13 w=14 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=14 w=58 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=58 events=2 2014-02-04 15:50:27.348+0000: 9081: debug : virNetMessageFree:72 : msg=0x7fac7ce827f0 nfds=0 cb=(nil) 2014-02-04 15:50:27.348+0000: 9081: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fac7ce86c80 tx=(nil) 2014-02-04 15:50:27.348+0000: 9081: debug : virNetServerClientCalculateHandleMode:188 : mode=1 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=58 events=1 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollInterruptLocked:713 : Skip interrupt, 1 140378773645312 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.348+0000: 9081: debug : virEventRunDefaultImpl:271 : running default event implementation 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=5 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=7 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=2 w=3, f=12 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=3 w=4, f=13 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=4 w=5, f=14 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=5 w=6, f=15 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=6 w=7, f=16 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=7 w=8, f=17 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=8 w=9, f=19 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=9 w=10, f=23 e=25 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=10 w=11, f=25 e=25 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=11 w=12, f=24 e=25 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=12 w=13, f=26 e=25 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=13 w=14, f=27 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=14 w=58, f=28 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 5 timers 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529031312 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529032347 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1391529031312 now=1391529027348 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:362 : Timeout at 1391529031312 due in 3964 ms 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=15 timeout=3964 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollRunOnce:641 : Poll got 1 event(s) 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchTimeouts:426 : Dispatch 5 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:471 : Dispatch 15 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=0 w=1 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=1 w=2 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=2 w=3 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=3 w=4 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=4 w=5 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=5 w=6 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=6 w=7 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=7 w=8 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=8 w=9 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=9 w=10 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=10 w=11 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=11 w=12 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=12 w=13 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=13 w=14 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=14 w=58 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=58 events=1 2014-02-04 15:50:27.348+0000: 9081: debug : virNetMessageDecodeLength:149 : Got length, now need 80 total (76 more) 2014-02-04 15:50:27.348+0000: 9081: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fac7ce86c80 tx=(nil) 2014-02-04 15:50:27.348+0000: 9081: debug : virNetServerClientCalculateHandleMode:188 : mode=1 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=58 events=1 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollInterruptLocked:713 : Skip interrupt, 1 140378773645312 2014-02-04 15:50:27.348+0000: 9081: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fac7ce86610 len=80 prog=536903814 vers=1 proc=150 type=0 status=0 serial=5 2014-02-04 15:50:27.348+0000: 9081: debug : virKeepAliveCheckMessage:374 : ka=0x7fac7ce86d60, client=0x7fac7ce86610, msg=0x7fac7ce86c80 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=59 frequency=5000 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollUpdateTimeout:278 : Set timer freq=5000 expires=1391529032348 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollInterruptLocked:713 : Skip interrupt, 1 140378773645312 2014-02-04 15:50:27.348+0000: 9081: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac7ce86610 2014-02-04 15:50:27.348+0000: 9081: debug : virNetServerDispatchNewMessage:213 : server=0x7fac7ce77ae0 client=0x7fac7ce86610 message=0x7fac7ce86c80 2014-02-04 15:50:27.348+0000: 9081: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac7ce80030 2014-02-04 15:50:27.348+0000: 9081: debug : virNetMessageNew:44 : msg=0x7fac7ce86e60 tracked=1 2014-02-04 15:50:27.348+0000: 9081: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fac7ce86e60 tx=(nil) 2014-02-04 15:50:27.348+0000: 9083: debug : virNetServerHandleJob:184 : server=0x7fac7ce77ae0 client=0x7fac7ce86610 message=0x7fac7ce86c80 prog=0x7fac7ce80030 2014-02-04 15:50:27.348+0000: 9081: debug : virNetServerClientCalculateHandleMode:188 : mode=1 2014-02-04 15:50:27.348+0000: 9083: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=150 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=58 events=1 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollInterruptLocked:713 : Skip interrupt, 1 140378773645312 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.348+0000: 9083: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac58000ac0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventRunDefaultImpl:271 : running default event implementation 2014-02-04 15:50:27.348+0000: 9083: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac58000ac0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.348+0000: 9083: debug : remoteDispatchDomainIsActiveHelper:4723 : server=0x7fac7ce77ae0 client=0x7fac7ce86610 msg=0x7fac7ce86c80 rerr=0x7fac6cfb4bc0 args=0x7fac50000970 ret=0x7fac50000910 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.348+0000: 9083: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7fac50000930 classname=virDomain 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=5 e=1 d=0 2014-02-04 15:50:27.348+0000: 9083: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac340018a0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=7 e=1 d=0 2014-02-04 15:50:27.348+0000: 9083: debug : virDomainIsActive:17649 : dom=0x7fac50000930 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=2 w=3, f=12 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=3 w=4, f=13 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=4 w=5, f=14 e=1 d=0 2014-02-04 15:50:27.348+0000: 9083: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac7ce760b0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=5 w=6, f=15 e=1 d=0 2014-02-04 15:50:27.348+0000: 9083: debug : virAccessManagerCheckDomain:231 : manager=0x7fac7ce760b0(name=stack) driver=QEMU domain=0x7fac5c2386c0 perm=1 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=6 w=7, f=16 e=1 d=0 2014-02-04 15:50:27.348+0000: 9083: debug : virAccessManagerCheckDomain:231 : manager=0x7fac7ce776f0(name=none) driver=QEMU domain=0x7fac5c2386c0 perm=1 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=7 w=8, f=17 e=1 d=0 2014-02-04 15:50:27.348+0000: 9083: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac7ce760b0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=8 w=9, f=19 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=9 w=10, f=23 e=25 d=0 2014-02-04 15:50:27.348+0000: 9083: debug : virDomainFree:2433 : dom=0x7fac50000930, (VM: name=bqafm0001ap.frm.meshcore.net, uuid=3b009e73-e095-29fa-8970-f5c2b647d5a0) 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=10 w=11, f=25 e=25 d=0 2014-02-04 15:50:27.348+0000: 9083: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac50000930 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=11 w=12, f=24 e=25 d=0 2014-02-04 15:50:27.348+0000: 9083: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7fac50000930 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=12 w=13, f=26 e=25 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=13 w=14, f=27 e=1 d=0 2014-02-04 15:50:27.348+0000: 9083: debug : virDomainDispose:264 : release domain 0x7fac50000930 bqafm0001ap.frm.meshcore.net 3b009e73-e095-29fa-8970-f5c2b647d5a0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=14 w=58, f=28 e=1 d=0 2014-02-04 15:50:27.348+0000: 9083: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac340018a0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 5 timers 2014-02-04 15:50:27.348+0000: 9083: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac58000ac0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529031312 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529032348 2014-02-04 15:50:27.348+0000: 9083: debug : virNetMessageEncodePayload:374 : Encode length as 32 2014-02-04 15:50:27.348+0000: 9083: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac58000ac0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1391529031312 now=1391529027348 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:362 : Timeout at 1391529031312 due in 3964 ms 2014-02-04 15:50:27.348+0000: 9083: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7fac7ce86c80 proc=150 len=32 offset=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=15 timeout=3964 2014-02-04 15:50:27.348+0000: 9083: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fac7ce86610 len=32 prog=536903814 vers=1 proc=150 type=1 status=0 serial=5 2014-02-04 15:50:27.348+0000: 9083: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fac7ce86e60 tx=0x7fac7ce86c80 2014-02-04 15:50:27.348+0000: 9083: debug : virNetServerClientCalculateHandleMode:188 : mode=3 2014-02-04 15:50:27.348+0000: 9083: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=58 events=3 2014-02-04 15:50:27.348+0000: 9083: debug : virEventPollInterruptLocked:717 : Interrupting 2014-02-04 15:50:27.348+0000: 9083: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac7ce80030 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollRunOnce:641 : Poll got 1 event(s) 2014-02-04 15:50:27.348+0000: 9083: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac7ce86610 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchTimeouts:426 : Dispatch 5 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:471 : Dispatch 15 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=0 w=1 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=1 w=2 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=2 w=3 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=3 w=4 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=4 w=5 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=5 w=6 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=6 w=7 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=7 w=8 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=8 w=9 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=9 w=10 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=10 w=11 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=11 w=12 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=12 w=13 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=13 w=14 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=14 w=58 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.348+0000: 9081: debug : virEventRunDefaultImpl:271 : running default event implementation 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=5 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=7 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=2 w=3, f=12 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=3 w=4, f=13 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=4 w=5, f=14 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=5 w=6, f=15 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=6 w=7, f=16 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=7 w=8, f=17 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=8 w=9, f=19 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=9 w=10, f=23 e=25 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=10 w=11, f=25 e=25 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=11 w=12, f=24 e=25 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=12 w=13, f=26 e=25 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=13 w=14, f=27 e=1 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=14 w=58, f=28 e=5 d=0 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 5 timers 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529031312 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529032348 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1391529031312 now=1391529027348 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollCalculateTimeout:362 : Timeout at 1391529031312 due in 3964 ms 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=15 timeout=3964 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollRunOnce:641 : Poll got 1 event(s) 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchTimeouts:426 : Dispatch 5 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:471 : Dispatch 15 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=0 w=1 2014-02-04 15:50:27.348+0000: 9081: debug : virEventPollDispatchHandles:485 : i=1 w=2 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=2 w=3 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=3 w=4 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=4 w=5 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=5 w=6 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=6 w=7 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=7 w=8 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=8 w=9 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=9 w=10 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=10 w=11 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=11 w=12 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=12 w=13 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=13 w=14 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=14 w=58 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=58 events=2 2014-02-04 15:50:27.349+0000: 9081: debug : virNetMessageFree:72 : msg=0x7fac7ce86c80 nfds=0 cb=(nil) 2014-02-04 15:50:27.349+0000: 9081: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fac7ce86e60 tx=(nil) 2014-02-04 15:50:27.349+0000: 9081: debug : virNetServerClientCalculateHandleMode:188 : mode=1 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=58 events=1 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollInterruptLocked:713 : Skip interrupt, 1 140378773645312 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.349+0000: 9081: debug : virEventRunDefaultImpl:271 : running default event implementation 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=5 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=7 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=2 w=3, f=12 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=3 w=4, f=13 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=4 w=5, f=14 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=5 w=6, f=15 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=6 w=7, f=16 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=7 w=8, f=17 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=8 w=9, f=19 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=9 w=10, f=23 e=25 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=10 w=11, f=25 e=25 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=11 w=12, f=24 e=25 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=12 w=13, f=26 e=25 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=13 w=14, f=27 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=14 w=58, f=28 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 5 timers 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529031312 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529032348 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1391529031312 now=1391529027349 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:362 : Timeout at 1391529031312 due in 3963 ms 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=15 timeout=3963 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollRunOnce:641 : Poll got 1 event(s) 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchTimeouts:426 : Dispatch 5 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:471 : Dispatch 15 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=0 w=1 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=1 w=2 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=2 w=3 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=3 w=4 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=4 w=5 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=5 w=6 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=6 w=7 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=7 w=8 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=8 w=9 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=9 w=10 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=10 w=11 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=11 w=12 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=12 w=13 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=13 w=14 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=14 w=58 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=58 events=1 2014-02-04 15:50:27.349+0000: 9081: debug : virNetMessageDecodeLength:149 : Got length, now need 88 total (84 more) 2014-02-04 15:50:27.349+0000: 9081: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fac7ce86e60 tx=(nil) 2014-02-04 15:50:27.349+0000: 9081: debug : virNetServerClientCalculateHandleMode:188 : mode=1 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=58 events=1 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollInterruptLocked:713 : Skip interrupt, 1 140378773645312 2014-02-04 15:50:27.349+0000: 9081: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fac7ce86610 len=88 prog=536903814 vers=1 proc=159 type=0 status=0 serial=6 2014-02-04 15:50:27.349+0000: 9081: debug : virKeepAliveCheckMessage:374 : ka=0x7fac7ce86d60, client=0x7fac7ce86610, msg=0x7fac7ce86e60 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=59 frequency=5000 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollUpdateTimeout:278 : Set timer freq=5000 expires=1391529032349 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollInterruptLocked:713 : Skip interrupt, 1 140378773645312 2014-02-04 15:50:27.349+0000: 9081: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac7ce86610 2014-02-04 15:50:27.349+0000: 9081: debug : virNetServerDispatchNewMessage:213 : server=0x7fac7ce77ae0 client=0x7fac7ce86610 message=0x7fac7ce86e60 2014-02-04 15:50:27.349+0000: 9081: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac7ce80030 2014-02-04 15:50:27.349+0000: 9081: debug : virNetMessageNew:44 : msg=0x7fac7ce78c60 tracked=1 2014-02-04 15:50:27.349+0000: 9081: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fac7ce78c60 tx=(nil) 2014-02-04 15:50:27.349+0000: 9081: debug : virNetServerClientCalculateHandleMode:188 : mode=1 2014-02-04 15:50:27.349+0000: 9082: debug : virNetServerHandleJob:184 : server=0x7fac7ce77ae0 client=0x7fac7ce86610 message=0x7fac7ce86e60 prog=0x7fac7ce80030 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=58 events=1 2014-02-04 15:50:27.349+0000: 9082: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=159 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollInterruptLocked:713 : Skip interrupt, 1 140378773645312 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.349+0000: 9082: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac58000ac0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.349+0000: 9082: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac58000ac0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventRunDefaultImpl:271 : running default event implementation 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.349+0000: 9082: debug : remoteDispatchDomainMemoryStatsHelper:5195 : server=0x7fac7ce77ae0 client=0x7fac7ce86610 msg=0x7fac7ce86e60 rerr=0x7fac6d9b5bc0 args=0x7fac44000900 ret=0x7fac440009a0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.349+0000: 9082: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7fac440008c0 classname=virDomain 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=5 e=1 d=0 2014-02-04 15:50:27.349+0000: 9082: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac340018a0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=7 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=2 w=3, f=12 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=3 w=4, f=13 e=1 d=0 2014-02-04 15:50:27.349+0000: 9082: debug : virDomainMemoryStats:8719 : dom=0x7fac440008c0, (VM: name=bqafm0001ap.frm.meshcore.net, uuid=3b009e73-e095-29fa-8970-f5c2b647d5a0), stats=0x7fac44000af0, nr_stats=8, flags=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=4 w=5, f=14 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=5 w=6, f=15 e=1 d=0 2014-02-04 15:50:27.349+0000: 9082: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac7ce760b0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=6 w=7, f=16 e=1 d=0 2014-02-04 15:50:27.349+0000: 9082: debug : virAccessManagerCheckDomain:231 : manager=0x7fac7ce760b0(name=stack) driver=QEMU domain=0x7fac5c2386c0 perm=1 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=7 w=8, f=17 e=1 d=0 2014-02-04 15:50:27.349+0000: 9082: debug : virAccessManagerCheckDomain:231 : manager=0x7fac7ce776f0(name=none) driver=QEMU domain=0x7fac5c2386c0 perm=1 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=8 w=9, f=19 e=1 d=0 2014-02-04 15:50:27.349+0000: 9082: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac7ce760b0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=9 w=10, f=23 e=25 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=10 w=11, f=25 e=25 d=0 2014-02-04 15:50:27.349+0000: 9082: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac5c209c20 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=11 w=12, f=24 e=25 d=0 2014-02-04 15:50:27.349+0000: 9082: debug : qemuDomainObjBeginJobInternal:1024 : Starting job: query (async=none vm=0x7fac5c2141a0 name=bqafm0001ap.frm.meshcore.net) 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=12 w=13, f=26 e=25 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=13 w=14, f=27 e=1 d=0 2014-02-04 15:50:27.349+0000: 9082: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac5c2141a0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=14 w=58, f=28 e=1 d=0 2014-02-04 15:50:27.349+0000: 9082: debug : qemuDomainObjBeginJobInternal:1066 : Started job: query (async=none vm=0x7fac5c2141a0 name=bqafm0001ap.frm.meshcore.net) 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 5 timers 2014-02-04 15:50:27.349+0000: 9082: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac5c209c20 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529031312 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529032349 2014-02-04 15:50:27.349+0000: 9082: debug : qemuDomainObjEnterMonitorInternal:1252 : Entering monitor (mon=0x7fac54000c00 vm=0x7fac5c2141a0 name=bqafm0001ap.frm.meshcore.net) 2014-02-04 15:50:27.349+0000: 9082: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac54000c00 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1391529031312 now=1391529027349 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:362 : Timeout at 1391529031312 due in 3963 ms 2014-02-04 15:50:27.349+0000: 9082: debug : qemuMonitorGetMemoryStats:1565 : mon=0x7fac54000c00 stats=0x7fac44000af0 nstats=8 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=15 timeout=3963 2014-02-04 15:50:27.349+0000: 9082: debug : qemuMonitorFindBalloonObjectPath:1045 : Searching for Balloon Object Path starting at / 2014-02-04 15:50:27.349+0000: 9082: debug : virJSONValueToString:1106 : object=0x7fac44000a50 2014-02-04 15:50:27.349+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac44000a50 type=0 gen=0x7fac44000d60 2014-02-04 15:50:27.349+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac44000a70 type=2 gen=0x7fac44000d60 2014-02-04 15:50:27.349+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac44000bf0 type=0 gen=0x7fac44000d60 2014-02-04 15:50:27.349+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac44000c10 type=2 gen=0x7fac44000d60 2014-02-04 15:50:27.349+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac44000ce0 type=2 gen=0x7fac44000d60 2014-02-04 15:50:27.349+0000: 9082: debug : virJSONValueToString:1139 : result={"execute":"qom-list","arguments":{"path":"/"},"id":"libvirt-6"} 2014-02-04 15:50:27.349+0000: 9082: debug : qemuMonitorJSONCommandWithFd:264 : Send command '{"execute":"qom-list","arguments":{"path":"/"},"id":"libvirt-6"}' for write with FD -1 2014-02-04 15:50:27.349+0000: 9082: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=15 2014-02-04 15:50:27.349+0000: 9082: debug : virEventPollInterruptLocked:717 : Interrupting 2014-02-04 15:50:27.349+0000: 9082: debug : qemuMonitorSend:959 : QEMU_MONITOR_SEND_MSG: mon=0x7fac54000c00 msg={"execute":"qom-list","arguments":{"path":"/"},"id":"libvirt-6"}
 fd=-1
2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollRunOnce:641 : Poll got 1 event(s) 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchTimeouts:426 : Dispatch 5 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:471 : Dispatch 15 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=0 w=1 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=1 w=2 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=2 w=3 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=3 w=4 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=4 w=5 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=5 w=6 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=6 w=7 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=7 w=8 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=8 w=9 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=9 w=10 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=10 w=11 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=11 w=12 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=12 w=13 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=13 w=14 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=14 w=58 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.349+0000: 9081: debug : virEventRunDefaultImpl:271 : running default event implementation 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=5 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=7 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=2 w=3, f=12 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=3 w=4, f=13 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=4 w=5, f=14 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=5 w=6, f=15 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=6 w=7, f=16 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=7 w=8, f=17 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=8 w=9, f=19 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=9 w=10, f=23 e=29 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=10 w=11, f=25 e=25 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=11 w=12, f=24 e=25 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=12 w=13, f=26 e=25 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=13 w=14, f=27 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=14 w=58, f=28 e=1 d=0 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 5 timers 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529031312 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529032349 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1391529031312 now=1391529027349 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollCalculateTimeout:362 : Timeout at 1391529031312 due in 3963 ms 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=15 timeout=3963 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollRunOnce:641 : Poll got 1 event(s) 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchTimeouts:426 : Dispatch 5 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:471 : Dispatch 15 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=0 w=1 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=1 w=2 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=2 w=3 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=3 w=4 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=4 w=5 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=5 w=6 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=6 w=7 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=7 w=8 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=8 w=9 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=9 w=10 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=2 2014-02-04 15:50:27.349+0000: 9081: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac54000c00 2014-02-04 15:50:27.349+0000: 9081: debug : qemuMonitorIOWrite:504 : QEMU_MONITOR_IO_WRITE: mon=0x7fac54000c00 buf={"execute":"qom-list","arguments":{"path":"/"},"id":"libvirt-6"}
 len=66 ret=66 errno=22
2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=13 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollInterruptLocked:713 : Skip interrupt, 1 140378773645312 2014-02-04 15:50:27.349+0000: 9081: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac54000c00 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=10 w=11 2014-02-04 15:50:27.349+0000: 9081: debug : virEventPollDispatchHandles:485 : i=11 w=12 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=12 w=13 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=13 w=14 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=14 w=58 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.350+0000: 9081: debug : virEventRunDefaultImpl:271 : running default event implementation 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=5 e=1 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=7 e=1 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=2 w=3, f=12 e=1 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=3 w=4, f=13 e=1 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=4 w=5, f=14 e=1 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=5 w=6, f=15 e=1 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=6 w=7, f=16 e=1 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=7 w=8, f=17 e=1 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=8 w=9, f=19 e=1 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=9 w=10, f=23 e=25 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=10 w=11, f=25 e=25 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=11 w=12, f=24 e=25 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=12 w=13, f=26 e=25 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=13 w=14, f=27 e=1 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=14 w=58, f=28 e=1 d=0 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 5 timers 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529031312 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529032349 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1391529031312 now=1391529027350 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollCalculateTimeout:362 : Timeout at 1391529031312 due in 3962 ms 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=15 timeout=3962 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollRunOnce:641 : Poll got 1 event(s) 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchTimeouts:426 : Dispatch 5 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:471 : Dispatch 15 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=0 w=1 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=1 w=2 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=2 w=3 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=3 w=4 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=4 w=5 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=5 w=6 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=6 w=7 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=7 w=8 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=8 w=9 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=9 w=10 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=1 2014-02-04 15:50:27.350+0000: 9081: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fac54000c00 2014-02-04 15:50:27.350+0000: 9081: debug : qemuMonitorIOProcess:396 : QEMU_MONITOR_IO_PROCESS: mon=0x7fac54000c00 buf={"id": "libvirt-6", "error": {"class": "CommandNotFound", "desc": "The command qom-list has not been found", "data": {"name": "qom-list"}}}
 len=141
2014-02-04 15:50:27.350+0000: 9081: debug : qemuMonitorJSONIOProcessLine:157 : Line [{"id": "libvirt-6", "error": {"class": "CommandNotFound", "desc": "The command qom-list has not been found", "data": {"name": "qom-list"}}}] 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONValueFromString:975 : string={"id": "libvirt-6", "error": {"class": "CommandNotFound", "desc": "The command qom-list has not been found", "data": {"name": "qom-list"}}} 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleStartMap:853 : parser=0x7fffd5667f70 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleMapKey:835 : parser=0x7fffd5667f70 key=0x7fac7ce78d42 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleString:815 : parser=0x7fffd5667f70 str=0x7fac7ce78d48 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleMapKey:835 : parser=0x7fffd5667f70 key=0x7fac7ce78d55 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleStartMap:853 : parser=0x7fffd5667f70 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleMapKey:835 : parser=0x7fffd5667f70 key=0x7fac7ce78d5f 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleString:815 : parser=0x7fffd5667f70 str=0x7fac7ce78d68 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleMapKey:835 : parser=0x7fffd5667f70 key=0x7fac7ce78d7b 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleString:815 : parser=0x7fffd5667f70 str=0x7fac7ce78d83 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleMapKey:835 : parser=0x7fffd5667f70 key=0x7fac7ce78dae 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleStartMap:853 : parser=0x7fffd5667f70 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleMapKey:835 : parser=0x7fffd5667f70 key=0x7fac7ce78db7 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleString:815 : parser=0x7fffd5667f70 str=0x7fac7ce78dbf 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd5667f70 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd5667f70 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd5667f70 2014-02-04 15:50:27.350+0000: 9081: debug : virJSONValueFromString:1026 : result=0x7fac7ce6b9a0 2014-02-04 15:50:27.350+0000: 9081: debug : qemuMonitorJSONIOProcessLine:177 : QEMU_MONITOR_RECV_REPLY: mon=0x7fac54000c00 reply={"id": "libvirt-6", "error": {"class": "CommandNotFound", "desc": "The command qom-list has not been found", "data": {"name": "qom-list"}}} 2014-02-04 15:50:27.350+0000: 9081: debug : qemuMonitorJSONIOProcess:226 : Total used 141 bytes out of 141 available in buffer 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=13 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollInterruptLocked:713 : Skip interrupt, 1 140378773645312 2014-02-04 15:50:27.350+0000: 9081: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fac54000c00 2014-02-04 15:50:27.350+0000: 9082: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=13 2014-02-04 15:50:27.350+0000: 9081: debug : virEventPollDispatchHandles:485 : i=10 w=11 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollDispatchHandles:485 : i=11 w=12 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollDispatchHandles:485 : i=12 w=13 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollDispatchHandles:485 : i=13 w=14 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollDispatchHandles:485 : i=14 w=58 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.351+0000: 9081: debug : virEventRunDefaultImpl:271 : running default event implementation 2014-02-04 15:50:27.351+0000: 9082: debug : virEventPollInterruptLocked:713 : Skip interrupt, 0 140378773645312 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollCleanupTimeouts:517 : Cleanup 5 2014-02-04 15:50:27.351+0000: 9082: debug : qemuMonitorJSONCommandWithFd:269 : Receive command reply ret=0 rxObject=0x7fac7ce6b9a0 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollCleanupHandles:565 : Cleanup 15 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToString:1106 : object=0x7fac44000a50 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=5 e=1 d=0 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac44000a50 type=0 gen=0x7fac44001000 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=7 e=1 d=0 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=2 w=3, f=12 e=1 d=0 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac44000a70 type=2 gen=0x7fac44001000 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=3 w=4, f=13 e=1 d=0 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac44000bf0 type=0 gen=0x7fac44001000 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=4 w=5, f=14 e=1 d=0 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac44000c10 type=2 gen=0x7fac44001000 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=5 w=6, f=15 e=1 d=0 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac44000ce0 type=2 gen=0x7fac44001000 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=6 w=7, f=16 e=1 d=0 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToString:1139 : result={"execute":"qom-list","arguments":{"path":"/"},"id":"libvirt-6"} 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=7 w=8, f=17 e=1 d=0 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToString:1106 : object=0x7fac7ce6b9a0 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=8 w=9, f=19 e=1 d=0 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=9 w=10, f=23 e=25 d=0 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac7ce6b9a0 type=0 gen=0x7fac44001000 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=10 w=11, f=25 e=25 d=0 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac7ce6b8a0 type=2 gen=0x7fac44001000 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=11 w=12, f=24 e=25 d=0 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac7ce67660 type=0 gen=0x7fac44001000 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=12 w=13, f=26 e=25 d=0 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac7ce83f30 type=2 gen=0x7fac44001000 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=13 w=14, f=27 e=1 d=0 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac7ce61930 type=2 gen=0x7fac44001000 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollMakePollFDs:394 : Prepare n=14 w=58, f=28 e=1 d=0 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac7ce6ce30 type=0 gen=0x7fac44001000 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 5 timers 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToStringOne:1037 : object=0x7fac7ce80230 type=2 gen=0x7fac44001000 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529031312 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1391529032349 2014-02-04 15:50:27.351+0000: 9082: debug : virJSONValueToString:1139 : result={"id":"libvirt-6","error":{"class":"CommandNotFound","desc":"The command qom-list has not been found","data":{"name":"qom-list"}}} 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1391529031312 now=1391529027351 2014-02-04 15:50:27.351+0000: 9082: debug : qemuMonitorJSONCheckError:343 : unable to execute QEMU command {"execute":"qom-list","arguments":{"path":"/"},"id":"libvirt-6"}: {"id":"libvirt-6","error":{"class":"CommandNotFound","desc":"The command qom-list has not been found","data":{"name":"qom-list"}}} 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollCalculateTimeout:362 : Timeout at 1391529031312 due in 3961 ms 2014-02-04 15:50:27.351+0000: 9081: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=15 timeout=3961 2014-02-04 15:50:27.351+0000: 9082: error : qemuMonitorJSONCheckError:354 : internal error: unable to execute QEMU command 'qom-list': The command qom-list has not been found


     ====== end of log =====

Segmentation fault (core dumped)


[Date Prev][Date Next]   [Thread Prev][Thread Next]   [Thread Index] [Date Index] [Author Index]