[libvirt] 1.2.0 segfault on Centos 6

Franky Van Liedekerke liedekef at telenet.be
Tue Feb 4 16:02:41 UTC 2014


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)




More information about the libvir-list mailing list