Attachment 1459053 Details for Bug 1601318 – libvirtd log

libvirtd log

libvirtd.log (text/plain), 89.53 KB, created by chhu on 2018-07-16 05:33:09 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: chhu

Created: 2018-07-16 05:33:09 UTC

Size: 89.53 KB

patch

obsolete

>
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetSocketNew:234 : localAddr=0x7fff40ad5990 remoteAddr=0x7fff40ad5a20 fd=18 errfd=-1 pid=0
>2018-07-16 02:49:46.214+0000: 30727: info : virNetSocketNew:290 : RPC_SOCKET_NEW: sock=0x5555f655f120 fd=18 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetServerClientNew:450 : sock=0x5555f655f120 auth=2 tls=(nil)
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f655eef0 tracked=1
>2018-07-16 02:49:46.214+0000: 30727: info : virNetServerClientNewInternal:425 : RPC_SERVER_CLIENT_NEW: client=0x5555f655f460 sock=0x5555f655f120
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655eef0 tx=(nil)
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetServerClientRegisterEvent:205 : Registering client event callback 1
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetServerCheckLimits:251 : Checking client-related limits to re-enable or temporarily suspend services: nclients=1 nclients_max=5000 nclients_unauth=1 nclients_unauth_max=20
>2018-07-16 02:49:46.214+0000: 30727: info : virNetServerCheckLimits:270 : Re-enabling services
>2018-07-16 02:49:46.214+0000: 30727: debug : virKeepAliveNew:193 : client=0x5555f655f460, interval=5, count=5
>2018-07-16 02:49:46.214+0000: 30727: info : virKeepAliveNew:212 : RPC_KEEPALIVE_NEW: ka=0x5555f65017b0 client=0x5555f655f460
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 28 total (24 more)
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655eef0 tx=(nil)
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.214+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
>2018-07-16 02:49:46.214+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65017b0, client=0x5555f655f460, msg=0x5555f655eef0
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f6501670 tracked=1
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6501670 tx=(nil)
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.214+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655eef0
>2018-07-16 02:49:46.214+0000: 30734: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655eef0 prog=0x5555f655ca40
>2018-07-16 02:49:46.214+0000: 30734: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
>2018-07-16 02:49:46.216+0000: 30734: debug : virFileClose:111 : Closed fd 22
>2018-07-16 02:49:46.216+0000: 30734: debug : virIdentitySetAttr:245 : ident=0x7fece80009c0 attribute=0 value=root
>2018-07-16 02:49:46.216+0000: 30734: debug : virIdentitySetAttr:245 : ident=0x7fece80009c0 attribute=1 value=0
>2018-07-16 02:49:46.216+0000: 30734: debug : virIdentitySetAttr:245 : ident=0x7fece80009c0 attribute=2 value=root
>2018-07-16 02:49:46.216+0000: 30734: debug : virIdentitySetAttr:245 : ident=0x7fece80009c0 attribute=3 value=0
>2018-07-16 02:49:46.216+0000: 30734: debug : virIdentitySetAttr:245 : ident=0x7fece80009c0 attribute=4 value=30913
>2018-07-16 02:49:46.216+0000: 30734: debug : virIdentitySetAttr:245 : ident=0x7fece80009c0 attribute=5 value=57996652
>2018-07-16 02:49:46.216+0000: 30734: debug : virIdentitySetAttr:245 : ident=0x7fece80009c0 attribute=8 value=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023
>2018-07-16 02:49:46.216+0000: 30734: debug : virThreadJobSet:96 : Thread 30734 (virNetServerHandleJob) is now running job remoteDispatchAuthList
>2018-07-16 02:49:46.216+0000: 30734: debug : remoteDispatchAuthListHelper:21 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f655eef0 rerr=0x7fed077fdc10 args=0x7fece8000900 ret=0x7fece80009a0
>2018-07-16 02:49:46.216+0000: 30734: debug : virFileClose:111 : Closed fd 22
>2018-07-16 02:49:46.216+0000: 30734: info : remoteDispatchAuthList:3341 : Bypass polkit auth for privileged client pid:30913,uid:0
>2018-07-16 02:49:46.216+0000: 30734: debug : virNetServerCheckLimits:251 : Checking client-related limits to re-enable or temporarily suspend services: nclients=1 nclients_max=5000 nclients_unauth=0 nclients_unauth_max=20
>2018-07-16 02:49:46.216+0000: 30734: info : virNetServerCheckLimits:270 : Re-enabling services
>2018-07-16 02:49:46.217+0000: 30734: debug : virThreadJobClear:121 : Thread 30734 (virNetServerHandleJob) finished job remoteDispatchAuthList with ret=0
>2018-07-16 02:49:46.217+0000: 30734: debug : virNetMessageEncodePayload:389 : Encode length as 36
>2018-07-16 02:49:46.217+0000: 30734: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f655eef0 proc=66 len=36 offset=0
>2018-07-16 02:49:46.217+0000: 30734: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
>2018-07-16 02:49:46.217+0000: 30734: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6501670 tx=0x5555f655eef0
>2018-07-16 02:49:46.217+0000: 30734: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f655eef0 nfds=0 cb=(nil)
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6501670 tx=(nil)
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 32 total (28 more)
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6501670 tx=(nil)
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.217+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
>2018-07-16 02:49:46.217+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65017b0, client=0x5555f655f460, msg=0x5555f6501670
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f65016e0 tracked=1
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65016e0 tx=(nil)
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f6501670
>2018-07-16 02:49:46.217+0000: 30733: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f6501670 prog=0x5555f655ca40
>2018-07-16 02:49:46.217+0000: 30733: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=60
>2018-07-16 02:49:46.217+0000: 30733: debug : virThreadJobSet:96 : Thread 30733 (virNetServerHandleJob) is now running job remoteDispatchConnectSupportsFeature
>2018-07-16 02:49:46.217+0000: 30733: debug : remoteDispatchConnectSupportsFeatureHelper:3482 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f6501670 rerr=0x7fed0ca70c10 args=0x7fecec000900 ret=0x7fecec0009a0
>2018-07-16 02:49:46.217+0000: 30733: info : virKeepAliveStart:273 : RPC_KEEPALIVE_START: ka=0x5555f65017b0 client=0x5555f655f460 interval=0 count=0
>2018-07-16 02:49:46.217+0000: 30733: debug : virThreadJobClear:121 : Thread 30733 (virNetServerHandleJob) finished job remoteDispatchConnectSupportsFeature with ret=0
>2018-07-16 02:49:46.217+0000: 30733: debug : virNetMessageEncodePayload:389 : Encode length as 32
>2018-07-16 02:49:46.217+0000: 30733: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f6501670 proc=60 len=32 offset=0
>2018-07-16 02:49:46.217+0000: 30733: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
>2018-07-16 02:49:46.217+0000: 30733: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65016e0 tx=0x5555f6501670
>2018-07-16 02:49:46.217+0000: 30733: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f6501670 nfds=0 cb=(nil)
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65016e0 tx=(nil)
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 40 total (36 more)
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65016e0 tx=(nil)
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.217+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
>2018-07-16 02:49:46.217+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65017b0, client=0x5555f655f460, msg=0x5555f65016e0
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f655eef0 tracked=1
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655eef0 tx=(nil)
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f65016e0
>2018-07-16 02:49:46.217+0000: 30735: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f65016e0 prog=0x5555f655ca40
>2018-07-16 02:49:46.217+0000: 30735: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=1
>2018-07-16 02:49:46.217+0000: 30735: debug : virThreadJobSet:96 : Thread 30735 (virNetServerHandleJob) is now running job remoteDispatchConnectOpen
>2018-07-16 02:49:46.217+0000: 30735: debug : remoteDispatchConnectOpenHelper:3324 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f65016e0 rerr=0x7fed06ffcc10 args=0x7fece0000900 ret=0x7fece00009a0
>2018-07-16 02:49:46.217+0000: 30735: debug : remoteDispatchConnectOpen:1809 : priv=0x5555f655f860 conn=(nil)
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpen:1116 : name=
>2018-07-16 02:49:46.217+0000: 30735: debug : virConfLoadConfig:1576 : Loading config file '/etc/libvirt/libvirt.conf'
>2018-07-16 02:49:46.217+0000: 30735: debug : virConfReadFile:752 : filename=/etc/libvirt/libvirt.conf
>2018-07-16 02:49:46.217+0000: 30735: debug : virFileClose:111 : Closed fd 22
>2018-07-16 02:49:46.217+0000: 30735: debug : virConfGetValueString:897 : Get value string (nil) 0
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:899 : Trying to probe for default URI
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:906 : QEMU driver URI probe returned 'qemu:///system'
>2018-07-16 02:49:46.217+0000: 30735: debug : virConfGetValueStringList:946 : Get value string list (nil) 0
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:944 : Split "qemu:///system" to URI components:
>  scheme qemu
>  server <null>
>  user <null>
>  port 0
>  path /system
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1001 : trying driver 0 (Test) ...
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1001 : trying driver 1 (ESX) ...
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1001 : trying driver 2 (remote) ...
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1029 : Matching any URI scheme for 'qemu'
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1045 : driver 2 remote returned DECLINED
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1001 : trying driver 3 (network) ...
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1001 : trying driver 4 (interface) ...
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1001 : trying driver 5 (storage) ...
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1001 : trying driver 6 (nodedev) ...
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1001 : trying driver 7 (secret) ...
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1001 : trying driver 8 (nwfilter) ...
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1001 : trying driver 9 (QEMU) ...
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1019 : Matched URI scheme 'qemu'
>2018-07-16 02:49:46.217+0000: 30735: debug : virAccessManagerCheckConnect:215 : manager=0x5555f6500ea0(name=stack) driver=QEMU perm=0
>2018-07-16 02:49:46.217+0000: 30735: debug : virAccessManagerCheckConnect:215 : manager=0x5555f6500f80(name=none) driver=QEMU perm=0
>2018-07-16 02:49:46.217+0000: 30735: debug : virConnectOpenInternal:1045 : driver 9 QEMU returned SUCCESS
>2018-07-16 02:49:46.217+0000: 30735: debug : virThreadJobClear:121 : Thread 30735 (virNetServerHandleJob) finished job remoteDispatchConnectOpen with ret=0
>2018-07-16 02:49:46.217+0000: 30735: debug : virNetMessageEncodePayload:389 : Encode length as 28
>2018-07-16 02:49:46.217+0000: 30735: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f65016e0 proc=1 len=28 offset=0
>2018-07-16 02:49:46.217+0000: 30735: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2
>2018-07-16 02:49:46.217+0000: 30735: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655eef0 tx=0x5555f65016e0
>2018-07-16 02:49:46.217+0000: 30735: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:49:46.217+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f65016e0 nfds=0 cb=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655eef0 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 28 total (24 more)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655eef0 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2018-07-16 02:49:46.218+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65017b0, client=0x5555f655f460, msg=0x5555f655eef0
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f6562f70 tracked=1
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655eef0
>2018-07-16 02:49:46.218+0000: 30729: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655eef0 prog=0x5555f655ca40
>2018-07-16 02:49:46.218+0000: 30729: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110
>2018-07-16 02:49:46.218+0000: 30729: debug : virThreadJobSet:96 : Thread 30729 (virNetServerHandleJob) is now running job remoteDispatchConnectGetURI
>2018-07-16 02:49:46.218+0000: 30729: debug : remoteDispatchConnectGetURIHelper:1187 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f655eef0 rerr=0x7fed0e273c10 args=0x7fecf0000900 ret=0x7fecf0000920
>2018-07-16 02:49:46.218+0000: 30729: debug : virConnectGetURI:255 : conn=0x7fece0000c30
>2018-07-16 02:49:46.218+0000: 30729: debug : virThreadJobClear:121 : Thread 30729 (virNetServerHandleJob) finished job remoteDispatchConnectGetURI with ret=0
>2018-07-16 02:49:46.218+0000: 30729: debug : virNetMessageEncodePayload:389 : Encode length as 48
>2018-07-16 02:49:46.218+0000: 30729: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f655eef0 proc=110 len=48 offset=0
>2018-07-16 02:49:46.218+0000: 30729: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2018-07-16 02:49:46.218+0000: 30729: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=0x5555f655eef0
>2018-07-16 02:49:46.218+0000: 30729: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f655eef0 nfds=0 cb=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 32 total (28 more)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=4
>2018-07-16 02:49:46.218+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65017b0, client=0x5555f655f460, msg=0x5555f6562f70
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f655ef60 tracked=1
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655ef60 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f6562f70
>2018-07-16 02:49:46.218+0000: 30737: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f6562f70 prog=0x5555f655ca40
>2018-07-16 02:49:46.218+0000: 30737: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=60
>2018-07-16 02:49:46.218+0000: 30737: debug : virThreadJobSet:96 : Thread 30737 (virNetServerHandleJob) is now running job remoteDispatchConnectSupportsFeature
>2018-07-16 02:49:46.218+0000: 30737: debug : remoteDispatchConnectSupportsFeatureHelper:3482 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f6562f70 rerr=0x7fed05ffac10 args=0x7fecd8000900 ret=0x7fecd80009a0
>2018-07-16 02:49:46.218+0000: 30737: debug : virThreadJobClear:121 : Thread 30737 (virNetServerHandleJob) finished job remoteDispatchConnectSupportsFeature with ret=0
>2018-07-16 02:49:46.218+0000: 30737: debug : virNetMessageEncodePayload:389 : Encode length as 32
>2018-07-16 02:49:46.218+0000: 30737: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f6562f70 proc=60 len=32 offset=0
>2018-07-16 02:49:46.218+0000: 30737: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=4
>2018-07-16 02:49:46.218+0000: 30737: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655ef60 tx=0x5555f6562f70
>2018-07-16 02:49:46.218+0000: 30737: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f6562f70 nfds=0 cb=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655ef60 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 32 total (28 more)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655ef60 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=5
>2018-07-16 02:49:46.218+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65017b0, client=0x5555f655f460, msg=0x5555f655ef60
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f655f320 tracked=1
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f320 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655ef60
>2018-07-16 02:49:46.218+0000: 30728: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655ef60 prog=0x5555f655ca40
>2018-07-16 02:49:46.218+0000: 30728: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=60
>2018-07-16 02:49:46.218+0000: 30728: debug : virThreadJobSet:96 : Thread 30728 (virNetServerHandleJob) is now running job remoteDispatchConnectSupportsFeature
>2018-07-16 02:49:46.218+0000: 30728: debug : remoteDispatchConnectSupportsFeatureHelper:3482 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f655ef60 rerr=0x7fed0ea74c10 args=0x7fed08000900 ret=0x7fed080009a0
>2018-07-16 02:49:46.218+0000: 30728: debug : virThreadJobClear:121 : Thread 30728 (virNetServerHandleJob) finished job remoteDispatchConnectSupportsFeature with ret=0
>2018-07-16 02:49:46.218+0000: 30728: debug : virNetMessageEncodePayload:389 : Encode length as 32
>2018-07-16 02:49:46.218+0000: 30728: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f655ef60 proc=60 len=32 offset=0
>2018-07-16 02:49:46.218+0000: 30728: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=5
>2018-07-16 02:49:46.218+0000: 30728: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f320 tx=0x5555f655ef60
>2018-07-16 02:49:46.218+0000: 30728: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f655ef60 nfds=0 cb=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f320 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 28 total (24 more)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f320 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=360 type=0 status=0 serial=6
>2018-07-16 02:49:46.218+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65017b0, client=0x5555f655f460, msg=0x5555f655f320
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f655eef0 tracked=1
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655eef0 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655f320
>2018-07-16 02:49:46.218+0000: 30731: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655f320 prog=0x5555f655ca40
>2018-07-16 02:49:46.218+0000: 30731: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=360
>2018-07-16 02:49:46.218+0000: 30731: debug : virThreadJobSet:96 : Thread 30731 (virNetServerHandleJob) is now running job remoteDispatchConnectRegisterCloseCallback
>2018-07-16 02:49:46.218+0000: 30731: debug : remoteDispatchConnectRegisterCloseCallbackHelper:3349 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f655f320 rerr=0x7fed0d271c10 args=0x7fecf8000900 ret=0x7fecf8000920
>2018-07-16 02:49:46.218+0000: 30731: debug : virConnectRegisterCloseCallback:1375 : conn=0x7fece0000c30
>2018-07-16 02:49:46.218+0000: 30731: debug : virThreadJobClear:121 : Thread 30731 (virNetServerHandleJob) finished job remoteDispatchConnectRegisterCloseCallback with ret=0
>2018-07-16 02:49:46.218+0000: 30731: debug : virNetMessageEncodePayload:389 : Encode length as 28
>2018-07-16 02:49:46.218+0000: 30731: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f655f320 proc=360 len=28 offset=0
>2018-07-16 02:49:46.218+0000: 30731: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=360 type=1 status=0 serial=6
>2018-07-16 02:49:46.218+0000: 30731: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655eef0 tx=0x5555f655f320
>2018-07-16 02:49:46.218+0000: 30731: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f655f320 nfds=0 cb=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655eef0 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 4672 total (4668 more)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655eef0 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=4672 prog=536903814 vers=1 proc=11 type=0 status=0 serial=7
>2018-07-16 02:49:46.218+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65017b0, client=0x5555f655f460, msg=0x5555f655eef0
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f655efd0 tracked=1
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655efd0 tx=(nil)
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.218+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655eef0
>2018-07-16 02:49:46.218+0000: 30730: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655eef0 prog=0x5555f655ca40
>2018-07-16 02:49:46.219+0000: 30730: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=7 proc=11
>2018-07-16 02:49:46.219+0000: 30730: debug : virThreadJobSet:96 : Thread 30730 (virNetServerHandleJob) is now running job remoteDispatchDomainDefineXML
>2018-07-16 02:49:46.219+0000: 30730: debug : remoteDispatchDomainDefineXMLHelper:4635 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f655eef0 rerr=0x7fed0da72c10 args=0x7fed00000900 ret=0x7fed000009a0
>2018-07-16 02:49:46.219+0000: 30730: debug : virDomainDefineXML:6150 : conn=0x7fece0000c30, xml=<domain type='kvm'>
>  <name>r7</name>
>  <memory unit='KiB'>2048576</memory>
>  <currentMemory unit='KiB'>2048576</currentMemory>
>  <vcpu placement='static' cpuset='0-1'>2</vcpu>
>  <resource>
>    <partition>/machine</partition>
>  </resource>
>  <os>
>    <type arch='x86_64' machine='pc-i440fx-rhel7.6.0'>hvm</type>
>    <bootmenu enable='yes' timeout='3000'/>
>    <bios useserial='yes' rebootTimeout='3000'/>
>  </os>
>  <features>
>    <acpi/>
>    <apic/>
>    <pmu state='on'/>
>    <vmport state='off'/>
>  </features>
>  <cpu mode='host-model' check='partial'>
>    <model fallback='allow'/>
>  </cpu>
>  <clock offset='utc'>
>    <timer name='rtc' tickpolicy='catchup'/>
>    <timer name='pit' tickpolicy='delay'/>
>    <timer name='hpet' present='no'/>
>  </clock>
>  <on_poweroff>destroy</on_poweroff>
>  <on_reboot>restart</on_reboot>
>  <on_crash>coredump-restart</on_crash>
>  <pm>
>    <suspend-to-mem enabled='no'/>
>    <suspend-to-disk enabled='no'/>
>  </pm>
>  <devices>
>    <emulator>/usr/libexec/qemu-kvm</emulator>
>    <disk type='file' device='disk'>
>      <driver name='qemu' type='raw' cache='none'/>
>      <source file='/var/lib/libvirt/images/t.qcow2'/>
>      <target dev='vda' bus='virtio'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x07' function='0x0'/>
>    </disk>
>    <interface type='hostdev' managed='yes'>
>      <mac address='00:16:3e:77:e2:ed'/>
>      <source>
>      <address type='pci' domain='0x0000' bus='0x08' slot='0x10' function='0x3'/>
>      </source>
>      <boot order='1'/>
>      <rom bar='on' file='/usr/share/ipxe/808610ca.rom'/>
>    </interface>
>    <controller type='usb' index='0' model='ich9-ehci1'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x7'/>
>    </controller>
>    <controller type='usb' index='0' model='ich9-uhci1'>
>      <master startport='0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x0' multifunction='on'/>
>    </controller>
>    <controller type='usb' index='0' model='ich9-uhci2'>
>      <master startport='2'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x1'/>
>    </controller>
>    <controller type='usb' index='0' model='ich9-uhci3'>
>      <master startport='4'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x2'/>
>    </controller>
>    <controller type='virtio-serial' index='0'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x05' function='0x0'/>
>    </controller>
>    <controller type='pci' index='0' model='pci-root'/>
>    <controller type='ide' index='0'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x1'/>
>    </controller>
>    <controller type='sata' index='0'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x0a' function='0x0'/>
>    </controller>
>    <serial type='pty'>
>      <target type='isa-serial' port='0'>
>        <model name='isa-serial'/>
>      </target>
>    </serial>
>    <console type='pty'>
>      <target type='serial' port='0'/>
>    </console>
>    <channel type='unix'>
>      <target type='virtio' name='org.qemu.guest_agent.0'/>
>      <address type='virtio-serial' controller='0' bus='0' port='1'/>
>    </channel>
>    <channel type='spicevmc'>
>      <target type='virtio' name='com.redhat.spice.0'/>
>      <address type='virtio-serial' controller='0' bus='0' port='2'/>
>    </channel>
>    <input type='tablet' bus='usb'>
>      <address type='usb' bus='0' port='1'/>
>    </input>
>    <input type='mouse' bus='ps2'/>
>    <input type='keyboard' bus='ps2'/>
>    <graphics type='spice' autoport='yes'>
>      <listen type='address'/>
>    </graphics>
>    <graphics type='vnc' port='-1' autoport='yes'>
>      <listen type='address'/>
>    </graphics>
>    <sound model='ich6'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x04' function='0x0'/>
>    </sound>
>    <video>
>      <model type='virtio' heads='1' primary='yes'>
>        <acceleration accel3d='no'/>
>      </model>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x03' function='0x0'/>
>    </video>
>    <redirdev bus='usb' type='spicevmc'>
>      <address type='usb' bus='0' port='2'/>
>    </redirdev>
>    <redirdev bus='usb' type='spicevmc'>
>      <address type='usb' bus='0' port='3'/>
>    </redirdev>
>    <watchdog model='ib700' action='dump'/>
>    <memballoon model='virtio'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x08' function='0x0'/>
>    </memballoon>
>    <rng model='virtio'>
>      <backend model='random'>/dev/random</backend>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x09' function='0x0'/>
>    </rng>
>    <panic model='isa'/>
>  </devices>
></domain>
>
>
>2018-07-16 02:49:46.223+0000: 30730: debug : virQEMUCapsCacheLookup:4633 : Returning caps 0x7feca4181400 for /usr/libexec/qemu-kvm
>2018-07-16 02:49:46.223+0000: 30730: error : virDomainDefCollectBootOrder:5041 : unsupported configuration: boot order '1' used for more than one device
>2018-07-16 02:49:46.223+0000: 30730: debug : virThreadJobClear:121 : Thread 30730 (virNetServerHandleJob) finished job remoteDispatchDomainDefineXML with ret=-1
>2018-07-16 02:49:46.223+0000: 30730: debug : virNetServerProgramSendError:150 : prog=536903814 ver=1 proc=11 type=1 serial=7 msg=0x5555f655eef0 rerr=0x7fed0da72c10
>2018-07-16 02:49:46.223+0000: 30730: debug : virNetMessageEncodePayload:389 : Encode length as 232
>2018-07-16 02:49:46.223+0000: 30730: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f655eef0 proc=11 len=232 offset=0
>2018-07-16 02:49:46.223+0000: 30730: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=232 prog=536903814 vers=1 proc=11 type=1 status=1 serial=7
>2018-07-16 02:49:46.223+0000: 30730: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655efd0 tx=0x5555f655eef0
>2018-07-16 02:49:46.223+0000: 30730: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:49:46.223+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f655eef0 nfds=0 cb=(nil)
>2018-07-16 02:49:46.223+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655efd0 tx=(nil)
>2018-07-16 02:49:46.223+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.223+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 28 total (24 more)
>2018-07-16 02:49:46.223+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655efd0 tx=(nil)
>2018-07-16 02:49:46.223+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.223+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=361 type=0 status=0 serial=8
>2018-07-16 02:49:46.223+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65017b0, client=0x5555f655f460, msg=0x5555f655efd0
>2018-07-16 02:49:46.223+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f6562f70 tracked=1
>2018-07-16 02:49:46.223+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=(nil)
>2018-07-16 02:49:46.223+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.223+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655efd0
>2018-07-16 02:49:46.224+0000: 30729: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655efd0 prog=0x5555f655ca40
>2018-07-16 02:49:46.224+0000: 30729: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=8 proc=361
>2018-07-16 02:49:46.224+0000: 30729: debug : virThreadJobSet:96 : Thread 30729 (virNetServerHandleJob) is now running job remoteDispatchConnectUnregisterCloseCallback
>2018-07-16 02:49:46.224+0000: 30729: debug : remoteDispatchConnectUnregisterCloseCallbackHelper:3507 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f655efd0 rerr=0x7fed0e273c10 args=0x7fecf0000b60 ret=0x7fecf0000b80
>2018-07-16 02:49:46.224+0000: 30729: debug : virConnectUnregisterCloseCallback:1410 : conn=0x7fece0000c30
>2018-07-16 02:49:46.224+0000: 30729: debug : virThreadJobClear:121 : Thread 30729 (virNetServerHandleJob) finished job remoteDispatchConnectUnregisterCloseCallback with ret=0
>2018-07-16 02:49:46.224+0000: 30729: debug : virNetMessageEncodePayload:389 : Encode length as 28
>2018-07-16 02:49:46.224+0000: 30729: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f655efd0 proc=361 len=28 offset=0
>2018-07-16 02:49:46.224+0000: 30729: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=361 type=1 status=0 serial=8
>2018-07-16 02:49:46.224+0000: 30729: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=0x5555f655efd0
>2018-07-16 02:49:46.224+0000: 30729: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f655efd0 nfds=0 cb=(nil)
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=(nil)
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 28 total (24 more)
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=(nil)
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.224+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=2 type=0 status=0 serial=9
>2018-07-16 02:49:46.224+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65017b0, client=0x5555f655f460, msg=0x5555f6562f70
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f655eef0 tracked=1
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655eef0 tx=(nil)
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f6562f70
>2018-07-16 02:49:46.224+0000: 30735: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f6562f70 prog=0x5555f655ca40
>2018-07-16 02:49:46.224+0000: 30735: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=9 proc=2
>2018-07-16 02:49:46.224+0000: 30735: debug : virThreadJobSet:96 : Thread 30735 (virNetServerHandleJob) is now running job remoteDispatchConnectClose
>2018-07-16 02:49:46.224+0000: 30735: debug : remoteDispatchConnectCloseHelper:271 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f6562f70 rerr=0x7fed06ffcc10 args=0x7fece00009a0 ret=0x7fece00016a0
>2018-07-16 02:49:46.224+0000: 30735: debug : virThreadJobClear:121 : Thread 30735 (virNetServerHandleJob) finished job remoteDispatchConnectClose with ret=0
>2018-07-16 02:49:46.224+0000: 30735: debug : virNetMessageEncodePayload:389 : Encode length as 28
>2018-07-16 02:49:46.224+0000: 30735: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f6562f70 proc=2 len=28 offset=0
>2018-07-16 02:49:46.224+0000: 30735: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=2 type=1 status=0 serial=9
>2018-07-16 02:49:46.224+0000: 30735: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655eef0 tx=0x5555f6562f70
>2018-07-16 02:49:46.224+0000: 30735: debug : virNetServerClientCalculateHandleMode:189 : mode=02
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f6562f70 nfds=0 cb=(nil)
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655eef0 tx=(nil)
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=00
>2018-07-16 02:49:46.224+0000: 30727: debug : virNetServerClientCloseLocked:1008 : client=0x5555f655f460
>2018-07-16 02:49:46.224+0000: 30727: info : virKeepAliveStop:304 : RPC_KEEPALIVE_STOP: ka=0x5555f65017b0 client=0x5555f655f460
>2018-07-16 02:49:46.224+0000: 30727: debug : daemonRemoveAllClientStreams:504 : stream=(nil)
>2018-07-16 02:49:46.224+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=4 value=30727
>2018-07-16 02:49:46.225+0000: 30727: debug : virFileClose:111 : Closed fd 22
>2018-07-16 02:49:46.225+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=5 value=57991862
>2018-07-16 02:49:46.225+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=0 value=root
>2018-07-16 02:49:46.225+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=1 value=0
>2018-07-16 02:49:46.226+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=2 value=root
>2018-07-16 02:49:46.226+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=3 value=0
>2018-07-16 02:49:46.226+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=8 value=system_u:system_r:virtd_t:s0-s0:c0.c1023
>2018-07-16 02:49:46.226+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f655eef0 nfds=0 cb=(nil)
>2018-07-16 02:49:46.226+0000: 30727: debug : virNetServerCheckLimits:251 : Checking client-related limits to re-enable or temporarily suspend services: nclients=0 nclients_max=5000 nclients_unauth=0 nclients_unauth_max=20
>2018-07-16 02:49:46.226+0000: 30727: info : virNetServerCheckLimits:270 : Re-enabling services
>2018-07-16 02:49:46.226+0000: 30727: info : virKeepAliveDispose:224 : RPC_KEEPALIVE_DISPOSE: ka=0x5555f65017b0
>2018-07-16 02:49:46.226+0000: 30727: info : virNetServerClientDispose:976 : RPC_SERVER_CLIENT_DISPOSE: client=0x5555f655f460
>2018-07-16 02:49:46.226+0000: 30727: debug : virConnectClose:1237 : conn=0x7fece0000c30
>2018-07-16 02:49:46.226+0000: 30727: debug : virConnectClose:1237 : conn=0x7fece0000c30
>2018-07-16 02:49:46.226+0000: 30727: debug : virConnectClose:1237 : conn=0x7fece0000c30
>2018-07-16 02:49:46.226+0000: 30727: debug : virConnectClose:1237 : conn=0x7fece0000c30
>2018-07-16 02:49:46.226+0000: 30727: debug : virConnectClose:1237 : conn=0x7fece0000c30
>2018-07-16 02:49:46.226+0000: 30727: debug : virConnectClose:1237 : conn=0x7fece0000c30
>2018-07-16 02:49:46.226+0000: 30727: debug : virConnectClose:1237 : conn=0x7fece0000c30
>2018-07-16 02:49:46.226+0000: 30727: debug : virCloseCallbacksRun:320 : conn=0x7fece0000c30
>2018-07-16 02:49:46.226+0000: 30727: info : virNetSocketDispose:1321 : RPC_SOCKET_DISPOSE: sock=0x5555f655f120
>2018-07-16 02:49:46.226+0000: 30727: debug : virFileClose:111 : Closed fd 18
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetSocketNew:234 : localAddr=0x7fff40ad5990 remoteAddr=0x7fff40ad5a20 fd=18 errfd=-1 pid=0
>2018-07-16 02:54:44.103+0000: 30727: info : virNetSocketNew:290 : RPC_SOCKET_NEW: sock=0x5555f655f120 fd=18 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetServerClientNew:450 : sock=0x5555f655f120 auth=2 tls=(nil)
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f655f860 tracked=1
>2018-07-16 02:54:44.103+0000: 30727: info : virNetServerClientNewInternal:425 : RPC_SERVER_CLIENT_NEW: client=0x5555f655f460 sock=0x5555f655f120
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f860 tx=(nil)
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetServerClientRegisterEvent:205 : Registering client event callback 1
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetServerCheckLimits:251 : Checking client-related limits to re-enable or temporarily suspend services: nclients=1 nclients_max=5000 nclients_unauth=1 nclients_unauth_max=20
>2018-07-16 02:54:44.103+0000: 30727: info : virNetServerCheckLimits:270 : Re-enabling services
>2018-07-16 02:54:44.103+0000: 30727: debug : virKeepAliveNew:193 : client=0x5555f655f460, interval=5, count=5
>2018-07-16 02:54:44.103+0000: 30727: info : virKeepAliveNew:212 : RPC_KEEPALIVE_NEW: ka=0x5555f65623b0 client=0x5555f655f460
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 28 total (24 more)
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f860 tx=(nil)
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.103+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
>2018-07-16 02:54:44.103+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65623b0, client=0x5555f655f460, msg=0x5555f655f860
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f65624f0 tracked=1
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65624f0 tx=(nil)
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.103+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655f860
>2018-07-16 02:54:44.103+0000: 30728: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655f860 prog=0x5555f655ca40
>2018-07-16 02:54:44.103+0000: 30728: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
>2018-07-16 02:54:44.105+0000: 30728: debug : virFileClose:111 : Closed fd 22
>2018-07-16 02:54:44.105+0000: 30728: debug : virIdentitySetAttr:245 : ident=0x7fed080009c0 attribute=0 value=root
>2018-07-16 02:54:44.106+0000: 30728: debug : virIdentitySetAttr:245 : ident=0x7fed080009c0 attribute=1 value=0
>2018-07-16 02:54:44.106+0000: 30728: debug : virIdentitySetAttr:245 : ident=0x7fed080009c0 attribute=2 value=root
>2018-07-16 02:54:44.106+0000: 30728: debug : virIdentitySetAttr:245 : ident=0x7fed080009c0 attribute=3 value=0
>2018-07-16 02:54:44.106+0000: 30728: debug : virIdentitySetAttr:245 : ident=0x7fed080009c0 attribute=4 value=31444
>2018-07-16 02:54:44.106+0000: 30728: debug : virIdentitySetAttr:245 : ident=0x7fed080009c0 attribute=5 value=58026441
>2018-07-16 02:54:44.106+0000: 30728: debug : virIdentitySetAttr:245 : ident=0x7fed080009c0 attribute=8 value=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023
>2018-07-16 02:54:44.106+0000: 30728: debug : virThreadJobSet:96 : Thread 30728 (virNetServerHandleJob) is now running job remoteDispatchAuthList
>2018-07-16 02:54:44.106+0000: 30728: debug : remoteDispatchAuthListHelper:21 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f655f860 rerr=0x7fed0ea74c10 args=0x7fed08000900 ret=0x7fed080009a0
>2018-07-16 02:54:44.106+0000: 30728: debug : virFileClose:111 : Closed fd 22
>2018-07-16 02:54:44.106+0000: 30728: info : remoteDispatchAuthList:3341 : Bypass polkit auth for privileged client pid:31444,uid:0
>2018-07-16 02:54:44.106+0000: 30728: debug : virNetServerCheckLimits:251 : Checking client-related limits to re-enable or temporarily suspend services: nclients=1 nclients_max=5000 nclients_unauth=0 nclients_unauth_max=20
>2018-07-16 02:54:44.106+0000: 30728: info : virNetServerCheckLimits:270 : Re-enabling services
>2018-07-16 02:54:44.106+0000: 30728: debug : virThreadJobClear:121 : Thread 30728 (virNetServerHandleJob) finished job remoteDispatchAuthList with ret=0
>2018-07-16 02:54:44.106+0000: 30728: debug : virNetMessageEncodePayload:389 : Encode length as 36
>2018-07-16 02:54:44.106+0000: 30728: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f655f860 proc=66 len=36 offset=0
>2018-07-16 02:54:44.106+0000: 30728: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
>2018-07-16 02:54:44.106+0000: 30728: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65624f0 tx=0x5555f655f860
>2018-07-16 02:54:44.106+0000: 30728: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f655f860 nfds=0 cb=(nil)
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65624f0 tx=(nil)
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 32 total (28 more)
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65624f0 tx=(nil)
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.106+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
>2018-07-16 02:54:44.106+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65623b0, client=0x5555f655f460, msg=0x5555f65624f0
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f6562f70 tracked=1
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=(nil)
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f65624f0
>2018-07-16 02:54:44.106+0000: 30731: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f65624f0 prog=0x5555f655ca40
>2018-07-16 02:54:44.106+0000: 30731: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=60
>2018-07-16 02:54:44.106+0000: 30731: debug : virThreadJobSet:96 : Thread 30731 (virNetServerHandleJob) is now running job remoteDispatchConnectSupportsFeature
>2018-07-16 02:54:44.106+0000: 30731: debug : remoteDispatchConnectSupportsFeatureHelper:3482 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f65624f0 rerr=0x7fed0d271c10 args=0x7fecf8000900 ret=0x7fecf80009a0
>2018-07-16 02:54:44.106+0000: 30731: info : virKeepAliveStart:273 : RPC_KEEPALIVE_START: ka=0x5555f65623b0 client=0x5555f655f460 interval=0 count=0
>2018-07-16 02:54:44.106+0000: 30731: debug : virThreadJobClear:121 : Thread 30731 (virNetServerHandleJob) finished job remoteDispatchConnectSupportsFeature with ret=0
>2018-07-16 02:54:44.106+0000: 30731: debug : virNetMessageEncodePayload:389 : Encode length as 32
>2018-07-16 02:54:44.106+0000: 30731: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f65624f0 proc=60 len=32 offset=0
>2018-07-16 02:54:44.106+0000: 30731: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
>2018-07-16 02:54:44.106+0000: 30731: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=0x5555f65624f0
>2018-07-16 02:54:44.106+0000: 30731: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f65624f0 nfds=0 cb=(nil)
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=(nil)
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 40 total (36 more)
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=(nil)
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.106+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
>2018-07-16 02:54:44.106+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65623b0, client=0x5555f655f460, msg=0x5555f6562f70
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f655f860 tracked=1
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f860 tx=(nil)
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.106+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f6562f70
>2018-07-16 02:54:44.106+0000: 30730: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f6562f70 prog=0x5555f655ca40
>2018-07-16 02:54:44.106+0000: 30730: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=1
>2018-07-16 02:54:44.106+0000: 30730: debug : virThreadJobSet:96 : Thread 30730 (virNetServerHandleJob) is now running job remoteDispatchConnectOpen
>2018-07-16 02:54:44.106+0000: 30730: debug : remoteDispatchConnectOpenHelper:3324 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f6562f70 rerr=0x7fed0da72c10 args=0x7fed00012c50 ret=0x7fed00013500
>2018-07-16 02:54:44.106+0000: 30730: debug : remoteDispatchConnectOpen:1809 : priv=0x5555f65621f0 conn=(nil)
>2018-07-16 02:54:44.106+0000: 30730: debug : virConnectOpen:1116 : name=
>2018-07-16 02:54:44.107+0000: 30730: debug : virConfLoadConfig:1576 : Loading config file '/etc/libvirt/libvirt.conf'
>2018-07-16 02:54:44.107+0000: 30730: debug : virConfReadFile:752 : filename=/etc/libvirt/libvirt.conf
>2018-07-16 02:54:44.107+0000: 30730: debug : virFileClose:111 : Closed fd 22
>2018-07-16 02:54:44.107+0000: 30730: debug : virConfGetValueString:897 : Get value string (nil) 0
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:899 : Trying to probe for default URI
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:906 : QEMU driver URI probe returned 'qemu:///system'
>2018-07-16 02:54:44.107+0000: 30730: debug : virConfGetValueStringList:946 : Get value string list (nil) 0
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:944 : Split "qemu:///system" to URI components:
>  scheme qemu
>  server <null>
>  user <null>
>  port 0
>  path /system
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1001 : trying driver 0 (Test) ...
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1001 : trying driver 1 (ESX) ...
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1001 : trying driver 2 (remote) ...
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1029 : Matching any URI scheme for 'qemu'
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1045 : driver 2 remote returned DECLINED
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1001 : trying driver 3 (network) ...
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1001 : trying driver 4 (interface) ...
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1001 : trying driver 5 (storage) ...
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1001 : trying driver 6 (nodedev) ...
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1001 : trying driver 7 (secret) ...
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1001 : trying driver 8 (nwfilter) ...
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1001 : trying driver 9 (QEMU) ...
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1019 : Matched URI scheme 'qemu'
>2018-07-16 02:54:44.107+0000: 30730: debug : virAccessManagerCheckConnect:215 : manager=0x5555f6500ea0(name=stack) driver=QEMU perm=0
>2018-07-16 02:54:44.107+0000: 30730: debug : virAccessManagerCheckConnect:215 : manager=0x5555f6500f80(name=none) driver=QEMU perm=0
>2018-07-16 02:54:44.107+0000: 30730: debug : virConnectOpenInternal:1045 : driver 9 QEMU returned SUCCESS
>2018-07-16 02:54:44.107+0000: 30730: debug : virThreadJobClear:121 : Thread 30730 (virNetServerHandleJob) finished job remoteDispatchConnectOpen with ret=0
>2018-07-16 02:54:44.107+0000: 30730: debug : virNetMessageEncodePayload:389 : Encode length as 28
>2018-07-16 02:54:44.107+0000: 30730: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f6562f70 proc=1 len=28 offset=0
>2018-07-16 02:54:44.107+0000: 30730: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2
>2018-07-16 02:54:44.107+0000: 30730: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f860 tx=0x5555f6562f70
>2018-07-16 02:54:44.107+0000: 30730: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f6562f70 nfds=0 cb=(nil)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f860 tx=(nil)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 28 total (24 more)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f860 tx=(nil)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.107+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2018-07-16 02:54:44.107+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65623b0, client=0x5555f655f460, msg=0x5555f655f860
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f65622d0 tracked=1
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65622d0 tx=(nil)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655f860
>2018-07-16 02:54:44.107+0000: 30729: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655f860 prog=0x5555f655ca40
>2018-07-16 02:54:44.107+0000: 30729: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110
>2018-07-16 02:54:44.107+0000: 30729: debug : virThreadJobSet:96 : Thread 30729 (virNetServerHandleJob) is now running job remoteDispatchConnectGetURI
>2018-07-16 02:54:44.107+0000: 30729: debug : remoteDispatchConnectGetURIHelper:1187 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f655f860 rerr=0x7fed0e273c10 args=0x7fecf0000c00 ret=0x7fecf0000c20
>2018-07-16 02:54:44.107+0000: 30729: debug : virConnectGetURI:255 : conn=0x7fed00017210
>2018-07-16 02:54:44.107+0000: 30729: debug : virThreadJobClear:121 : Thread 30729 (virNetServerHandleJob) finished job remoteDispatchConnectGetURI with ret=0
>2018-07-16 02:54:44.107+0000: 30729: debug : virNetMessageEncodePayload:389 : Encode length as 48
>2018-07-16 02:54:44.107+0000: 30729: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f655f860 proc=110 len=48 offset=0
>2018-07-16 02:54:44.107+0000: 30729: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2018-07-16 02:54:44.107+0000: 30729: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65622d0 tx=0x5555f655f860
>2018-07-16 02:54:44.107+0000: 30729: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f655f860 nfds=0 cb=(nil)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65622d0 tx=(nil)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 32 total (28 more)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65622d0 tx=(nil)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.107+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=4
>2018-07-16 02:54:44.107+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65623b0, client=0x5555f655f460, msg=0x5555f65622d0
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f6562f70 tracked=1
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=(nil)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f65622d0
>2018-07-16 02:54:44.107+0000: 30732: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f65622d0 prog=0x5555f655ca40
>2018-07-16 02:54:44.107+0000: 30732: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=60
>2018-07-16 02:54:44.107+0000: 30732: debug : virThreadJobSet:96 : Thread 30732 (virNetServerHandleJob) is now running job remoteDispatchConnectSupportsFeature
>2018-07-16 02:54:44.107+0000: 30732: debug : remoteDispatchConnectSupportsFeatureHelper:3482 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f65622d0 rerr=0x7fed07ffec10 args=0x7fecfc000900 ret=0x7fecfc0009a0
>2018-07-16 02:54:44.107+0000: 30732: debug : virThreadJobClear:121 : Thread 30732 (virNetServerHandleJob) finished job remoteDispatchConnectSupportsFeature with ret=0
>2018-07-16 02:54:44.107+0000: 30732: debug : virNetMessageEncodePayload:389 : Encode length as 32
>2018-07-16 02:54:44.107+0000: 30732: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f65622d0 proc=60 len=32 offset=0
>2018-07-16 02:54:44.107+0000: 30732: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=4
>2018-07-16 02:54:44.107+0000: 30732: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=0x5555f65622d0
>2018-07-16 02:54:44.107+0000: 30732: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f65622d0 nfds=0 cb=(nil)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=(nil)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 32 total (28 more)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f6562f70 tx=(nil)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.107+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=5
>2018-07-16 02:54:44.107+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65623b0, client=0x5555f655f460, msg=0x5555f6562f70
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f655f860 tracked=1
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f860 tx=(nil)
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.107+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f6562f70
>2018-07-16 02:54:44.108+0000: 30728: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f6562f70 prog=0x5555f655ca40
>2018-07-16 02:54:44.108+0000: 30728: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=60
>2018-07-16 02:54:44.108+0000: 30728: debug : virThreadJobSet:96 : Thread 30728 (virNetServerHandleJob) is now running job remoteDispatchConnectSupportsFeature
>2018-07-16 02:54:44.108+0000: 30728: debug : remoteDispatchConnectSupportsFeatureHelper:3482 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f6562f70 rerr=0x7fed0ea74c10 args=0x7fed08000a20 ret=0x7fed080008c0
>2018-07-16 02:54:44.108+0000: 30728: debug : virThreadJobClear:121 : Thread 30728 (virNetServerHandleJob) finished job remoteDispatchConnectSupportsFeature with ret=0
>2018-07-16 02:54:44.108+0000: 30728: debug : virNetMessageEncodePayload:389 : Encode length as 32
>2018-07-16 02:54:44.108+0000: 30728: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f6562f70 proc=60 len=32 offset=0
>2018-07-16 02:54:44.108+0000: 30728: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=5
>2018-07-16 02:54:44.108+0000: 30728: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f860 tx=0x5555f6562f70
>2018-07-16 02:54:44.108+0000: 30728: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f6562f70 nfds=0 cb=(nil)
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f860 tx=(nil)
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 28 total (24 more)
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f860 tx=(nil)
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.108+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=360 type=0 status=0 serial=6
>2018-07-16 02:54:44.108+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65623b0, client=0x5555f655f460, msg=0x5555f655f860
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f65622d0 tracked=1
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65622d0 tx=(nil)
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655f860
>2018-07-16 02:54:44.108+0000: 30731: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655f860 prog=0x5555f655ca40
>2018-07-16 02:54:44.108+0000: 30731: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=360
>2018-07-16 02:54:44.108+0000: 30731: debug : virThreadJobSet:96 : Thread 30731 (virNetServerHandleJob) is now running job remoteDispatchConnectRegisterCloseCallback
>2018-07-16 02:54:44.108+0000: 30731: debug : remoteDispatchConnectRegisterCloseCallbackHelper:3349 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f655f860 rerr=0x7fed0d271c10 args=0x7fecf8000a80 ret=0x7fecf8000aa0
>2018-07-16 02:54:44.108+0000: 30731: debug : virConnectRegisterCloseCallback:1375 : conn=0x7fed00017210
>2018-07-16 02:54:44.108+0000: 30731: debug : virThreadJobClear:121 : Thread 30731 (virNetServerHandleJob) finished job remoteDispatchConnectRegisterCloseCallback with ret=0
>2018-07-16 02:54:44.108+0000: 30731: debug : virNetMessageEncodePayload:389 : Encode length as 28
>2018-07-16 02:54:44.108+0000: 30731: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f655f860 proc=360 len=28 offset=0
>2018-07-16 02:54:44.108+0000: 30731: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=360 type=1 status=0 serial=6
>2018-07-16 02:54:44.108+0000: 30731: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65622d0 tx=0x5555f655f860
>2018-07-16 02:54:44.108+0000: 30731: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f655f860 nfds=0 cb=(nil)
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65622d0 tx=(nil)
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 4672 total (4668 more)
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f65622d0 tx=(nil)
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.108+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=4672 prog=536903814 vers=1 proc=11 type=0 status=0 serial=7
>2018-07-16 02:54:44.108+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65623b0, client=0x5555f655f460, msg=0x5555f65622d0
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f655f780 tracked=1
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f780 tx=(nil)
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.108+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f65622d0
>2018-07-16 02:54:44.108+0000: 30730: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f65622d0 prog=0x5555f655ca40
>2018-07-16 02:54:44.108+0000: 30730: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=7 proc=11
>2018-07-16 02:54:44.108+0000: 30730: debug : virThreadJobSet:96 : Thread 30730 (virNetServerHandleJob) is now running job remoteDispatchDomainDefineXML
>2018-07-16 02:54:44.108+0000: 30730: debug : remoteDispatchDomainDefineXMLHelper:4635 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f65622d0 rerr=0x7fed0da72c10 args=0x7fed00013500 ret=0x7fed00018060
>2018-07-16 02:54:44.108+0000: 30730: debug : virDomainDefineXML:6150 : conn=0x7fed00017210, xml=<domain type='kvm'>
>  <name>r7</name>
>  <memory unit='KiB'>2048576</memory>
>  <currentMemory unit='KiB'>2048576</currentMemory>
>  <vcpu placement='static' cpuset='0-1'>2</vcpu>
>  <resource>
>    <partition>/machine</partition>
>  </resource>
>  <os>
>    <type arch='x86_64' machine='pc-i440fx-rhel7.6.0'>hvm</type>
>    <bootmenu enable='yes' timeout='3000'/>
>    <bios useserial='yes' rebootTimeout='3000'/>
>  </os>
>  <features>
>    <acpi/>
>    <apic/>
>    <pmu state='on'/>
>    <vmport state='off'/>
>  </features>
>  <cpu mode='host-model' check='partial'>
>    <model fallback='allow'/>
>  </cpu>
>  <clock offset='utc'>
>    <timer name='rtc' tickpolicy='catchup'/>
>    <timer name='pit' tickpolicy='delay'/>
>    <timer name='hpet' present='no'/>
>  </clock>
>  <on_poweroff>destroy</on_poweroff>
>  <on_reboot>restart</on_reboot>
>  <on_crash>coredump-restart</on_crash>
>  <pm>
>    <suspend-to-mem enabled='no'/>
>    <suspend-to-disk enabled='no'/>
>  </pm>
>  <devices>
>    <emulator>/usr/libexec/qemu-kvm</emulator>
>    <disk type='file' device='disk'>
>      <driver name='qemu' type='raw' cache='none'/>
>      <source file='/var/lib/libvirt/images/t.qcow2'/>
>      <target dev='vda' bus='virtio'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x07' function='0x0'/>
>    </disk>
>    <interface type='hostdev' managed='yes'>
>      <mac address='00:16:3e:77:e2:ed'/>
>      <source>
>      <address type='pci' domain='0x0000' bus='0x08' slot='0x10' function='0x3'/>
>      </source>
>      <boot order='1'/>
>      <rom bar='on' file='/usr/share/ipxe/808610ca.rom'/>
>    </interface>
>    <controller type='usb' index='0' model='ich9-ehci1'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x7'/>
>    </controller>
>    <controller type='usb' index='0' model='ich9-uhci1'>
>      <master startport='0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x0' multifunction='on'/>
>    </controller>
>    <controller type='usb' index='0' model='ich9-uhci2'>
>      <master startport='2'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x1'/>
>    </controller>
>    <controller type='usb' index='0' model='ich9-uhci3'>
>      <master startport='4'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x2'/>
>    </controller>
>    <controller type='virtio-serial' index='0'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x05' function='0x0'/>
>    </controller>
>    <controller type='pci' index='0' model='pci-root'/>
>    <controller type='ide' index='0'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x1'/>
>    </controller>
>    <controller type='sata' index='0'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x0a' function='0x0'/>
>    </controller>
>    <serial type='pty'>
>      <target type='isa-serial' port='0'>
>        <model name='isa-serial'/>
>      </target>
>    </serial>
>    <console type='pty'>
>      <target type='serial' port='0'/>
>    </console>
>    <channel type='unix'>
>      <target type='virtio' name='org.qemu.guest_agent.0'/>
>      <address type='virtio-serial' controller='0' bus='0' port='1'/>
>    </channel>
>    <channel type='spicevmc'>
>      <target type='virtio' name='com.redhat.spice.0'/>
>      <address type='virtio-serial' controller='0' bus='0' port='2'/>
>    </channel>
>    <input type='tablet' bus='usb'>
>      <address type='usb' bus='0' port='1'/>
>    </input>
>    <input type='mouse' bus='ps2'/>
>    <input type='keyboard' bus='ps2'/>
>    <graphics type='spice' autoport='yes'>
>      <listen type='address'/>
>    </graphics>
>    <graphics type='vnc' port='-1' autoport='yes'>
>      <listen type='address'/>
>    </graphics>
>    <sound model='ich6'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x04' function='0x0'/>
>    </sound>
>    <video>
>      <model type='virtio' heads='1' primary='yes'>
>        <acceleration accel3d='no'/>
>      </model>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x03' function='0x0'/>
>    </video>
>    <redirdev bus='usb' type='spicevmc'>
>      <address type='usb' bus='0' port='2'/>
>    </redirdev>
>    <redirdev bus='usb' type='spicevmc'>
>      <address type='usb' bus='0' port='3'/>
>    </redirdev>
>    <watchdog model='ib700' action='dump'/>
>    <memballoon model='virtio'>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x08' function='0x0'/>
>    </memballoon>
>    <rng model='virtio'>
>      <backend model='random'>/dev/random</backend>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x09' function='0x0'/>
>    </rng>
>    <panic model='isa'/>
>  </devices>
></domain>
>
>
>2018-07-16 02:54:44.113+0000: 30730: debug : virQEMUCapsCacheLookup:4633 : Returning caps 0x7feca4181400 for /usr/libexec/qemu-kvm
>2018-07-16 02:54:44.113+0000: 30730: error : virDomainDefCollectBootOrder:5041 : unsupported configuration: boot order '1' used for more than one device
>2018-07-16 02:54:44.113+0000: 30730: debug : virThreadJobClear:121 : Thread 30730 (virNetServerHandleJob) finished job remoteDispatchDomainDefineXML with ret=-1
>2018-07-16 02:54:44.113+0000: 30730: debug : virNetServerProgramSendError:150 : prog=536903814 ver=1 proc=11 type=1 serial=7 msg=0x5555f65622d0 rerr=0x7fed0da72c10
>2018-07-16 02:54:44.113+0000: 30730: debug : virNetMessageEncodePayload:389 : Encode length as 232
>2018-07-16 02:54:44.113+0000: 30730: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f65622d0 proc=11 len=232 offset=0
>2018-07-16 02:54:44.113+0000: 30730: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=232 prog=536903814 vers=1 proc=11 type=1 status=1 serial=7
>2018-07-16 02:54:44.113+0000: 30730: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f780 tx=0x5555f65622d0
>2018-07-16 02:54:44.113+0000: 30730: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:54:44.113+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f65622d0 nfds=0 cb=(nil)
>2018-07-16 02:54:44.113+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f780 tx=(nil)
>2018-07-16 02:54:44.113+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 28 total (24 more)
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f780 tx=(nil)
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.114+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=361 type=0 status=0 serial=8
>2018-07-16 02:54:44.114+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65623b0, client=0x5555f655f460, msg=0x5555f655f780
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f655f3a0 tracked=1
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f3a0 tx=(nil)
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655f780
>2018-07-16 02:54:44.114+0000: 30729: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655f780 prog=0x5555f655ca40
>2018-07-16 02:54:44.114+0000: 30729: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=8 proc=361
>2018-07-16 02:54:44.114+0000: 30729: debug : virThreadJobSet:96 : Thread 30729 (virNetServerHandleJob) is now running job remoteDispatchConnectUnregisterCloseCallback
>2018-07-16 02:54:44.114+0000: 30729: debug : remoteDispatchConnectUnregisterCloseCallbackHelper:3507 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f655f780 rerr=0x7fed0e273c10 args=0x7fecf0000b30 ret=0x7fecf0000d10
>2018-07-16 02:54:44.114+0000: 30729: debug : virConnectUnregisterCloseCallback:1410 : conn=0x7fed00017210
>2018-07-16 02:54:44.114+0000: 30729: debug : virThreadJobClear:121 : Thread 30729 (virNetServerHandleJob) finished job remoteDispatchConnectUnregisterCloseCallback with ret=0
>2018-07-16 02:54:44.114+0000: 30729: debug : virNetMessageEncodePayload:389 : Encode length as 28
>2018-07-16 02:54:44.114+0000: 30729: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f655f780 proc=361 len=28 offset=0
>2018-07-16 02:54:44.114+0000: 30729: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=361 type=1 status=0 serial=8
>2018-07-16 02:54:44.114+0000: 30729: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f3a0 tx=0x5555f655f780
>2018-07-16 02:54:44.114+0000: 30729: debug : virNetServerClientCalculateHandleMode:189 : mode=03
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f655f780 nfds=0 cb=(nil)
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f3a0 tx=(nil)
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetMessageDecodeLength:161 : Got length, now need 28 total (24 more)
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655f3a0 tx=(nil)
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.114+0000: 30727: info : virNetServerClientDispatchRead:1271 : RPC_SERVER_CLIENT_MSG_RX: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=2 type=0 status=0 serial=9
>2018-07-16 02:54:44.114+0000: 30727: debug : virKeepAliveCheckMessage:371 : ka=0x5555f65623b0, client=0x5555f655f460, msg=0x5555f655f3a0
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetMessageNew:46 : msg=0x5555f655ef70 tracked=1
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655ef70 tx=(nil)
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=01
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerDispatchNewMessage:190 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655f3a0
>2018-07-16 02:54:44.114+0000: 30732: debug : virNetServerHandleJob:162 : server=0x5555f6501860 client=0x5555f655f460 message=0x5555f655f3a0 prog=0x5555f655ca40
>2018-07-16 02:54:44.114+0000: 30732: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=9 proc=2
>2018-07-16 02:54:44.114+0000: 30732: debug : virThreadJobSet:96 : Thread 30732 (virNetServerHandleJob) is now running job remoteDispatchConnectClose
>2018-07-16 02:54:44.114+0000: 30732: debug : remoteDispatchConnectCloseHelper:271 : server=0x5555f6501860 client=0x5555f655f460 msg=0x5555f655f3a0 rerr=0x7fed07ffec10 args=0x7fecfc000900 ret=0x7fecfc0009a0
>2018-07-16 02:54:44.114+0000: 30732: debug : virThreadJobClear:121 : Thread 30732 (virNetServerHandleJob) finished job remoteDispatchConnectClose with ret=0
>2018-07-16 02:54:44.114+0000: 30732: debug : virNetMessageEncodePayload:389 : Encode length as 28
>2018-07-16 02:54:44.114+0000: 30732: debug : virNetServerClientSendMessageLocked:1509 : msg=0x5555f655f3a0 proc=2 len=28 offset=0
>2018-07-16 02:54:44.114+0000: 30732: info : virNetServerClientSendMessageLocked:1517 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x5555f655f460 len=28 prog=536903814 vers=1 proc=2 type=1 status=0 serial=9
>2018-07-16 02:54:44.114+0000: 30732: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655ef70 tx=0x5555f655f3a0
>2018-07-16 02:54:44.114+0000: 30732: debug : virNetServerClientCalculateHandleMode:189 : mode=02
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f655f3a0 nfds=0 cb=(nil)
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerClientCalculateHandleMode:158 : tls=(nil) hs=-1, rx=0x5555f655ef70 tx=(nil)
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerClientCalculateHandleMode:189 : mode=00
>2018-07-16 02:54:44.114+0000: 30727: debug : virNetServerClientCloseLocked:1008 : client=0x5555f655f460
>2018-07-16 02:54:44.114+0000: 30727: info : virKeepAliveStop:304 : RPC_KEEPALIVE_STOP: ka=0x5555f65623b0 client=0x5555f655f460
>2018-07-16 02:54:44.114+0000: 30727: debug : daemonRemoveAllClientStreams:504 : stream=(nil)
>2018-07-16 02:54:44.114+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=4 value=30727
>2018-07-16 02:54:44.115+0000: 30727: debug : virFileClose:111 : Closed fd 22
>2018-07-16 02:54:44.115+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=5 value=57991862
>2018-07-16 02:54:44.115+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=0 value=root
>2018-07-16 02:54:44.115+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=1 value=0
>2018-07-16 02:54:44.115+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=2 value=root
>2018-07-16 02:54:44.115+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=3 value=0
>2018-07-16 02:54:44.116+0000: 30727: debug : virIdentitySetAttr:245 : ident=0x5555f655f040 attribute=8 value=system_u:system_r:virtd_t:s0-s0:c0.c1023
>2018-07-16 02:54:44.116+0000: 30727: debug : virNetMessageFree:87 : msg=0x5555f655ef70 nfds=0 cb=(nil)
>2018-07-16 02:54:44.116+0000: 30727: debug : virNetServerCheckLimits:251 : Checking client-related limits to re-enable or temporarily suspend services: nclients=0 nclients_max=5000 nclients_unauth=0 nclients_unauth_max=20
>2018-07-16 02:54:44.116+0000: 30727: info : virNetServerCheckLimits:270 : Re-enabling services
>2018-07-16 02:54:44.116+0000: 30727: info : virKeepAliveDispose:224 : RPC_KEEPALIVE_DISPOSE: ka=0x5555f65623b0
>2018-07-16 02:54:44.116+0000: 30727: info : virNetServerClientDispose:976 : RPC_SERVER_CLIENT_DISPOSE: client=0x5555f655f460
>2018-07-16 02:54:44.116+0000: 30727: debug : virConnectClose:1237 : conn=0x7fed00017210
>2018-07-16 02:54:44.116+0000: 30727: debug : virConnectClose:1237 : conn=0x7fed00017210
>2018-07-16 02:54:44.116+0000: 30727: debug : virConnectClose:1237 : conn=0x7fed00017210
>2018-07-16 02:54:44.116+0000: 30727: debug : virConnectClose:1237 : conn=0x7fed00017210
>2018-07-16 02:54:44.116+0000: 30727: debug : virConnectClose:1237 : conn=0x7fed00017210
>2018-07-16 02:54:44.116+0000: 30727: debug : virConnectClose:1237 : conn=0x7fed00017210
>2018-07-16 02:54:44.116+0000: 30727: debug : virConnectClose:1237 : conn=0x7fed00017210
>2018-07-16 02:54:44.116+0000: 30727: debug : virCloseCallbacksRun:320 : conn=0x7fed00017210
>2018-07-16 02:54:44.116+0000: 30727: info : virNetSocketDispose:1321 : RPC_SOCKET_DISPOSE: sock=0x5555f655f120
>2018-07-16 02:54:44.116+0000: 30727: debug : virFileClose:111 : Closed fd 18

Actions: View

Attachments on bug 1601318: 1459049 | 1459053