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)
--
libvir-list mailing list
libvir-list@xxxxxxxxxx
https://www.redhat.com/mailman/listinfo/libvir-list