Mageia Bugzilla – Attachment 7450 Details for
Bug 17417
libvirt new security issue CVE-2015-5313
Home
|
New
|
Browse
|
Search
|
[?]
|
Reports
|
Requests
|
Help
|
Log In
[x]
|
New Account
|
Forgot Password
more debug output
libvirt-debug-2.txt (text/plain), 56.21 KB, created by
James Kerr
on 2016-02-12 10:30:41 CET
(
hide
)
Description:
more debug output
Filename:
MIME Type:
Creator:
James Kerr
Created:
2016-02-12 10:30:41 CET
Size:
56.21 KB
patch
obsolete
># LIBVIRT_DEBUG=1 virsh -d 0 -c qemu:///system list >2016-02-12 09:11:56.067+0000: 4917: info : libvirt version: 1.2.9.3 >2016-02-12 09:11:56.067+0000: 4917: debug : virGlobalInit:407 : register drivers >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:682 : driver=0x7f56608a1ac0 name=Test >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:688 : registering Test as driver 0 >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterNetworkDriver:553 : registering Test as network driver 0 >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterInterfaceDriver:575 : registering Test as interface driver 0 >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterStorageDriver:597 : registering Test as storage driver 0 >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterNodeDeviceDriver:619 : registering Test as device driver 0 >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterSecretDriver:641 : registering Test as secret driver 0 >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterNWFilterDriver:663 : registering Test as network filter driver 0 >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:682 : driver=0x7f56608a3cc0 name=OPENVZ >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:688 : registering OPENVZ as driver 1 >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:682 : driver=0x7f56608a43c0 name=VMWARE >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:688 : registering VMWARE as driver 2 >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:682 : driver=0x7f56608a3600 name=PHYP >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:688 : registering PHYP as driver 3 >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterStorageDriver:597 : registering PHYP as storage driver 1 >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterInterfaceDriver:575 : registering PHYP as interface driver 1 >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:682 : driver=0x7f56608a4a40 name=ESX >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:688 : registering ESX as driver 4 >2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterInterfaceDriver:575 : registering ESX as interface driver 2 >2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterNetworkDriver:553 : registering ESX as network driver 1 >2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterStorageDriver:597 : registering ESX as storage driver 2 >2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterNodeDeviceDriver:619 : registering ESX as device driver 1 >2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterSecretDriver:641 : registering ESX as secret driver 1 >2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterNWFilterDriver:663 : registering ESX as network filter driver 1 >2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterDriver:682 : driver=0x7f56608a2680 name=remote >2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterDriver:688 : registering remote as driver 5 >2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterNetworkDriver:553 : registering remote as network driver 2 >2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterInterfaceDriver:575 : registering remote as interface driver 3 >2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterStorageDriver:597 : registering remote as storage driver 3 >2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterNodeDeviceDriver:619 : registering remote as device driver 2 >2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterSecretDriver:641 : registering remote as secret driver 2 >2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterNWFilterDriver:663 : registering remote as network filter driver 2 >commands: "list" >2016-02-12 09:11:56.068+0000: 4917: debug : virEventRegisterDefaultImpl:261 : registering default event implementation >2016-02-12 09:11:56.068+0000: 4917: debug : virEventPollAddHandle:115 : Used 0 handle slots, adding at least 10 more >2016-02-12 09:11:56.068+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 0 >2016-02-12 09:11:56.068+0000: 4917: debug : virEventPollAddHandle:140 : EVENT_POLL_ADD_HANDLE: watch=1 fd=4 events=1 cb=0x7f56603cee50 opaque=(nil) ff=(nil) >2016-02-12 09:11:56.068+0000: 4917: debug : virEventRegisterImpl:231 : addHandle=0x7f56603cf4f0 updateHandle=0x7f56603cf820 removeHandle=0x7f56603cee90 addTimeout=0x7f56603ceff0 updateTimeout=0x7f56603cf1d0 removeTimeout=0x7f56603cf370 >2016-02-12 09:11:56.068+0000: 4917: debug : virEventPollAddTimeout:231 : Used 0 timeout slots, adding at least 10 more >2016-02-12 09:11:56.068+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 0 >2016-02-12 09:11:56.068+0000: 4917: debug : virEventPollAddTimeout:254 : EVENT_POLL_ADD_TIMEOUT: timer=1 frequency=-1 cb=0x55c64b93a0c0 opaque=0x7ffea9329200 ff=(nil) >2016-02-12 09:11:56.068+0000: 4917: debug : virConnectOpenAuth:1391 : name=qemu:///system, auth=0x7f56608a14e0, flags=0 >2016-02-12 09:11:56.068+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0a080 classname=virConnect >2016-02-12 09:11:56.068+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf05870 classname=virConnectCloseCallbackData >2016-02-12 09:11:56.068+0000: 4917: debug : virConnectGetConfigFile:920 : Loading config file '/etc/libvirt/libvirt.conf' >2016-02-12 09:11:56.068+0000: 4917: debug : virConfReadFile:764 : filename=/etc/libvirt/libvirt.conf >2016-02-12 09:11:56.068+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.068+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2016-02-12 09:11:56.068+0000: 4917: debug : virFileClose:99 : Closed fd 6 >2016-02-12 09:11:56.068+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 1 >2016-02-12 09:11:56.068+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.068+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers >2016-02-12 09:11:56.068+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2016-02-12 09:11:56.068+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 >2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1099 : name "qemu:///system" to URI components: > scheme qemu > server <null> > user <null> > port -1 > path /system > >2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1145 : trying driver 0 (Test) ... >2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1152 : driver 0 Test returned DECLINED >2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1145 : trying driver 1 (OPENVZ) ... >2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1152 : driver 1 OPENVZ returned DECLINED >2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1145 : trying driver 2 (VMWARE) ... >2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1152 : driver 2 VMWARE returned DECLINED >2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1145 : trying driver 3 (PHYP) ... >2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1152 : driver 3 PHYP returned DECLINED >2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1145 : trying driver 4 (ESX) ... >2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1152 : driver 4 ESX returned DECLINED >2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1145 : trying driver 5 (remote) ... >2016-02-12 09:11:56.068+0000: 4917: debug : doRemoteOpen:774 : proceeding with name = qemu:///system >2016-02-12 09:11:56.068+0000: 4917: debug : doRemoteOpen:783 : Connecting with transport 1 >2016-02-12 09:11:56.068+0000: 4917: debug : doRemoteOpen:874 : Proceeding with sockname /var/run/libvirt/libvirt-sock >2016-02-12 09:11:56.068+0000: 4917: debug : virNetSocketNew:163 : localAddr=0x7ffea9328b30 remoteAddr=0x7ffea9328bc0 fd=6 errfd=-1 pid=0 >2016-02-12 09:11:56.069+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0aff0 classname=virNetSocket >2016-02-12 09:11:56.069+0000: 4917: debug : virNetSocketNew:213 : RPC_SOCKET_NEW: sock=0x55c64bf0aff0 fd=6 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0 >2016-02-12 09:11:56.069+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0b3f0 classname=virNetClient >2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientNew:329 : RPC_CLIENT_NEW: client=0x55c64bf0b3f0 sock=0x55c64bf0aff0 >2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0b3f0 >2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0aff0 >2016-02-12 09:11:56.069+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.069+0000: 4917: debug : virEventPollAddHandle:140 : EVENT_POLL_ADD_HANDLE: watch=2 fd=6 events=1 cb=0x7f5660502b40 opaque=0x55c64bf0aff0 ff=0x7f5660502af0 >2016-02-12 09:11:56.069+0000: 4917: debug : virKeepAliveNew:199 : client=0x55c64bf0b3f0, interval=-1, count=0 >2016-02-12 09:11:56.069+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0b6e0 classname=virKeepAlive >2016-02-12 09:11:56.069+0000: 4917: debug : virKeepAliveNew:218 : RPC_KEEPALIVE_NEW: ka=0x55c64bf0b6e0 client=0x55c64bf0b3f0 >2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0b3f0 >2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf05870 >2016-02-12 09:11:56.069+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0b600 classname=virNetClientProgram >2016-02-12 09:11:56.069+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0b1d0 classname=virNetClientProgram >2016-02-12 09:11:56.069+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0af80 classname=virNetClientProgram >2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0b600 >2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0b1d0 >2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0af80 >2016-02-12 09:11:56.069+0000: 4917: debug : doRemoteOpen:991 : Trying authentication >2016-02-12 09:11:56.069+0000: 4917: debug : virNetMessageNew:46 : msg=0x55c64bf0bc10 tracked=0 >2016-02-12 09:11:56.069+0000: 4917: debug : virNetMessageEncodePayload:376 : Encode length as 28 >2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientSendInternal:1946 : RPC_CLIENT_MSG_TX_QUEUE: client=0x55c64bf0b3f0 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0 >2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientCallNew:1899 : New call 0x55c64bf0bb90: msg=0x55c64bf0bc10, expectReply=1, nonBlock=0 >2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientIO:1708 : Outgoing message prog=536903814 version=1 serial=0 proc=66 type=0 length=28 dispatch=(nil) >2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientIO:1767 : We have the buck head=0x55c64bf0bb90 call=0x55c64bf0bb90 >2016-02-12 09:11:56.069+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 >2016-02-12 09:11:56.069+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 >2016-02-12 09:11:56.069+0000: 4917: debug : virNetMessageDecodeLength:151 : Got length, now need 36 total (32 more) >2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientCallDispatch:1117 : RPC_CLIENT_MSG_RX: client=0x55c64bf0b3f0 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0 >2016-02-12 09:11:56.069+0000: 4917: debug : virKeepAliveCheckMessage:377 : ka=0x55c64bf0b6e0, client=0x55c64bf0b3f0, msg=0x55c64bf0b458 >2016-02-12 09:11:56.069+0000: 4917: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 >2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck 0x55c64bf0bb90 >2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to >2016-02-12 09:11:56.069+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 >2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIO:1797 : All done with our call head=(nil) call=0x55c64bf0bb90 rv=0 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageFree:74 : msg=0x55c64bf0bc10 nfds=0 cb=(nil) >2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageNew:46 : msg=0x55c64bf0bc10 tracked=0 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageEncodePayload:376 : Encode length as 32 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientSendInternal:1946 : RPC_CLIENT_MSG_TX_QUEUE: client=0x55c64bf0b3f0 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientCallNew:1899 : New call 0x55c64bf0b390: msg=0x55c64bf0bc10, expectReply=1, nonBlock=0 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIO:1708 : Outgoing message prog=536903814 version=1 serial=1 proc=60 type=0 length=32 dispatch=(nil) >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIO:1767 : We have the buck head=0x55c64bf0b390 call=0x55c64bf0b390 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageDecodeLength:151 : Got length, now need 32 total (28 more) >2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientCallDispatch:1117 : RPC_CLIENT_MSG_RX: client=0x55c64bf0b3f0 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1 >2016-02-12 09:11:56.070+0000: 4917: debug : virKeepAliveCheckMessage:377 : ka=0x55c64bf0b6e0, client=0x55c64bf0b3f0, msg=0x55c64bf0b458 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck 0x55c64bf0b390 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to >2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 >2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIO:1797 : All done with our call head=(nil) call=0x55c64bf0b390 rv=0 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageFree:74 : msg=0x55c64bf0bc10 nfds=0 cb=(nil) >2016-02-12 09:11:56.070+0000: 4917: debug : doRemoteOpen:1017 : Trying to open URI qemu:///system >2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageNew:46 : msg=0x55c64bf0b240 tracked=0 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageEncodePayload:376 : Encode length as 56 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientSendInternal:1946 : RPC_CLIENT_MSG_TX_QUEUE: client=0x55c64bf0b3f0 len=56 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientCallNew:1899 : New call 0x55c64bf0b590: msg=0x55c64bf0b240, expectReply=1, nonBlock=0 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIO:1708 : Outgoing message prog=536903814 version=1 serial=2 proc=1 type=0 length=56 dispatch=(nil) >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIO:1767 : We have the buck head=0x55c64bf0b590 call=0x55c64bf0b590 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 >2016-02-12 09:11:56.071+0000: 4917: debug : virNetMessageDecodeLength:151 : Got length, now need 28 total (24 more) >2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientCallDispatch:1117 : RPC_CLIENT_MSG_RX: client=0x55c64bf0b3f0 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2 >2016-02-12 09:11:56.071+0000: 4917: debug : virKeepAliveCheckMessage:377 : ka=0x55c64bf0b6e0, client=0x55c64bf0b3f0, msg=0x55c64bf0b458 >2016-02-12 09:11:56.071+0000: 4917: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 >2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck 0x55c64bf0b590 >2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to >2016-02-12 09:11:56.071+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 >2016-02-12 09:11:56.071+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientIO:1797 : All done with our call head=(nil) call=0x55c64bf0b590 rv=0 >2016-02-12 09:11:56.071+0000: 4917: debug : virNetMessageFree:74 : msg=0x55c64bf0b240 nfds=0 cb=(nil) >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.071+0000: 4917: debug : virNetMessageNew:46 : msg=0x55c64bf0b240 tracked=0 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 >2016-02-12 09:11:56.071+0000: 4917: debug : virNetMessageEncodePayload:376 : Encode length as 32 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 >2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientSendInternal:1946 : RPC_CLIENT_MSG_TX_QUEUE: client=0x55c64bf0b3f0 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=3 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientCallNew:1899 : New call 0x55c64bf0bd50: msg=0x55c64bf0b240, expectReply=1, nonBlock=0 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientIO:1708 : Outgoing message prog=536903814 version=1 serial=3 proc=60 type=0 length=32 dispatch=(nil) >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientIO:1767 : We have the buck head=0x55c64bf0bd50 call=0x55c64bf0bd50 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.071+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.071+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 >2016-02-12 09:11:56.072+0000: 4917: debug : virNetMessageDecodeLength:151 : Got length, now need 32 total (28 more) >2016-02-12 09:11:56.072+0000: 4917: debug : virNetClientCallDispatch:1117 : RPC_CLIENT_MSG_RX: client=0x55c64bf0b3f0 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=3 >2016-02-12 09:11:56.072+0000: 4917: debug : virKeepAliveCheckMessage:377 : ka=0x55c64bf0b6e0, client=0x55c64bf0b3f0, msg=0x55c64bf0b458 >2016-02-12 09:11:56.072+0000: 4917: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 >2016-02-12 09:11:56.072+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck 0x55c64bf0bd50 >2016-02-12 09:11:56.072+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to >2016-02-12 09:11:56.072+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 >2016-02-12 09:11:56.072+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.072+0000: 4917: debug : virNetClientIO:1797 : All done with our call head=(nil) call=0x55c64bf0bd50 rv=0 >2016-02-12 09:11:56.072+0000: 4917: debug : virNetMessageFree:74 : msg=0x55c64bf0b240 nfds=0 cb=(nil) >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1152 : driver 5 remote returned SUCCESS >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1176 : network driver 0 Test returned DECLINED >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1176 : network driver 1 ESX returned DECLINED >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1176 : network driver 2 remote returned SUCCESS >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1192 : interface driver 0 Test returned DECLINED >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1192 : interface driver 1 PHYP returned DECLINED >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1192 : interface driver 2 ESX returned DECLINED >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1192 : interface driver 3 remote returned SUCCESS >2016-02-12 09:11:56.072+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1209 : storage driver 0 Test returned DECLINED >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1209 : storage driver 1 PHYP returned DECLINED >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1209 : storage driver 2 ESX returned DECLINED >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1209 : storage driver 3 remote returned SUCCESS >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=1 d=0 >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1226 : node driver 0 Test returned DECLINED >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1226 : node driver 1 ESX returned DECLINED >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1226 : node driver 2 remote returned SUCCESS >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=2 timeout=-1 >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1243 : secret driver 0 Test returned DECLINED >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1243 : secret driver 1 ESX returned DECLINED >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1243 : secret driver 2 remote returned SUCCESS >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1260 : nwfilter driver 0 Test returned DECLINED >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1260 : nwfilter driver 1 ESX returned DECLINED >2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1260 : nwfilter driver 2 remote returned SUCCESS >2016-02-12 09:11:56.072+0000: 4917: debug : virConnectSetKeepAlive:20445 : conn=0x55c64bf0a080, interval=5, count=6 >2016-02-12 09:11:56.072+0000: 4917: debug : virKeepAliveStart:279 : RPC_KEEPALIVE_START: ka=0x55c64bf0b6e0 client=0x55c64bf0b3f0 interval=5 count=6 >2016-02-12 09:11:56.072+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.072+0000: 4917: debug : virEventPollAddTimeout:254 : EVENT_POLL_ADD_TIMEOUT: timer=2 frequency=5000 cb=0x7f56604ff250 opaque=0x55c64bf0b6e0 ff=0x7f56603f1e50 >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.072+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0b6e0 >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2016-02-12 09:11:56.072+0000: 4917: debug : virConnectRegisterCloseCallback:20529 : conn=0x55c64bf0a080 >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 2 >2016-02-12 09:11:56.072+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0a080 >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.072+0000: 4917: debug : virConnectIsAlive:20480 : conn=0x55c64bf0a080 >2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:494 : i=1 w=2 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.073+0000: 4917: debug : virConnectListAllDomains:8950 : conn=0x55c64bf0a080, domains=0x55c64bf08ca0, flags=1 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.073+0000: 4917: debug : virNetMessageNew:46 : msg=0x55c64bf08e70 tracked=0 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.073+0000: 4917: debug : virNetMessageEncodePayload:376 : Encode length as 36 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.073+0000: 4917: debug : virNetClientSendInternal:1946 : RPC_CLIENT_MSG_TX_QUEUE: client=0x55c64bf0b3f0 len=36 prog=536903814 vers=1 proc=273 type=0 status=0 serial=4 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.073+0000: 4917: debug : virNetClientCallNew:1899 : New call 0x55c64bf08e00: msg=0x55c64bf08e70, expectReply=1, nonBlock=0 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.073+0000: 4917: debug : virNetClientIO:1708 : Outgoing message prog=536903814 version=1 serial=4 proc=273 type=0 length=36 dispatch=(nil) >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=1 d=0 >2016-02-12 09:11:56.073+0000: 4917: debug : virNetClientIO:1767 : We have the buck head=0x55c64bf08e00 call=0x55c64bf08e00 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321072 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321072 now=1455268316073 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321072 due in 4999 ms >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=2 timeout=4999 >2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 2 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1455268321073 >2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321073 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321073 now=1455268316073 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321073 due in 5000 ms >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=5000 >2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000 >2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1455268321073 >2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.073+0000: 4917: debug : virNetMessageDecodeLength:151 : Got length, now need 36 total (32 more) >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2016-02-12 09:11:56.073+0000: 4917: debug : virNetClientCallDispatch:1117 : RPC_CLIENT_MSG_RX: client=0x55c64bf0b3f0 len=36 prog=536903814 vers=1 proc=273 type=1 status=0 serial=4 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 >2016-02-12 09:11:56.073+0000: 4917: debug : virKeepAliveCheckMessage:377 : ka=0x55c64bf0b6e0, client=0x55c64bf0b3f0, msg=0x55c64bf0b458 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000 >2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.074+0000: 4917: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1455268321074 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.074+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 >2016-02-12 09:11:56.074+0000: 4917: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.074+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck 0x55c64bf08e00 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.074+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.074+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321074 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321074 now=1455268316074 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321074 due in 5000 ms >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=5000 >2016-02-12 09:11:56.074+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.074+0000: 4917: debug : virNetClientIO:1797 : All done with our call head=(nil) call=0x55c64bf08e00 rv=0 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2016-02-12 09:11:56.074+0000: 4917: debug : virNetMessageFree:74 : msg=0x55c64bf08e70 nfds=0 cb=(nil) >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=1 d=0 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321074 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321074 now=1455268316074 >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321074 due in 5000 ms >2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=2 timeout=5000 > Id Name State >---------------------------------------------------- > >2016-02-12 09:11:56.084+0000: 4917: debug : virConnectUnregisterCloseCallback:20584 : conn=0x55c64bf0a080 >2016-02-12 09:11:56.084+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0a080 >2016-02-12 09:11:56.084+0000: 4917: debug : virConnectClose:1435 : conn=0x55c64bf0a080 >2016-02-12 09:11:56.084+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0a080 >2016-02-12 09:11:56.084+0000: 4917: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0a080 >2016-02-12 09:11:56.084+0000: 4917: debug : virNetMessageNew:46 : msg=0x55c64bf08c10 tracked=0 >2016-02-12 09:11:56.084+0000: 4917: debug : virNetMessageEncodePayload:376 : Encode length as 28 >2016-02-12 09:11:56.084+0000: 4917: debug : virNetClientSendInternal:1946 : RPC_CLIENT_MSG_TX_QUEUE: client=0x55c64bf0b3f0 len=28 prog=536903814 vers=1 proc=2 type=0 status=0 serial=5 >2016-02-12 09:11:56.084+0000: 4917: debug : virNetClientCallNew:1899 : New call 0x55c64bf0bac0: msg=0x55c64bf08c10, expectReply=1, nonBlock=0 >2016-02-12 09:11:56.084+0000: 4917: debug : virNetClientIO:1708 : Outgoing message prog=536903814 version=1 serial=5 proc=2 type=0 length=28 dispatch=(nil) >2016-02-12 09:11:56.084+0000: 4917: debug : virNetClientIO:1767 : We have the buck head=0x55c64bf0bac0 call=0x55c64bf0bac0 >2016-02-12 09:11:56.084+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 >2016-02-12 09:11:56.084+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.084+0000: 4917: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000 >2016-02-12 09:11:56.084+0000: 4917: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1455268321084 >2016-02-12 09:11:56.084+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 2 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321084 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321084 now=1455268316084 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321084 due in 5000 ms >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=5000 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.084+0000: 4917: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321084 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321084 now=1455268316084 >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321084 due in 5000 ms >2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=5000 >2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1455268321084 >2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.085+0000: 4917: debug : virNetMessageDecodeLength:151 : Got length, now need 28 total (24 more) >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 >2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientCallDispatch:1117 : RPC_CLIENT_MSG_RX: client=0x55c64bf0b3f0 len=28 prog=536903814 vers=1 proc=2 type=1 status=0 serial=5 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.085+0000: 4917: debug : virKeepAliveCheckMessage:377 : ka=0x55c64bf0b6e0, client=0x55c64bf0b3f0, msg=0x55c64bf0b458 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1455268321085 >2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 >2016-02-12 09:11:56.085+0000: 4917: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck 0x55c64bf0bac0 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 >2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321085 >2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321085 now=1455268316085 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321085 due in 5000 ms >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=5000 >2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientIO:1797 : All done with our call head=(nil) call=0x55c64bf0bac0 rv=0 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 >2016-02-12 09:11:56.085+0000: 4917: debug : virNetMessageFree:74 : msg=0x55c64bf08c10 nfds=0 cb=(nil) >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 >2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientCloseInternal:690 : client=0x55c64bf0b3f0 wantclose=0 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientMarkClose:636 : client=0x55c64bf0b3f0, reason=3 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollRemoveHandle:186 : EVENT_POLL_REMOVE_HANDLE: watch=2 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation >2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollRemoveHandle:199 : mark delete 1 6 >2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 >2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck (nil) >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 >2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 >2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientCloseLocked:649 : client=0x55c64bf0b3f0, sock=0x55c64bf0aff0, reason=3 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupHandles:589 : EVENT_POLL_PURGE_HANDLE: watch=2 >2016-02-12 09:11:56.085+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0aff0 >2016-02-12 09:11:56.085+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b3f0 >2016-02-12 09:11:56.085+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0b3f0 >2016-02-12 09:11:56.085+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0aff0 >2016-02-12 09:11:56.085+0000: 4917: debug : virKeepAliveStop:310 : RPC_KEEPALIVE_STOP: ka=0x55c64bf0b6e0 client=0x55c64bf0b3f0 >2016-02-12 09:11:56.085+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0aff0 >2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollRemoveTimeout:307 : EVENT_POLL_REMOVE_TIMEOUT: timer=2 >2016-02-12 09:11:56.085+0000: 4918: debug : virNetSocketDispose:1137 : RPC_SOCKET_DISPOSE: sock=0x55c64bf0aff0 >2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollRemoveHandle:186 : EVENT_POLL_REMOVE_HANDLE: watch=2 >2016-02-12 09:11:56.085+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b6e0 >2016-02-12 09:11:56.085+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b3f0 >2016-02-12 09:11:56.085+0000: 4918: debug : virFileClose:99 : Closed fd 6 >2016-02-12 09:11:56.085+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b3f0 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 >2016-02-12 09:11:56.085+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b600 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers >2016-02-12 09:11:56.085+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b1d0 >2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending >2016-02-12 09:11:56.086+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0af80 >2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 >2016-02-12 09:11:56.086+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf05870 >2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) >2016-02-12 09:11:56.086+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting >2016-02-12 09:11:56.086+0000: 4917: debug : virEventPollAddTimeout:254 : EVENT_POLL_ADD_TIMEOUT: timer=3 frequency=0 cb=0x55c64b93a000 opaque=(nil) ff=(nil) >2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 3 >2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollDispatchTimeouts:457 : EVENT_POLL_DISPATCH_TIMEOUT: timer=3 >2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 >2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 >2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 3 >2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollCleanupTimeouts:540 : EVENT_POLL_PURGE_TIMEOUT: timer=2 >2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b6e0 >2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0b6e0 >2016-02-12 09:11:56.086+0000: 4918: debug : virKeepAliveDispose:230 : RPC_KEEPALIVE_DISPOSE: ka=0x55c64bf0b6e0 >2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b3f0 >2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0b3f0 >2016-02-12 09:11:56.086+0000: 4918: debug : virNetClientDispose:602 : RPC_CLIENT_DISPOSE: client=0x55c64bf0b3f0 >2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf05870 >2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf05870 >2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b600 >2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0b600 >2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b1d0 >2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0b1d0 >2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0af80 >2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0af80 >2016-02-12 09:11:56.086+0000: 4918: debug : virFileClose:99 : Closed fd 8 >2016-02-12 09:11:56.086+0000: 4918: debug : virFileClose:99 : Closed fd 7 >2016-02-12 09:11:56.086+0000: 4918: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 >2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 1 >2016-02-12 09:11:56.086+0000: 4917: debug : virEventPollRemoveTimeout:307 : EVENT_POLL_REMOVE_TIMEOUT: timer=3 >2016-02-12 09:11:56.086+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 >2016-02-12 09:11:56.086+0000: 4917: debug : virEventPollRemoveTimeout:307 : EVENT_POLL_REMOVE_TIMEOUT: timer=1 >2016-02-12 09:11:56.086+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744
# LIBVIRT_DEBUG=1 virsh -d 0 -c qemu:///system list 2016-02-12 09:11:56.067+0000: 4917: info : libvirt version: 1.2.9.3 2016-02-12 09:11:56.067+0000: 4917: debug : virGlobalInit:407 : register drivers 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:682 : driver=0x7f56608a1ac0 name=Test 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:688 : registering Test as driver 0 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterNetworkDriver:553 : registering Test as network driver 0 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterInterfaceDriver:575 : registering Test as interface driver 0 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterStorageDriver:597 : registering Test as storage driver 0 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterNodeDeviceDriver:619 : registering Test as device driver 0 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterSecretDriver:641 : registering Test as secret driver 0 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterNWFilterDriver:663 : registering Test as network filter driver 0 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:682 : driver=0x7f56608a3cc0 name=OPENVZ 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:688 : registering OPENVZ as driver 1 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:682 : driver=0x7f56608a43c0 name=VMWARE 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:688 : registering VMWARE as driver 2 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:682 : driver=0x7f56608a3600 name=PHYP 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:688 : registering PHYP as driver 3 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterStorageDriver:597 : registering PHYP as storage driver 1 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterInterfaceDriver:575 : registering PHYP as interface driver 1 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:682 : driver=0x7f56608a4a40 name=ESX 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterDriver:688 : registering ESX as driver 4 2016-02-12 09:11:56.067+0000: 4917: debug : virRegisterInterfaceDriver:575 : registering ESX as interface driver 2 2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterNetworkDriver:553 : registering ESX as network driver 1 2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterStorageDriver:597 : registering ESX as storage driver 2 2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterNodeDeviceDriver:619 : registering ESX as device driver 1 2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterSecretDriver:641 : registering ESX as secret driver 1 2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterNWFilterDriver:663 : registering ESX as network filter driver 1 2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterDriver:682 : driver=0x7f56608a2680 name=remote 2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterDriver:688 : registering remote as driver 5 2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterNetworkDriver:553 : registering remote as network driver 2 2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterInterfaceDriver:575 : registering remote as interface driver 3 2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterStorageDriver:597 : registering remote as storage driver 3 2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterNodeDeviceDriver:619 : registering remote as device driver 2 2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterSecretDriver:641 : registering remote as secret driver 2 2016-02-12 09:11:56.068+0000: 4917: debug : virRegisterNWFilterDriver:663 : registering remote as network filter driver 2 commands: "list" 2016-02-12 09:11:56.068+0000: 4917: debug : virEventRegisterDefaultImpl:261 : registering default event implementation 2016-02-12 09:11:56.068+0000: 4917: debug : virEventPollAddHandle:115 : Used 0 handle slots, adding at least 10 more 2016-02-12 09:11:56.068+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 0 2016-02-12 09:11:56.068+0000: 4917: debug : virEventPollAddHandle:140 : EVENT_POLL_ADD_HANDLE: watch=1 fd=4 events=1 cb=0x7f56603cee50 opaque=(nil) ff=(nil) 2016-02-12 09:11:56.068+0000: 4917: debug : virEventRegisterImpl:231 : addHandle=0x7f56603cf4f0 updateHandle=0x7f56603cf820 removeHandle=0x7f56603cee90 addTimeout=0x7f56603ceff0 updateTimeout=0x7f56603cf1d0 removeTimeout=0x7f56603cf370 2016-02-12 09:11:56.068+0000: 4917: debug : virEventPollAddTimeout:231 : Used 0 timeout slots, adding at least 10 more 2016-02-12 09:11:56.068+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 0 2016-02-12 09:11:56.068+0000: 4917: debug : virEventPollAddTimeout:254 : EVENT_POLL_ADD_TIMEOUT: timer=1 frequency=-1 cb=0x55c64b93a0c0 opaque=0x7ffea9329200 ff=(nil) 2016-02-12 09:11:56.068+0000: 4917: debug : virConnectOpenAuth:1391 : name=qemu:///system, auth=0x7f56608a14e0, flags=0 2016-02-12 09:11:56.068+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0a080 classname=virConnect 2016-02-12 09:11:56.068+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf05870 classname=virConnectCloseCallbackData 2016-02-12 09:11:56.068+0000: 4917: debug : virConnectGetConfigFile:920 : Loading config file '/etc/libvirt/libvirt.conf' 2016-02-12 09:11:56.068+0000: 4917: debug : virConfReadFile:764 : filename=/etc/libvirt/libvirt.conf 2016-02-12 09:11:56.068+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.068+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 2016-02-12 09:11:56.068+0000: 4917: debug : virFileClose:99 : Closed fd 6 2016-02-12 09:11:56.068+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 1 2016-02-12 09:11:56.068+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.068+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers 2016-02-12 09:11:56.068+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending 2016-02-12 09:11:56.068+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1099 : name "qemu:///system" to URI components: scheme qemu server <null> user <null> port -1 path /system 2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1145 : trying driver 0 (Test) ... 2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1152 : driver 0 Test returned DECLINED 2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1145 : trying driver 1 (OPENVZ) ... 2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1152 : driver 1 OPENVZ returned DECLINED 2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1145 : trying driver 2 (VMWARE) ... 2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1152 : driver 2 VMWARE returned DECLINED 2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1145 : trying driver 3 (PHYP) ... 2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1152 : driver 3 PHYP returned DECLINED 2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1145 : trying driver 4 (ESX) ... 2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1152 : driver 4 ESX returned DECLINED 2016-02-12 09:11:56.068+0000: 4917: debug : do_open:1145 : trying driver 5 (remote) ... 2016-02-12 09:11:56.068+0000: 4917: debug : doRemoteOpen:774 : proceeding with name = qemu:///system 2016-02-12 09:11:56.068+0000: 4917: debug : doRemoteOpen:783 : Connecting with transport 1 2016-02-12 09:11:56.068+0000: 4917: debug : doRemoteOpen:874 : Proceeding with sockname /var/run/libvirt/libvirt-sock 2016-02-12 09:11:56.068+0000: 4917: debug : virNetSocketNew:163 : localAddr=0x7ffea9328b30 remoteAddr=0x7ffea9328bc0 fd=6 errfd=-1 pid=0 2016-02-12 09:11:56.069+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0aff0 classname=virNetSocket 2016-02-12 09:11:56.069+0000: 4917: debug : virNetSocketNew:213 : RPC_SOCKET_NEW: sock=0x55c64bf0aff0 fd=6 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0 2016-02-12 09:11:56.069+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0b3f0 classname=virNetClient 2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientNew:329 : RPC_CLIENT_NEW: client=0x55c64bf0b3f0 sock=0x55c64bf0aff0 2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0b3f0 2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0aff0 2016-02-12 09:11:56.069+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.069+0000: 4917: debug : virEventPollAddHandle:140 : EVENT_POLL_ADD_HANDLE: watch=2 fd=6 events=1 cb=0x7f5660502b40 opaque=0x55c64bf0aff0 ff=0x7f5660502af0 2016-02-12 09:11:56.069+0000: 4917: debug : virKeepAliveNew:199 : client=0x55c64bf0b3f0, interval=-1, count=0 2016-02-12 09:11:56.069+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0b6e0 classname=virKeepAlive 2016-02-12 09:11:56.069+0000: 4917: debug : virKeepAliveNew:218 : RPC_KEEPALIVE_NEW: ka=0x55c64bf0b6e0 client=0x55c64bf0b3f0 2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0b3f0 2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf05870 2016-02-12 09:11:56.069+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0b600 classname=virNetClientProgram 2016-02-12 09:11:56.069+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0b1d0 classname=virNetClientProgram 2016-02-12 09:11:56.069+0000: 4917: debug : virObjectNew:202 : OBJECT_NEW: obj=0x55c64bf0af80 classname=virNetClientProgram 2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0b600 2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0b1d0 2016-02-12 09:11:56.069+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0af80 2016-02-12 09:11:56.069+0000: 4917: debug : doRemoteOpen:991 : Trying authentication 2016-02-12 09:11:56.069+0000: 4917: debug : virNetMessageNew:46 : msg=0x55c64bf0bc10 tracked=0 2016-02-12 09:11:56.069+0000: 4917: debug : virNetMessageEncodePayload:376 : Encode length as 28 2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientSendInternal:1946 : RPC_CLIENT_MSG_TX_QUEUE: client=0x55c64bf0b3f0 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0 2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientCallNew:1899 : New call 0x55c64bf0bb90: msg=0x55c64bf0bc10, expectReply=1, nonBlock=0 2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientIO:1708 : Outgoing message prog=536903814 version=1 serial=0 proc=66 type=0 length=28 dispatch=(nil) 2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientIO:1767 : We have the buck head=0x55c64bf0bb90 call=0x55c64bf0bb90 2016-02-12 09:11:56.069+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 2016-02-12 09:11:56.069+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.069+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.069+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending 2016-02-12 09:11:56.069+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 2016-02-12 09:11:56.069+0000: 4917: debug : virNetMessageDecodeLength:151 : Got length, now need 36 total (32 more) 2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientCallDispatch:1117 : RPC_CLIENT_MSG_RX: client=0x55c64bf0b3f0 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0 2016-02-12 09:11:56.069+0000: 4917: debug : virKeepAliveCheckMessage:377 : ka=0x55c64bf0b6e0, client=0x55c64bf0b3f0, msg=0x55c64bf0b458 2016-02-12 09:11:56.069+0000: 4917: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck 0x55c64bf0bb90 2016-02-12 09:11:56.069+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to 2016-02-12 09:11:56.069+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIO:1797 : All done with our call head=(nil) call=0x55c64bf0bb90 rv=0 2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageFree:74 : msg=0x55c64bf0bc10 nfds=0 cb=(nil) 2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageNew:46 : msg=0x55c64bf0bc10 tracked=0 2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageEncodePayload:376 : Encode length as 32 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientSendInternal:1946 : RPC_CLIENT_MSG_TX_QUEUE: client=0x55c64bf0b3f0 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientCallNew:1899 : New call 0x55c64bf0b390: msg=0x55c64bf0bc10, expectReply=1, nonBlock=0 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIO:1708 : Outgoing message prog=536903814 version=1 serial=1 proc=60 type=0 length=32 dispatch=(nil) 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIO:1767 : We have the buck head=0x55c64bf0b390 call=0x55c64bf0b390 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.070+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageDecodeLength:151 : Got length, now need 32 total (28 more) 2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientCallDispatch:1117 : RPC_CLIENT_MSG_RX: client=0x55c64bf0b3f0 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1 2016-02-12 09:11:56.070+0000: 4917: debug : virKeepAliveCheckMessage:377 : ka=0x55c64bf0b6e0, client=0x55c64bf0b3f0, msg=0x55c64bf0b458 2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck 0x55c64bf0b390 2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to 2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIO:1797 : All done with our call head=(nil) call=0x55c64bf0b390 rv=0 2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageFree:74 : msg=0x55c64bf0bc10 nfds=0 cb=(nil) 2016-02-12 09:11:56.070+0000: 4917: debug : doRemoteOpen:1017 : Trying to open URI qemu:///system 2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageNew:46 : msg=0x55c64bf0b240 tracked=0 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.070+0000: 4917: debug : virNetMessageEncodePayload:376 : Encode length as 56 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientSendInternal:1946 : RPC_CLIENT_MSG_TX_QUEUE: client=0x55c64bf0b3f0 len=56 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientCallNew:1899 : New call 0x55c64bf0b590: msg=0x55c64bf0b240, expectReply=1, nonBlock=0 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIO:1708 : Outgoing message prog=536903814 version=1 serial=2 proc=1 type=0 length=56 dispatch=(nil) 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.070+0000: 4917: debug : virNetClientIO:1767 : We have the buck head=0x55c64bf0b590 call=0x55c64bf0b590 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.070+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 2016-02-12 09:11:56.070+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 2016-02-12 09:11:56.070+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 2016-02-12 09:11:56.071+0000: 4917: debug : virNetMessageDecodeLength:151 : Got length, now need 28 total (24 more) 2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientCallDispatch:1117 : RPC_CLIENT_MSG_RX: client=0x55c64bf0b3f0 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2 2016-02-12 09:11:56.071+0000: 4917: debug : virKeepAliveCheckMessage:377 : ka=0x55c64bf0b6e0, client=0x55c64bf0b3f0, msg=0x55c64bf0b458 2016-02-12 09:11:56.071+0000: 4917: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck 0x55c64bf0b590 2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to 2016-02-12 09:11:56.071+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 2016-02-12 09:11:56.071+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientIO:1797 : All done with our call head=(nil) call=0x55c64bf0b590 rv=0 2016-02-12 09:11:56.071+0000: 4917: debug : virNetMessageFree:74 : msg=0x55c64bf0b240 nfds=0 cb=(nil) 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.071+0000: 4917: debug : virNetMessageNew:46 : msg=0x55c64bf0b240 tracked=0 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 2016-02-12 09:11:56.071+0000: 4917: debug : virNetMessageEncodePayload:376 : Encode length as 32 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientSendInternal:1946 : RPC_CLIENT_MSG_TX_QUEUE: client=0x55c64bf0b3f0 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=3 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientCallNew:1899 : New call 0x55c64bf0bd50: msg=0x55c64bf0b240, expectReply=1, nonBlock=0 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientIO:1708 : Outgoing message prog=536903814 version=1 serial=3 proc=60 type=0 length=32 dispatch=(nil) 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 2016-02-12 09:11:56.071+0000: 4917: debug : virNetClientIO:1767 : We have the buck head=0x55c64bf0bd50 call=0x55c64bf0bd50 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.071+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 2016-02-12 09:11:56.071+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.071+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending 2016-02-12 09:11:56.071+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 2016-02-12 09:11:56.072+0000: 4917: debug : virNetMessageDecodeLength:151 : Got length, now need 32 total (28 more) 2016-02-12 09:11:56.072+0000: 4917: debug : virNetClientCallDispatch:1117 : RPC_CLIENT_MSG_RX: client=0x55c64bf0b3f0 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=3 2016-02-12 09:11:56.072+0000: 4917: debug : virKeepAliveCheckMessage:377 : ka=0x55c64bf0b6e0, client=0x55c64bf0b3f0, msg=0x55c64bf0b458 2016-02-12 09:11:56.072+0000: 4917: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 2016-02-12 09:11:56.072+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck 0x55c64bf0bd50 2016-02-12 09:11:56.072+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to 2016-02-12 09:11:56.072+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 2016-02-12 09:11:56.072+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.072+0000: 4917: debug : virNetClientIO:1797 : All done with our call head=(nil) call=0x55c64bf0bd50 rv=0 2016-02-12 09:11:56.072+0000: 4917: debug : virNetMessageFree:74 : msg=0x55c64bf0b240 nfds=0 cb=(nil) 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1152 : driver 5 remote returned SUCCESS 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1176 : network driver 0 Test returned DECLINED 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 1 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1176 : network driver 1 ESX returned DECLINED 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1176 : network driver 2 remote returned SUCCESS 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1192 : interface driver 0 Test returned DECLINED 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1192 : interface driver 1 PHYP returned DECLINED 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1192 : interface driver 2 ESX returned DECLINED 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1192 : interface driver 3 remote returned SUCCESS 2016-02-12 09:11:56.072+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1209 : storage driver 0 Test returned DECLINED 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 1 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1209 : storage driver 1 PHYP returned DECLINED 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1209 : storage driver 2 ESX returned DECLINED 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1209 : storage driver 3 remote returned SUCCESS 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=1 d=0 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1226 : node driver 0 Test returned DECLINED 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 1 timers 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1226 : node driver 1 ESX returned DECLINED 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1226 : node driver 2 remote returned SUCCESS 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=2 timeout=-1 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1243 : secret driver 0 Test returned DECLINED 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1243 : secret driver 1 ESX returned DECLINED 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1243 : secret driver 2 remote returned SUCCESS 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1260 : nwfilter driver 0 Test returned DECLINED 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1260 : nwfilter driver 1 ESX returned DECLINED 2016-02-12 09:11:56.072+0000: 4917: debug : do_open:1260 : nwfilter driver 2 remote returned SUCCESS 2016-02-12 09:11:56.072+0000: 4917: debug : virConnectSetKeepAlive:20445 : conn=0x55c64bf0a080, interval=5, count=6 2016-02-12 09:11:56.072+0000: 4917: debug : virKeepAliveStart:279 : RPC_KEEPALIVE_START: ka=0x55c64bf0b6e0 client=0x55c64bf0b3f0 interval=5 count=6 2016-02-12 09:11:56.072+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.072+0000: 4917: debug : virEventPollAddTimeout:254 : EVENT_POLL_ADD_TIMEOUT: timer=2 frequency=5000 cb=0x7f56604ff250 opaque=0x55c64bf0b6e0 ff=0x7f56603f1e50 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.072+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0b6e0 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 2016-02-12 09:11:56.072+0000: 4917: debug : virConnectRegisterCloseCallback:20529 : conn=0x55c64bf0a080 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 2 2016-02-12 09:11:56.072+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0a080 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.072+0000: 4917: debug : virConnectIsAlive:20480 : conn=0x55c64bf0a080 2016-02-12 09:11:56.072+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:494 : i=1 w=2 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.073+0000: 4917: debug : virConnectListAllDomains:8950 : conn=0x55c64bf0a080, domains=0x55c64bf08ca0, flags=1 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.073+0000: 4917: debug : virNetMessageNew:46 : msg=0x55c64bf08e70 tracked=0 2016-02-12 09:11:56.073+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.073+0000: 4917: debug : virNetMessageEncodePayload:376 : Encode length as 36 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.073+0000: 4917: debug : virNetClientSendInternal:1946 : RPC_CLIENT_MSG_TX_QUEUE: client=0x55c64bf0b3f0 len=36 prog=536903814 vers=1 proc=273 type=0 status=0 serial=4 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.073+0000: 4917: debug : virNetClientCallNew:1899 : New call 0x55c64bf08e00: msg=0x55c64bf08e70, expectReply=1, nonBlock=0 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.073+0000: 4917: debug : virNetClientIO:1708 : Outgoing message prog=536903814 version=1 serial=4 proc=273 type=0 length=36 dispatch=(nil) 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=1 d=0 2016-02-12 09:11:56.073+0000: 4917: debug : virNetClientIO:1767 : We have the buck head=0x55c64bf08e00 call=0x55c64bf08e00 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers 2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321072 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321072 now=1455268316073 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321072 due in 4999 ms 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=2 timeout=4999 2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 2 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.073+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1455268321073 2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321073 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321073 now=1455268316073 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321073 due in 5000 ms 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=5000 2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000 2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1455268321073 2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.073+0000: 4917: debug : virNetMessageDecodeLength:151 : Got length, now need 36 total (32 more) 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 2016-02-12 09:11:56.073+0000: 4917: debug : virNetClientCallDispatch:1117 : RPC_CLIENT_MSG_RX: client=0x55c64bf0b3f0 len=36 prog=536903814 vers=1 proc=273 type=1 status=0 serial=4 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 2016-02-12 09:11:56.073+0000: 4917: debug : virKeepAliveCheckMessage:377 : ka=0x55c64bf0b6e0, client=0x55c64bf0b3f0, msg=0x55c64bf0b458 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.073+0000: 4917: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000 2016-02-12 09:11:56.073+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.074+0000: 4917: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1455268321074 2016-02-12 09:11:56.074+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.074+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 2016-02-12 09:11:56.074+0000: 4917: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.074+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck 0x55c64bf08e00 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.074+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.074+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321074 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321074 now=1455268316074 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321074 due in 5000 ms 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=5000 2016-02-12 09:11:56.074+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.074+0000: 4917: debug : virNetClientIO:1797 : All done with our call head=(nil) call=0x55c64bf08e00 rv=0 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 2016-02-12 09:11:56.074+0000: 4917: debug : virNetMessageFree:74 : msg=0x55c64bf08e70 nfds=0 cb=(nil) 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.074+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=1 d=0 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321074 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321074 now=1455268316074 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321074 due in 5000 ms 2016-02-12 09:11:56.074+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=2 timeout=5000 Id Name State ---------------------------------------------------- 2016-02-12 09:11:56.084+0000: 4917: debug : virConnectUnregisterCloseCallback:20584 : conn=0x55c64bf0a080 2016-02-12 09:11:56.084+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0a080 2016-02-12 09:11:56.084+0000: 4917: debug : virConnectClose:1435 : conn=0x55c64bf0a080 2016-02-12 09:11:56.084+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0a080 2016-02-12 09:11:56.084+0000: 4917: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0a080 2016-02-12 09:11:56.084+0000: 4917: debug : virNetMessageNew:46 : msg=0x55c64bf08c10 tracked=0 2016-02-12 09:11:56.084+0000: 4917: debug : virNetMessageEncodePayload:376 : Encode length as 28 2016-02-12 09:11:56.084+0000: 4917: debug : virNetClientSendInternal:1946 : RPC_CLIENT_MSG_TX_QUEUE: client=0x55c64bf0b3f0 len=28 prog=536903814 vers=1 proc=2 type=0 status=0 serial=5 2016-02-12 09:11:56.084+0000: 4917: debug : virNetClientCallNew:1899 : New call 0x55c64bf0bac0: msg=0x55c64bf08c10, expectReply=1, nonBlock=0 2016-02-12 09:11:56.084+0000: 4917: debug : virNetClientIO:1708 : Outgoing message prog=536903814 version=1 serial=5 proc=2 type=0 length=28 dispatch=(nil) 2016-02-12 09:11:56.084+0000: 4917: debug : virNetClientIO:1767 : We have the buck head=0x55c64bf0bac0 call=0x55c64bf0bac0 2016-02-12 09:11:56.084+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 2016-02-12 09:11:56.084+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.084+0000: 4917: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000 2016-02-12 09:11:56.084+0000: 4917: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1455268321084 2016-02-12 09:11:56.084+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 2 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.084+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321084 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321084 now=1455268316084 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321084 due in 5000 ms 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=5000 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.084+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.084+0000: 4917: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321084 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321084 now=1455268316084 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321084 due in 5000 ms 2016-02-12 09:11:56.084+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=5000 2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1455268321084 2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.085+0000: 4917: debug : virNetMessageDecodeLength:151 : Got length, now need 28 total (24 more) 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientCallDispatch:1117 : RPC_CLIENT_MSG_RX: client=0x55c64bf0b3f0 len=28 prog=536903814 vers=1 proc=2 type=1 status=0 serial=5 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.085+0000: 4917: debug : virKeepAliveCheckMessage:377 : ka=0x55c64bf0b6e0, client=0x55c64bf0b3f0, msg=0x55c64bf0b458 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollUpdateTimeout:266 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.085+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollUpdateTimeout:284 : Set timer freq=5000 expires=1455268321085 2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 2016-02-12 09:11:56.085+0000: 4917: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck 0x55c64bf0bac0 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=1 w=2, f=6 e=0 d=0 2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCalculateTimeout:348 : Got a timeout scheduled for 1455268321085 2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCalculateTimeout:361 : Schedule timeout then=1455268321085 now=1455268316085 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCalculateTimeout:370 : Timeout at 1455268321085 due in 5000 ms 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=5000 2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientIO:1797 : All done with our call head=(nil) call=0x55c64bf0bac0 rv=0 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 2 2016-02-12 09:11:56.085+0000: 4917: debug : virNetMessageFree:74 : msg=0x55c64bf08c10 nfds=0 cb=(nil) 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientCloseInternal:690 : client=0x55c64bf0b3f0 wantclose=0 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientMarkClose:636 : client=0x55c64bf0b3f0, reason=3 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollRemoveHandle:186 : EVENT_POLL_REMOVE_HANDLE: watch=2 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.085+0000: 4918: debug : virEventRunDefaultImpl:305 : running default event implementation 2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollRemoveHandle:199 : mark delete 1 6 2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1414 : Giving up the buck (nil) 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 2 2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientIOEventLoopPassTheBuck:1428 : No thread to pass the buck to 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 2 2016-02-12 09:11:56.085+0000: 4917: debug : virNetClientCloseLocked:649 : client=0x55c64bf0b3f0, sock=0x55c64bf0aff0, reason=3 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCleanupHandles:589 : EVENT_POLL_PURGE_HANDLE: watch=2 2016-02-12 09:11:56.085+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0aff0 2016-02-12 09:11:56.085+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b3f0 2016-02-12 09:11:56.085+0000: 4917: debug : virObjectRef:296 : OBJECT_REF: obj=0x55c64bf0b3f0 2016-02-12 09:11:56.085+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0aff0 2016-02-12 09:11:56.085+0000: 4917: debug : virKeepAliveStop:310 : RPC_KEEPALIVE_STOP: ka=0x55c64bf0b6e0 client=0x55c64bf0b3f0 2016-02-12 09:11:56.085+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0aff0 2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollRemoveTimeout:307 : EVENT_POLL_REMOVE_TIMEOUT: timer=2 2016-02-12 09:11:56.085+0000: 4918: debug : virNetSocketDispose:1137 : RPC_SOCKET_DISPOSE: sock=0x55c64bf0aff0 2016-02-12 09:11:56.085+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollRemoveHandle:186 : EVENT_POLL_REMOVE_HANDLE: watch=2 2016-02-12 09:11:56.085+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b6e0 2016-02-12 09:11:56.085+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b3f0 2016-02-12 09:11:56.085+0000: 4918: debug : virFileClose:99 : Closed fd 6 2016-02-12 09:11:56.085+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b3f0 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollMakePollFDs:402 : Prepare n=0 w=1, f=4 e=1 d=0 2016-02-12 09:11:56.085+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b600 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCalculateTimeout:340 : Calculate expiry of 2 timers 2016-02-12 09:11:56.085+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b1d0 2016-02-12 09:11:56.085+0000: 4918: debug : virEventPollCalculateTimeout:372 : No timeout is pending 2016-02-12 09:11:56.086+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0af80 2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollRunOnce:642 : EVENT_POLL_RUN: nhandles=1 timeout=-1 2016-02-12 09:11:56.086+0000: 4917: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf05870 2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollRunOnce:653 : Poll got 1 event(s) 2016-02-12 09:11:56.086+0000: 4917: debug : virEventPollInterruptLocked:729 : Interrupting 2016-02-12 09:11:56.086+0000: 4917: debug : virEventPollAddTimeout:254 : EVENT_POLL_ADD_TIMEOUT: timer=3 frequency=0 cb=0x55c64b93a000 opaque=(nil) ff=(nil) 2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollDispatchTimeouts:434 : Dispatch 3 2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollDispatchTimeouts:457 : EVENT_POLL_DISPATCH_TIMEOUT: timer=3 2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollDispatchHandles:480 : Dispatch 1 2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollDispatchHandles:494 : i=0 w=1 2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollDispatchHandles:508 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollCleanupTimeouts:527 : Cleanup 3 2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollCleanupTimeouts:540 : EVENT_POLL_PURGE_TIMEOUT: timer=2 2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b6e0 2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0b6e0 2016-02-12 09:11:56.086+0000: 4918: debug : virKeepAliveDispose:230 : RPC_KEEPALIVE_DISPOSE: ka=0x55c64bf0b6e0 2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b3f0 2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0b3f0 2016-02-12 09:11:56.086+0000: 4918: debug : virNetClientDispose:602 : RPC_CLIENT_DISPOSE: client=0x55c64bf0b3f0 2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf05870 2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf05870 2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b600 2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0b600 2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0b1d0 2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0b1d0 2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:259 : OBJECT_UNREF: obj=0x55c64bf0af80 2016-02-12 09:11:56.086+0000: 4918: debug : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x55c64bf0af80 2016-02-12 09:11:56.086+0000: 4918: debug : virFileClose:99 : Closed fd 8 2016-02-12 09:11:56.086+0000: 4918: debug : virFileClose:99 : Closed fd 7 2016-02-12 09:11:56.086+0000: 4918: debug : virNetMessageClear:57 : msg=0x55c64bf0b458 nfds=0 2016-02-12 09:11:56.086+0000: 4918: debug : virEventPollCleanupHandles:576 : Cleanup 1 2016-02-12 09:11:56.086+0000: 4917: debug : virEventPollRemoveTimeout:307 : EVENT_POLL_REMOVE_TIMEOUT: timer=3 2016-02-12 09:11:56.086+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744 2016-02-12 09:11:56.086+0000: 4917: debug : virEventPollRemoveTimeout:307 : EVENT_POLL_REMOVE_TIMEOUT: timer=1 2016-02-12 09:11:56.086+0000: 4917: debug : virEventPollInterruptLocked:725 : Skip interrupt, 0 140008837887744
View Attachment As Raw
Actions:
View
Attachments on
bug 17417
:
7449
| 7450