Attachment 857889 Details for Bug 1060248 – Log buffer dump after SIGABRT

Log buffer dump after SIGABRT

libvirtd.log (text/x-log), 64.67 KB, created by Florian Haas on 2014-01-31 15:14:12 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Florian Haas

Created: 2014-01-31 15:14:12 UTC

Size: 64.67 KB

patch

obsolete

>Caught abort signal dumping internal log buffer:
>
>
>    ====== start of log =====
>
>-01-29 11:27:30.122+0000: 30421: debug : virConfReadFile:745 : filename=/etc/libvirt/libvirt.conf
>2014-01-29 11:27:30.122+0000: 30408: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=60
>2014-01-29 11:27:30.122+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533eba0 tx=(nil)
>2014-01-29 11:27:30.122+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.122+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=144 events=1
>2014-01-29 11:27:30.122+0000: 30408: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f294c002a70
>2014-01-29 11:27:30.122+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.122+0000: 30408: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f294c002a70
>2014-01-29 11:27:30.122+0000: 30408: debug : remoteDispatchConnectSupportsFeatureHelper:2200 : server=0x7f2a853172b0 client=0x7f2a85343190 msg=0x7f2a8533e190 rerr=0x7f2a39df7c40 args=0x7f2978000db0 ret=0x7f2978001a20
>2014-01-29 11:27:30.122+0000: 30269: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7f2a8533bbb0 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
>2014-01-29 11:27:30.122+0000: 30408: debug : virKeepAliveStart:276 : RPC_KEEPALIVE_START: ka=0x7f2a8533cd80 client=0x7f2a85343190 interval=0 count=0
>2014-01-29 11:27:30.122+0000: 30269: debug : virKeepAliveCheckMessage:374 : ka=0x7f2a85345890, client=0x7f2a8533bbb0, msg=0x7f2a8533eba0
>2014-01-29 11:27:30.122+0000: 30408: debug : virEventPollInterruptLocked:714 : Interrupting
>2014-01-29 11:27:30.122+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a8533bbb0
>2014-01-29 11:27:30.122+0000: 30269: debug : virNetServerDispatchNewMessage:211 : server=0x7f2a853172b0 client=0x7f2a8533bbb0 message=0x7f2a8533eba0
>2014-01-29 11:27:30.122+0000: 30408: debug : virEventPollAddTimeout:248 : EVENT_POLL_ADD_TIMEOUT: timer=75 frequency=5000 cb=0x7f2a82ce1190 opaque=0x7f2a8533cd80 ff=0x7f2a82be6cb0
>2014-01-29 11:27:30.122+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a853382b0
>2014-01-29 11:27:30.122+0000: 30408: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a8533cd80
>2014-01-29 11:27:30.122+0000: 30408: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f294c002a70
>2014-01-29 11:27:30.122+0000: 30408: debug : virNetMessageEncodePayload:373 : Encode length as 32
>2014-01-29 11:27:30.122+0000: 30408: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f294c002a70
>2014-01-29 11:27:30.123+0000: 30408: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7f2a8533e190 proc=60 len=32 offset=0
>2014-01-29 11:27:30.123+0000: 30298: debug : virNetServerHandleJob:182 : server=0x7f2a853172b0 client=0x7f2a8533bbb0 message=0x7f2a8533eba0 prog=0x7f2a853382b0
>2014-01-29 11:27:30.123+0000: 30298: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
>2014-01-29 11:27:30.123+0000: 30408: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7f2a85343190 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
>2014-01-29 11:27:30.123+0000: 30408: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8539a3b0 tx=0x7f2a8533e190
>2014-01-29 11:27:30.123+0000: 30408: debug : virNetServerClientCalculateHandleMode:188 : mode=3
>2014-01-29 11:27:30.123+0000: 30408: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=142 events=3
>2014-01-29 11:27:30.123+0000: 30408: debug : virEventPollInterruptLocked:714 : Interrupting
>2014-01-29 11:27:30.123+0000: 30408: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a853382b0
>2014-01-29 11:27:30.123+0000: 30408: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a85343190
>2014-01-29 11:27:30.123+0000: 30269: debug : virNetMessageNew:44 : msg=0x7f2a8533b170 tracked=1
>2014-01-29 11:27:30.123+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533b170 tx=(nil)
>2014-01-29 11:27:30.123+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=144 events=1
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollCleanupTimeouts:514 : Cleanup 23
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollCleanupHandles:562 : Cleanup 70
>2014-01-29 11:27:30.123+0000: 30298: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f2a14001b10 classname=virIdentity
>2014-01-29 11:27:30.123+0000: 30298: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a14001b10
>2014-01-29 11:27:30.123+0000: 30298: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a14001b10
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventRunDefaultImpl:270 : running default event implementation
>2014-01-29 11:27:30.123+0000: 30298: debug : remoteDispatchAuthListHelper:18 : server=0x7f2a853172b0 client=0x7f2a8533bbb0 msg=0x7f2a8533eba0 rerr=0x7f2a70e65c40 args=0x7f2a14001100 ret=0x7f2a14000ef0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollCleanupTimeouts:514 : Cleanup 23
>2014-01-29 11:27:30.123+0000: 30298: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a14001b10
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollCleanupHandles:562 : Cleanup 70
>2014-01-29 11:27:30.123+0000: 30298: debug : virNetMessageEncodePayload:373 : Encode length as 36
>2014-01-29 11:27:30.123+0000: 30298: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a14001b10
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-01-29 11:27:30.123+0000: 30298: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7f2a8533eba0 proc=66 len=36 offset=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-01-29 11:27:30.123+0000: 30298: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7f2a8533bbb0 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-01-29 11:27:30.123+0000: 30298: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533b170 tx=0x7f2a8533eba0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=14 e=1 d=0
>2014-01-29 11:27:30.123+0000: 30298: debug : virNetServerClientCalculateHandleMode:188 : mode=3
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=6 e=1 d=0
>2014-01-29 11:27:30.123+0000: 30298: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=144 events=3
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=0 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : virFileClose:90 : Closed fd 88
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=15 e=1 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=10, f=18 e=1 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=11 w=15, f=23 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=12 w=16, f=25 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : do_open:1169 : name "qemu:///system" to URI components:
>  scheme qemu
>  server (null)
>  user (null)
>  port 0
>  path /system
>
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=13 w=17, f=26 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : do_open:1215 : trying driver 0 (Test) ...
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=14 w=18, f=27 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : do_open:1222 : driver 0 Test returned DECLINED
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=15 w=19, f=24 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : do_open:1215 : trying driver 1 (OPENVZ) ...
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=16 w=20, f=28 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : do_open:1222 : driver 1 OPENVZ returned DECLINED
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=17 w=21, f=29 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : do_open:1215 : trying driver 2 (VMWARE) ...
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=18 w=22, f=30 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : do_open:1222 : driver 2 VMWARE returned DECLINED
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=19 w=23, f=32 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : do_open:1215 : trying driver 3 (remote) ...
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=20 w=24, f=33 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : do_open:1222 : driver 3 remote returned DECLINED
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=21 w=25, f=31 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : do_open:1215 : trying driver 4 (Xen) ...
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=22 w=26, f=34 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : do_open:1222 : driver 4 Xen returned DECLINED
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=23 w=27, f=35 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : do_open:1215 : trying driver 5 (QEMU) ...
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=24 w=28, f=38 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a280dc7b0
>2014-01-29 11:27:30.123+0000: 30421: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a85321a70
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=25 w=29, f=39 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=26 w=30, f=37 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : virAccessManagerCheckConnect:215 : manager=0x7f2a85321a70(name=stack) driver=QEMU perm=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=27 w=31, f=40 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=28 w=32, f=36 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=29 w=33, f=41 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=30 w=34, f=44 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=31 w=35, f=54 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=32 w=36, f=49 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=33 w=37, f=43 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=34 w=38, f=46 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=35 w=39, f=48 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=36 w=40, f=50 e=25 d=0
>2014-01-29 11:27:30.123+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=37 w=41, f=52 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=38 w=42, f=42 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=39 w=43, f=51 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=40 w=44, f=45 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=41 w=45, f=47 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=42 w=46, f=53 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=43 w=47, f=56 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=44 w=48, f=57 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=45 w=49, f=58 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=46 w=50, f=63 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=47 w=51, f=59 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=48 w=52, f=61 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=49 w=53, f=60 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=50 w=54, f=62 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=51 w=55, f=55 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=52 w=56, f=64 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=53 w=57, f=67 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=54 w=58, f=69 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=55 w=59, f=65 e=25 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=56 w=106, f=20 e=1 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=57 w=133, f=68 e=4 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=58 w=134, f=21 e=4 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=59 w=135, f=71 e=5 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=60 w=136, f=75 e=1 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=61 w=137, f=115 e=1 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=62 w=138, f=78 e=1 d=0
>2014-01-29 11:27:30.123+0000: 30421: debug : virAccessManagerCheckConnect:215 : manager=0x7f2a85316eb0(name=none) driver=QEMU perm=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=63 w=139, f=83 e=1 d=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=64 w=140, f=72 e=1 d=0
>2014-01-29 11:27:30.124+0000: 30421: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a85321a70
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=65 w=141, f=87 e=1 d=0
>2014-01-29 11:27:30.124+0000: 30421: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a280dc7b0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=66 w=142, f=77 e=5 d=0
>2014-01-29 11:27:30.124+0000: 30421: debug : do_open:1222 : driver 5 QEMU returned SUCCESS
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=67 w=143, f=66 e=5 d=0
>2014-01-29 11:27:30.124+0000: 30421: debug : do_open:1248 : network driver 0 Test returned DECLINED
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=68 w=144, f=79 e=1 d=0
>2014-01-29 11:27:30.124+0000: 30421: debug : do_open:1248 : network driver 1 remote returned DECLINED
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollMakePollFDs:391 : Prepare n=69 w=145, f=86 e=1 d=0
>2014-01-29 11:27:30.124+0000: 30421: debug : do_open:1248 : network driver 2 Network returned SUCCESS
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 23 timers
>2014-01-29 11:27:30.124+0000: 30421: debug : do_open:1264 : interface driver 0 Test returned DECLINED
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1390994855120
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1390994855120
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1390994855121
>2014-01-29 11:27:30.124+0000: 30421: debug : do_open:1264 : interface driver 1 remote returned DECLINED
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1390994855116
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1390994855117
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1390994855075
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1390994855117
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1390994855122
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1390994855118
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1390994855122
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1390994855075 now=1390994850124
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollCalculateTimeout:361 : Timeout at 1390994855075 due in 4951 ms
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=69 timeout=4951
>2014-01-29 11:27:30.124+0000: 30298: debug : virEventPollInterruptLocked:714 : Interrupting
>2014-01-29 11:27:30.124+0000: 30298: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a853382b0
>2014-01-29 11:27:30.124+0000: 30298: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8533bbb0
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollRunOnce:638 : Poll got 12 event(s)
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollDispatchTimeouts:423 : Dispatch 23
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollDispatchHandles:468 : Dispatch 69
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=5 events=1
>2014-01-29 11:27:30.124+0000: 30269: debug : virNetSocketNew:155 : localAddr=0x7fff2d0ec450 remoteAddr=0x7fff2d0ec4e0 fd=88 errfd=-1 pid=0
>2014-01-29 11:27:30.124+0000: 30269: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f2a85342720 classname=virNetSocket
>2014-01-29 11:27:30.124+0000: 30269: debug : virNetSocketNew:205 : RPC_SOCKET_NEW: sock=0x7f2a85342720 fd=88 errfd=-1 pid=0 localAddr=127.0.0.1;16509, remoteAddr=127.0.0.1;55013
>2014-01-29 11:27:30.124+0000: 30269: debug : virNetServerClientNew:414 : sock=0x7f2a85342720 auth=0 tls=(nil)
>2014-01-29 11:27:30.124+0000: 30269: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f2a8533e640 classname=virNetServerClient
>2014-01-29 11:27:30.124+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a85342720
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollAddTimeout:248 : EVENT_POLL_ADD_TIMEOUT: timer=76 frequency=-1 cb=0x7f2a82cdccc0 opaque=0x7f2a8533e640 ff=(nil)
>2014-01-29 11:27:30.124+0000: 30269: debug : virNetMessageNew:44 : msg=0x7f2a8533af20 tracked=1
>2014-01-29 11:27:30.124+0000: 30269: debug : virNetServerClientNewInternal:384 : RPC_SERVER_CLIENT_NEW: client=0x7f2a8533e640 sock=0x7f2a85342720
>2014-01-29 11:27:30.124+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533af20 tx=(nil)
>2014-01-29 11:27:30.124+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.124+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a8533e640
>2014-01-29 11:27:30.124+0000: 30269: debug : virNetServerClientRegisterEvent:204 : Registering client event callback 1
>2014-01-29 11:27:30.124+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a85342720
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.124+0000: 30269: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=146 fd=88 events=1 cb=0x7f2a82ce4930 opaque=0x7f2a85342720 ff=0x7f2a82ce49c0
>2014-01-29 11:27:30.124+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a8533e640
>2014-01-29 11:27:30.124+0000: 30269: debug : virKeepAliveNew:196 : client=0x7f2a8533e640, interval=5, count=5
>2014-01-29 11:27:30.124+0000: 30269: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f2a85342b70 classname=virKeepAlive
>2014-01-29 11:27:30.124+0000: 30269: debug : virKeepAliveNew:215 : RPC_KEEPALIVE_NEW: ka=0x7f2a85342b70 client=0x7f2a8533e640
>2014-01-29 11:27:30.124+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a8533e640
>2014-01-29 11:27:30.124+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8533e640
>2014-01-29 11:27:30.125+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a85342720
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=6 w=7
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=9 w=10
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=11 w=15
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=12 w=16
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=13 w=17
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=14 w=18
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=15 w=19
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=16 w=20
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=17 w=21
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=18 w=22
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=19 w=23
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=20 w=24
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=21 w=25
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=22 w=26
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=23 w=27
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=24 w=28
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=25 w=29
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=26 w=30
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=27 w=31
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=28 w=32
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=29 w=33
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=30 w=34
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=31 w=35
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=32 w=36
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=33 w=37
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=34 w=38
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=35 w=39
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=36 w=40
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=37 w=41
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=38 w=42
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=39 w=43
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=40 w=44
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=41 w=45
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=42 w=46
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=43 w=47
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=44 w=48
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=45 w=49
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=46 w=50
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=47 w=51
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=48 w=52
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=49 w=53
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=50 w=54
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=51 w=55
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=52 w=56
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=53 w=57
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=54 w=58
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=55 w=59
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=56 w=106
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=57 w=133
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=133 events=2
>2014-01-29 11:27:30.125+0000: 30269: debug : virNetMessageFree:72 : msg=0x7f2a85398f90 nfds=0 cb=(nil)
>2014-01-29 11:27:30.125+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533ca30 tx=(nil)
>2014-01-29 11:27:30.125+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=0
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=133 events=0
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=58 w=134
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=134 events=2
>2014-01-29 11:27:30.125+0000: 30269: debug : virNetMessageFree:72 : msg=0x7f2a8533e960 nfds=0 cb=(nil)
>2014-01-29 11:27:30.125+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533b8b0 tx=(nil)
>2014-01-29 11:27:30.125+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=0
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=134 events=0
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=59 w=135
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=135 events=2
>2014-01-29 11:27:30.125+0000: 30269: debug : virNetMessageFree:72 : msg=0x7f2a8533cf60 nfds=0 cb=(nil)
>2014-01-29 11:27:30.125+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a853475e0 tx=(nil)
>2014-01-29 11:27:30.125+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=135 events=1
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:482 : i=60 w=136
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=136 events=1
>2014-01-29 11:27:30.125+0000: 30269: debug : virNetMessageDecodeLength:149 : Got length, now need 68 total (64 more)
>2014-01-29 11:27:30.125+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8539c3a0 tx=(nil)
>2014-01-29 11:27:30.125+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=136 events=1
>2014-01-29 11:27:30.125+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.125+0000: 30269: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7f2a8533f090 len=68 prog=536903814 vers=1 proc=212 type=0 status=0 serial=4
>2014-01-29 11:27:30.125+0000: 30269: debug : virKeepAliveCheckMessage:374 : ka=0x7f2a8533ce90, client=0x7f2a8533f090, msg=0x7f2a8539c3a0
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=62 frequency=5000
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollUpdateTimeout:278 : Set timer freq=5000 expires=1390994855126
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.126+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a8533f090
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerDispatchNewMessage:211 : server=0x7f2a853172b0 client=0x7f2a8533f090 message=0x7f2a8539c3a0
>2014-01-29 11:27:30.126+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a853382b0
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetMessageNew:44 : msg=0x7f2a85342a90 tracked=1
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a85342a90 tx=(nil)
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=136 events=1
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollDispatchHandles:482 : i=61 w=137
>2014-01-29 11:27:30.126+0000: 30403: debug : virNetServerHandleJob:182 : server=0x7f2a853172b0 client=0x7f2a8533f090 message=0x7f2a8539c3a0 prog=0x7f2a853382b0
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=137 events=1
>2014-01-29 11:27:30.126+0000: 30403: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=212
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetMessageDecodeLength:149 : Got length, now need 68 total (64 more)
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533b1e0 tx=(nil)
>2014-01-29 11:27:30.126+0000: 30403: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a04001590
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.126+0000: 30403: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a04001590
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=137 events=1
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.126+0000: 30403: debug : remoteDispatchDomainGetStateHelper:4296 : server=0x7f2a853172b0 client=0x7f2a8533f090 msg=0x7f2a8539c3a0 rerr=0x7f2a3c5fcc40 args=0x7f29b80f6940 ret=0x7f29b8104ae0
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7f2a85340e50 len=68 prog=536903814 vers=1 proc=212 type=0 status=0 serial=4
>2014-01-29 11:27:30.126+0000: 30403: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f29b80f6900 classname=virDomain
>2014-01-29 11:27:30.126+0000: 30269: debug : virKeepAliveCheckMessage:374 : ka=0x7f2a8533ea20, client=0x7f2a85340e50, msg=0x7f2a8533b1e0
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=63 frequency=5000
>2014-01-29 11:27:30.126+0000: 30403: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a18001900
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollUpdateTimeout:278 : Set timer freq=5000 expires=1390994855126
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.126+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a85340e50
>2014-01-29 11:27:30.126+0000: 30403: debug : virDomainGetState:4447 : dom=0x7f29b80f6900, (VM: name=ssd-vms_76, uuid=fa589668-6a16-444c-973a-cb8fd59cf02d), state=0x7f29b8104ae0, reason=0x7f29b8104ae4, flags=0
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerDispatchNewMessage:211 : server=0x7f2a853172b0 client=0x7f2a85340e50 message=0x7f2a8533b1e0
>2014-01-29 11:27:30.126+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a853382b0
>2014-01-29 11:27:30.126+0000: 30403: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a85321a70
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetMessageNew:44 : msg=0x7f2a8533eb30 tracked=1
>2014-01-29 11:27:30.126+0000: 30403: debug : virAccessManagerCheckDomain:231 : manager=0x7f2a85321a70(name=stack) driver=QEMU domain=0x7f2a2806de70 perm=1
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533eb30 tx=(nil)
>2014-01-29 11:27:30.126+0000: 30403: debug : virAccessManagerCheckDomain:231 : manager=0x7f2a85316eb0(name=none) driver=QEMU domain=0x7f2a2806de70 perm=1
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.126+0000: 30403: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a85321a70
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=137 events=1
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.126+0000: 30403: debug : virDomainFree:2406 : dom=0x7f29b80f6900, (VM: name=ssd-vms_76, uuid=fa589668-6a16-444c-973a-cb8fd59cf02d)
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollDispatchHandles:482 : i=62 w=138
>2014-01-29 11:27:30.126+0000: 30403: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f29b80f6900
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=138 events=1
>2014-01-29 11:27:30.126+0000: 30403: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7f29b80f6900
>2014-01-29 11:27:30.126+0000: 30300: debug : virNetServerHandleJob:182 : server=0x7f2a853172b0 client=0x7f2a85340e50 message=0x7f2a8533b1e0 prog=0x7f2a853382b0
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetMessageDecodeLength:149 : Got length, now need 44 total (40 more)
>2014-01-29 11:27:30.126+0000: 30403: debug : virDomainDispose:262 : release domain 0x7f29b80f6900 ssd-vms_76 fa589668-6a16-444c-973a-cb8fd59cf02d
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a85341410 tx=(nil)
>2014-01-29 11:27:30.126+0000: 30403: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a18001900
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.126+0000: 30403: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a04001590
>2014-01-29 11:27:30.126+0000: 30300: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=212
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=138 events=1
>2014-01-29 11:27:30.126+0000: 30300: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f295c000d80
>2014-01-29 11:27:30.126+0000: 30300: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f295c000d80
>2014-01-29 11:27:30.126+0000: 30403: debug : virNetMessageEncodePayload:373 : Encode length as 36
>2014-01-29 11:27:30.126+0000: 30300: debug : remoteDispatchDomainGetStateHelper:4296 : server=0x7f2a853172b0 client=0x7f2a85340e50 msg=0x7f2a8533b1e0 rerr=0x7f2a6fe63c40 args=0x7f29b0001260 ret=0x7f29b00011e0
>2014-01-29 11:27:30.126+0000: 30403: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a04001590
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.126+0000: 30403: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7f2a8539c3a0 proc=212 len=36 offset=0
>2014-01-29 11:27:30.126+0000: 30300: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f29b0001220 classname=virDomain
>2014-01-29 11:27:30.126+0000: 30300: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f29d4001840
>2014-01-29 11:27:30.126+0000: 30403: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7f2a8533f090 len=36 prog=536903814 vers=1 proc=212 type=1 status=0 serial=4
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7f2a85345f40 len=44 prog=536903814 vers=1 proc=23 type=0 status=0 serial=3
>2014-01-29 11:27:30.126+0000: 30269: debug : virKeepAliveCheckMessage:374 : ka=0x7f2a853437f0, client=0x7f2a85345f40, msg=0x7f2a85341410
>2014-01-29 11:27:30.126+0000: 30300: debug : virDomainGetState:4447 : dom=0x7f29b0001220, (VM: name=ssd-vms_8, uuid=84727463-8a02-4c67-b048-c502164eaba5), state=0x7f29b00011e0, reason=0x7f29b00011e4, flags=0
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=66 frequency=5000
>2014-01-29 11:27:30.126+0000: 30300: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a85321a70
>2014-01-29 11:27:30.126+0000: 30403: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a85342a90 tx=0x7f2a8539c3a0
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollUpdateTimeout:278 : Set timer freq=5000 expires=1390994855126
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.126+0000: 30403: debug : virNetServerClientCalculateHandleMode:188 : mode=3
>2014-01-29 11:27:30.126+0000: 30300: debug : virAccessManagerCheckDomain:231 : manager=0x7f2a85321a70(name=stack) driver=QEMU domain=0x7f2a28099ff0 perm=1
>2014-01-29 11:27:30.126+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a85345f40
>2014-01-29 11:27:30.126+0000: 30300: debug : virAccessManagerCheckDomain:231 : manager=0x7f2a85316eb0(name=none) driver=QEMU domain=0x7f2a28099ff0 perm=1
>2014-01-29 11:27:30.126+0000: 30403: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=136 events=3
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerDispatchNewMessage:211 : server=0x7f2a853172b0 client=0x7f2a85345f40 message=0x7f2a85341410
>2014-01-29 11:27:30.126+0000: 30300: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a85321a70
>2014-01-29 11:27:30.126+0000: 30403: debug : virEventPollInterruptLocked:714 : Interrupting
>2014-01-29 11:27:30.126+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a853382b0
>2014-01-29 11:27:30.126+0000: 30300: debug : virDomainFree:2406 : dom=0x7f29b0001220, (VM: name=ssd-vms_8, uuid=84727463-8a02-4c67-b048-c502164eaba5)
>2014-01-29 11:27:30.126+0000: 30403: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a853382b0
>2014-01-29 11:27:30.126+0000: 30300: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f29b0001220
>2014-01-29 11:27:30.126+0000: 30403: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8533f090
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetMessageNew:44 : msg=0x7f2a8533d980 tracked=1
>2014-01-29 11:27:30.126+0000: 30300: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7f29b0001220
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533d980 tx=(nil)
>2014-01-29 11:27:30.126+0000: 30300: debug : virDomainDispose:262 : release domain 0x7f29b0001220 ssd-vms_8 84727463-8a02-4c67-b048-c502164eaba5
>2014-01-29 11:27:30.126+0000: 30300: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f29d4001840
>2014-01-29 11:27:30.126+0000: 30300: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f295c000d80
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.126+0000: 30301: debug : virNetServerHandleJob:182 : server=0x7f2a853172b0 client=0x7f2a85345f40 message=0x7f2a85341410 prog=0x7f2a853382b0
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=138 events=1
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.126+0000: 30300: debug : virNetMessageEncodePayload:373 : Encode length as 36
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollDispatchHandles:482 : i=63 w=139
>2014-01-29 11:27:30.126+0000: 30301: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=23
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollDispatchHandles:482 : i=64 w=140
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollDispatchHandles:482 : i=65 w=141
>2014-01-29 11:27:30.126+0000: 30301: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f295c0019f0
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=141 events=1
>2014-01-29 11:27:30.126+0000: 30301: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f295c0019f0
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetMessageDecodeLength:149 : Got length, now need 56 total (52 more)
>2014-01-29 11:27:30.126+0000: 30301: debug : remoteDispatchDomainLookupByNameHelper:4943 : server=0x7f2a853172b0 client=0x7f2a85345f40 msg=0x7f2a85341410 rerr=0x7f2a6f662c40 args=0x7f292c000900 ret=0x7f292c0009a0
>2014-01-29 11:27:30.126+0000: 30300: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f295c000d80
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533ab30 tx=(nil)
>2014-01-29 11:27:30.126+0000: 30301: debug : virDomainLookupByName:2242 : conn=0x7f2a00001980, name=ssd-vms_88
>2014-01-29 11:27:30.126+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.126+0000: 30300: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7f2a8533b1e0 proc=212 len=36 offset=0
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=141 events=1
>2014-01-29 11:27:30.126+0000: 30300: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7f2a85340e50 len=36 prog=536903814 vers=1 proc=212 type=1 status=0 serial=4
>2014-01-29 11:27:30.126+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.126+0000: 30300: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533eb30 tx=0x7f2a8533b1e0
>2014-01-29 11:27:30.127+0000: 30300: debug : virNetServerClientCalculateHandleMode:188 : mode=3
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7f2a8533f190 len=56 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
>2014-01-29 11:27:30.127+0000: 30300: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=137 events=3
>2014-01-29 11:27:30.127+0000: 30269: debug : virKeepAliveCheckMessage:374 : ka=0x7f2a8533e200, client=0x7f2a8533f190, msg=0x7f2a8533ab30
>2014-01-29 11:27:30.127+0000: 30300: debug : virEventPollInterruptLocked:714 : Interrupting
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=73 frequency=5000
>2014-01-29 11:27:30.127+0000: 30300: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a853382b0
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollUpdateTimeout:278 : Set timer freq=5000 expires=1390994855127
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.127+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a8533f190
>2014-01-29 11:27:30.127+0000: 30300: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a85340e50
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerDispatchNewMessage:211 : server=0x7f2a853172b0 client=0x7f2a8533f190 message=0x7f2a8533ab30
>2014-01-29 11:27:30.127+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a853382b0
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetMessageNew:44 : msg=0x7f2a853457b0 tracked=1
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a853457b0 tx=(nil)
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=141 events=1
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollDispatchHandles:482 : i=66 w=142
>2014-01-29 11:27:30.127+0000: 30303: debug : virNetServerHandleJob:182 : server=0x7f2a853172b0 client=0x7f2a8533f190 message=0x7f2a8533ab30 prog=0x7f2a853382b0
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=142 events=2
>2014-01-29 11:27:30.127+0000: 30303: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=1
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetMessageFree:72 : msg=0x7f2a8533e190 nfds=0 cb=(nil)
>2014-01-29 11:27:30.127+0000: 30303: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f29cc001b10
>2014-01-29 11:27:30.127+0000: 30303: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f29cc001b10
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8539a3b0 tx=(nil)
>2014-01-29 11:27:30.127+0000: 30303: debug : remoteDispatchConnectOpenHelper:2178 : server=0x7f2a853172b0 client=0x7f2a8533f190 msg=0x7f2a8533ab30 rerr=0x7f2a6e660c40 args=0x7f2930000900 ret=0x7f29300009a0
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.127+0000: 30301: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a85321a70
>2014-01-29 11:27:30.127+0000: 30303: debug : remoteDispatchConnectOpen:737 : priv=0x7f2a8539ab40 conn=(nil)
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=142 events=1
>2014-01-29 11:27:30.127+0000: 30301: debug : virAccessManagerCheckDomain:231 : manager=0x7f2a85321a70(name=stack) driver=QEMU domain=0x7f2a2809d850 perm=0
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.127+0000: 30303: debug : virConnectOpen:1383 : name=qemu:///system
>2014-01-29 11:27:30.127+0000: 30301: debug : virAccessManagerCheckDomain:231 : manager=0x7f2a85316eb0(name=none) driver=QEMU domain=0x7f2a2809d850 perm=0
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollDispatchHandles:482 : i=67 w=143
>2014-01-29 11:27:30.127+0000: 30303: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f2930000a80 classname=virConnect
>2014-01-29 11:27:30.127+0000: 30301: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a85321a70
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=143 events=2
>2014-01-29 11:27:30.127+0000: 30303: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f2930000c90 classname=virConnectCloseCallbackData
>2014-01-29 11:27:30.127+0000: 30301: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f292c0008c0 classname=virDomain
>2014-01-29 11:27:30.127+0000: 30301: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a00001980
>2014-01-29 11:27:30.127+0000: 30303: debug : virConnectGetConfigFile:998 : Loading config file '/etc/libvirt/libvirt.conf'
>2014-01-29 11:27:30.127+0000: 30303: debug : virConfReadFile:745 : filename=/etc/libvirt/libvirt.conf
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetMessageFree:72 : msg=0x7f2a853239f0 nfds=0 cb=(nil)
>2014-01-29 11:27:30.127+0000: 30301: debug : virDomainFree:2406 : dom=0x7f292c0008c0, (VM: name=ssd-vms_88, uuid=bf2ed1cd-c41f-4b17-bba2-3508f928973d)
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a85340de0 tx=(nil)
>2014-01-29 11:27:30.127+0000: 30301: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f292c0008c0
>2014-01-29 11:27:30.127+0000: 30301: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7f292c0008c0
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=143 events=1
>2014-01-29 11:27:30.127+0000: 30303: debug : virFileClose:90 : Closed fd 85
>2014-01-29 11:27:30.127+0000: 30301: debug : virDomainDispose:262 : release domain 0x7f292c0008c0 ssd-vms_88 bf2ed1cd-c41f-4b17-bba2-3508f928973d
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.127+0000: 30301: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a00001980
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollDispatchHandles:482 : i=68 w=144
>2014-01-29 11:27:30.127+0000: 30301: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f295c0019f0
>2014-01-29 11:27:30.127+0000: 30301: debug : virNetMessageEncodePayload:373 : Encode length as 64
>2014-01-29 11:27:30.127+0000: 30301: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f295c0019f0
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1169 : name "qemu:///system" to URI components:
>  scheme qemu
>  server (null)
>  user (null)
>  port 0
>  path /system
>
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollDispatchHandles:482 : i=69 w=145
>2014-01-29 11:27:30.127+0000: 30301: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7f2a85341410 proc=23 len=64 offset=0
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1215 : trying driver 0 (Test) ...
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=145 events=1
>2014-01-29 11:27:30.127+0000: 30301: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7f2a85345f40 len=64 prog=536903814 vers=1 proc=23 type=1 status=0 serial=3
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1222 : driver 0 Test returned DECLINED
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1215 : trying driver 1 (OPENVZ) ...
>2014-01-29 11:27:30.127+0000: 30301: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533d980 tx=0x7f2a85341410
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1222 : driver 1 OPENVZ returned DECLINED
>2014-01-29 11:27:30.127+0000: 30301: debug : virNetServerClientCalculateHandleMode:188 : mode=3
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1215 : trying driver 2 (VMWARE) ...
>2014-01-29 11:27:30.127+0000: 30301: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=138 events=3
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1222 : driver 2 VMWARE returned DECLINED
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1215 : trying driver 3 (remote) ...
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a85345540 tx=(nil)
>2014-01-29 11:27:30.127+0000: 30301: debug : virEventPollInterruptLocked:714 : Interrupting
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1222 : driver 3 remote returned DECLINED
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1215 : trying driver 4 (Xen) ...
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.127+0000: 30301: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a853382b0
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1222 : driver 4 Xen returned DECLINED
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=145 events=1
>2014-01-29 11:27:30.127+0000: 30301: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a85345f40
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1215 : trying driver 5 (QEMU) ...
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.127+0000: 30303: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a280dc7b0
>2014-01-29 11:27:30.127+0000: 30303: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a85321a70
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7f2a8533a970 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
>2014-01-29 11:27:30.127+0000: 30303: debug : virAccessManagerCheckConnect:215 : manager=0x7f2a85321a70(name=stack) driver=QEMU perm=0
>2014-01-29 11:27:30.127+0000: 30269: debug : virKeepAliveCheckMessage:374 : ka=0x7f2a8539c690, client=0x7f2a8533a970, msg=0x7f2a85345540
>2014-01-29 11:27:30.127+0000: 30303: debug : virAccessManagerCheckConnect:215 : manager=0x7f2a85316eb0(name=none) driver=QEMU perm=0
>2014-01-29 11:27:30.127+0000: 30303: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a85321a70
>2014-01-29 11:27:30.127+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a8533a970
>2014-01-29 11:27:30.127+0000: 30303: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a280dc7b0
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerDispatchNewMessage:211 : server=0x7f2a853172b0 client=0x7f2a8533a970 message=0x7f2a85345540
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1222 : driver 5 QEMU returned SUCCESS
>2014-01-29 11:27:30.127+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a853382b0
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1248 : network driver 0 Test returned DECLINED
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1248 : network driver 1 remote returned DECLINED
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1248 : network driver 2 Network returned SUCCESS
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1264 : interface driver 0 Test returned DECLINED
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetMessageNew:44 : msg=0x7f2a8533b250 tracked=1
>2014-01-29 11:27:30.127+0000: 30303: debug : do_open:1264 : interface driver 1 remote returned DECLINED
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533b250 tx=(nil)
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=145 events=1
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 139820569249792
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollCleanupTimeouts:514 : Cleanup 24
>2014-01-29 11:27:30.127+0000: 30402: debug : virNetServerHandleJob:182 : server=0x7f2a853172b0 client=0x7f2a8533a970 message=0x7f2a85345540 prog=0x7f2a853382b0
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollCleanupHandles:562 : Cleanup 71
>2014-01-29 11:27:30.127+0000: 30269: debug : virNetServerClientClose:941 : client=0x7f2a8533c170
>2014-01-29 11:27:30.127+0000: 30402: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
>2014-01-29 11:27:30.127+0000: 30269: debug : virKeepAliveStop:307 : RPC_KEEPALIVE_STOP: ka=0x7f2a8539c410 client=0x7f2a8533c170
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollRemoveTimeout:300 : EVENT_POLL_REMOVE_TIMEOUT: timer=57
>2014-01-29 11:27:30.127+0000: 30402: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f2a04001000 classname=virIdentity
>2014-01-29 11:27:30.127+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 0 139820569249792
>2014-01-29 11:27:30.127+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a8533c170
>2014-01-29 11:27:30.127+0000: 30402: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a04001000
>2014-01-29 11:27:30.127+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8539c410
>2014-01-29 11:27:30.127+0000: 30402: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a04001000
>2014-01-29 11:27:30.127+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8533c170
>2014-01-29 11:27:30.127+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a8533c170
>2014-01-29 11:27:30.127+0000: 30402: debug : remoteDispatchAuthListHelper:18 : server=0x7f2a853172b0 client=0x7f2a8533a970 msg=0x7f2a85345540 rerr=0x7f2a3cdfdc40 args=0x7f2a04000dd0 ret=0x7f2a04001290
>2014-01-29 11:27:30.127+0000: 30269: debug : daemonRemoveAllClientStreams:486 : stream=(nil)
>2014-01-29 11:27:30.127+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8533c170
>2014-01-29 11:27:30.127+0000: 30402: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a04001000
>2014-01-29 11:27:30.128+0000: 30402: debug : virNetMessageEncodePayload:373 : Encode length as 36
>2014-01-29 11:27:30.128+0000: 30402: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a04001000
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventPollRemoveHandle:180 : EVENT_POLL_REMOVE_HANDLE: watch=133
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventPollRemoveHandle:193 : mark delete 57 68
>2014-01-29 11:27:30.128+0000: 30402: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7f2a85345540 proc=66 len=36 offset=0
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 0 139820569249792
>2014-01-29 11:27:30.128+0000: 30269: debug : virNetMessageFree:72 : msg=0x7f2a8533ca30 nfds=0 cb=(nil)
>2014-01-29 11:27:30.128+0000: 30402: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7f2a8533a970 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8533c520
>2014-01-29 11:27:30.128+0000: 30402: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7f2a8533b250 tx=0x7f2a85345540
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8533c170
>2014-01-29 11:27:30.128+0000: 30402: debug : virNetServerClientCalculateHandleMode:188 : mode=3
>2014-01-29 11:27:30.128+0000: 30269: debug : virNetServerClientClose:941 : client=0x7f2a85344910
>2014-01-29 11:27:30.128+0000: 30402: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=145 events=3
>2014-01-29 11:27:30.128+0000: 30269: debug : virKeepAliveStop:307 : RPC_KEEPALIVE_STOP: ka=0x7f2a8533f370 client=0x7f2a85344910
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventPollRemoveTimeout:300 : EVENT_POLL_REMOVE_TIMEOUT: timer=58
>2014-01-29 11:27:30.128+0000: 30402: debug : virEventPollInterruptLocked:710 : Skip interrupt, 0 139820569249792
>2014-01-29 11:27:30.128+0000: 30402: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a853382b0
>2014-01-29 11:27:30.128+0000: 30402: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8533a970
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 0 139820569249792
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a85344910
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8533f370
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a85344910
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a85344910
>2014-01-29 11:27:30.128+0000: 30269: debug : daemonRemoveAllClientStreams:486 : stream=(nil)
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a85344910
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventPollRemoveHandle:180 : EVENT_POLL_REMOVE_HANDLE: watch=134
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventPollRemoveHandle:193 : mark delete 58 21
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventPollInterruptLocked:710 : Skip interrupt, 0 139820569249792
>2014-01-29 11:27:30.128+0000: 30269: debug : virNetMessageFree:72 : msg=0x7f2a8533b8b0 nfds=0 cb=(nil)
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8539a1e0
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a85344910
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventRunDefaultImpl:270 : running default event implementation
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventPollCleanupTimeouts:514 : Cleanup 24
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventPollCleanupTimeouts:527 : EVENT_POLL_PURGE_TIMEOUT: timer=57
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8539c410
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7f2a8539c410
>2014-01-29 11:27:30.128+0000: 30269: debug : virKeepAliveDispose:227 : RPC_KEEPALIVE_DISPOSE: ka=0x7f2a8539c410
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8533c170
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventPollCleanupTimeouts:527 : EVENT_POLL_PURGE_TIMEOUT: timer=58
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8533f370
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7f2a8533f370
>2014-01-29 11:27:30.128+0000: 30269: debug : virKeepAliveDispose:227 : RPC_KEEPALIVE_DISPOSE: ka=0x7f2a8533f370
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a85344910
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventPollCleanupHandles:562 : Cleanup 71
>2014-01-29 11:27:30.128+0000: 30269: debug : virEventPollCleanupHandles:575 : EVENT_POLL_PURGE_HANDLE: watch=133
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f2a8533c170
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7f2a8533c170
>2014-01-29 11:27:30.128+0000: 30269: debug : virNetServerClientDispose:905 : RPC_SERVER_CLIENT_DISPOSE: client=0x7f2a8533c170
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f29f00020a0
>2014-01-29 11:27:30.128+0000: 30269: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7f29f00020a0
>2014-01-29 11:27:30.128+0000: 30269: debug : virFileClose:90 : Closed fd 91
>2014-01-29 11:27:30.129+0000: 30269: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f2a8533bca0 classname=virIdentity
>2014-01-29 11:27:30.129+0000: 30269: debug : virIdentitySetAttr:282 : ident=0x7f2a8533bca0 attribute=0 value=root
>2014-01-29 11:27:30.129+0000: 30269: debug : virIdentitySetAttr:282 : ident=0x7f2a8533bca0 attribute=1 value=0
>2014-01-29 11:27:30.129+0000: 30269: debug : virIdentitySetAttr:282 : ident=0x7f2a8533bca0 attribute=2 value=root
>2014-01-29 11:27:30.129+0000: 30269: debug : virIdentitySetAttr:282 : ident=0x7f2a8533bca0 attribute=3 value=0
>2014-01-29 11:27:30.129+0000: 30269: debug : virIdentitySetAttr:282 : ident=0x7f2a8533bca0 attribute=4 value=30269
>2014-01-29 11:27:30.129+0000: 30269: debug : virIdentitySetAttr:282 : ident=0x7f2a8533bca0 attribute=5 value=173851
>2014-01-29 11:27:30.129+0000: 30269: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f2a8533bca0
>2014-01-29 11:27:30.129+0000: 30269: debug : virConnectClose:1501 : conn=0x7f29b80053c0
>2014-01-29 11:27:30.129+0000: 30269: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7f29b80053c0
>2014-01-29 11:27:30.129+0000: 30269: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7f29b80053c0
>

Actions: View

Attachments on bug 1060248: 857889