[libvirt] Python stream callback removal

Dave Allan dallan at redhat.com
Wed Aug 17 21:55:06 UTC 2011


On Tue, Aug 09, 2011 at 10:28:26PM -0400, Dave Allan wrote:
> On Tue, Aug 09, 2011 at 10:59:02AM +0100, Daniel P. Berrange wrote:
> > On Mon, Aug 08, 2011 at 06:04:50PM -0400, Dave Allan wrote:
> > > I'm trying to write an example serial console implementation in python
> > > (attached), but I'm having some trouble getting stream events to do
> > > what I want.  The console itself works fine as long as the domain
> > > stays up, but as soon as the domain shuts down the python script goes
> > > into a tight loop repeatedly calling the stream event callback.
> > > Debugging indicates that the stream event callback is being requested
> > > to be removed, but it never actually is removed which makes me think I
> > > am not properly releasing some resource, but I was under the
> > > impression that an error on a stream resulting in the stream aborting
> > > was supposed to free all the resources for me.  Is that not correct?
> > 
> > No where in your code do you ever invoke eventRemoveCallback.
> > When the stream is "aborted" this just means that libvirtd
> > has released server side resource & reported the error back
> > to the client. You still have to remove your event callbacks
> > otherwise you'll just be invoked forever. See tools/console.c
> > for example code doing what you're attempting, but in C.
> > In particular the places which call virConsoleShutdown.
> > Your code in Python should  basically be a straight conversion
> > of tools/console.c from C into Python.
> 
> Thanks, that was the problem; calling remove fixed it.
> 
> What I'm trying to do is to write a console that does not exit when
> the domain is down, and the code is now working, at least for a short
> while.  However, I am seeing a strange behavior.  After the domain has
> been powered off twice--regardless of whether the domain was started
> or stopped when the console program is started--when starting the
> domain the next time the console hangs and no callbacks are called.  I
> attached to the process with gdb and the backtraces are very different
> when the process is responsive vs. when it is ok.
> 
> Any ideas on what's going wrong?

So, after your patches which have greatly improved the console
behavior, I find that I'm back to this hang, which by its nature I
can't reproduce with virsh console, as it only appears when I've
shutdown and started a domain several times within the same
connection.  The hang is 100% reproducible.  Per our IRC conversation,
I'm attaching the RPC logs, as well as the python code for reference
and a backtrace of the python process at the time that it was hung.

Dave

-------------- next part --------------
#0  0x000000300a0d7248 in poll () from /lib64/libc.so.6
#1  0x00007ffaea8f75cf in virNetClientIOEventLoop (client=0x7ffaea32a010, thiscall=0x2634730)
    at rpc/virnetclient.c:899
#2  0x00007ffaea8f8c0c in virNetClientIO (client=0x7ffaea32a010, msg=<value optimized out>, 
    expectReply=true) at rpc/virnetclient.c:1136
#3  virNetClientSend (client=0x7ffaea32a010, msg=<value optimized out>, expectReply=true)
    at rpc/virnetclient.c:1218
#4  0x00007ffaea8f94d8 in virNetClientProgramCall (prog=0x25bfc00, client=0x7ffaea32a010, 
    serial=11, proc=212, args_filter=0x7ffaea8f61a0 <xdr_remote_domain_get_state_args>, 
    args=0x7fffce3ee7b0, ret_filter=0x7ffaea8f6210 <xdr_remote_domain_get_state_ret>, 
    ret=0x7fffce3ee7a0) at rpc/virnetclientprogram.c:291
#5  0x00007ffaea8da0d9 in call (priv=0x261ec10, flags=<value optimized out>, proc_nr=212, 
    args_filter=0x7ffaea8f61a0 <xdr_remote_domain_get_state_args>, args=<value optimized out>, 
    ret_filter=<value optimized out>, ret=0x7fffce3ee7a0 "", conn=<value optimized out>)
    at remote/remote_driver.c:4039
#6  0x00007ffaea8e12b8 in remoteDomainGetState (domain=0x25ab440, state=0x7fffce3ee8cc, 
    reason=0x7fffce3ee8c8, flags=0) at remote/remote_driver.c:1656
#7  0x00007ffaea8b0547 in virDomainGetState (domain=0x25ab440, state=0x7fffce3ee8cc, 
    reason=0x7fffce3ee8c8, flags=0) at libvirt.c:3655
#8  0x00007ffaeaca68f5 in libvirt_virDomainGetState (self=<value optimized out>, 
    args=<value optimized out>) at libvirt-override.c:1712
#9  0x0000003016ce965b in PyEval_EvalFrameEx () from /usr/lib64/libpython2.7.so.1.0
#10 0x0000003016cea71d in PyEval_EvalFrameEx () from /usr/lib64/libpython2.7.so.1.0
#11 0x0000003016cea71d in PyEval_EvalFrameEx () from /usr/lib64/libpython2.7.so.1.0
#12 0x0000003016ceb04d in PyEval_EvalCodeEx () from /usr/lib64/libpython2.7.so.1.0
#13 0x0000003016c71c62 in ?? () from /usr/lib64/libpython2.7.so.1.0
#14 0x0000003016c48fc3 in PyObject_Call () from /usr/lib64/libpython2.7.so.1.0
#15 0x0000003016c5a65f in ?? () from /usr/lib64/libpython2.7.so.1.0
#16 0x0000003016c48fc3 in PyObject_Call () from /usr/lib64/libpython2.7.so.1.0
#17 0x0000003016c490ab in ?? () from /usr/lib64/libpython2.7.so.1.0
#18 0x0000003016c4935b in PyObject_CallMethod () from /usr/lib64/libpython2.7.so.1.0
#19 0x00007ffaeaca8082 in libvirt_virConnectDomainEventCallback (conn=<value optimized out>, 
    dom=<value optimized out>, event=2, detail=0, opaque=0x7ffaeaef3bd8) at libvirt-override.c:3210
#20 0x00007ffaea874176 in virDomainEventDispatchDefaultFunc (conn=0x259a000, event=0x25e9fa0, 
    cb=0x7ffaeaca7f30 <libvirt_virConnectDomainEventCallback>, cbopaque=0x7ffaeaef3bd8, 
    opaque=<value optimized out>) at conf/domain_event.c:1023
#21 0x00007ffaea8d998a in remoteDomainEventDispatchFunc (conn=0x259a000, event=0x25e9fa0, 
    cb=0x7ffaeaca7f30 <libvirt_virConnectDomainEventCallback>, cbopaque=0x7ffaeaef3bd8, 
    opaque=0x261ec10) at remote/remote_driver.c:4063
#22 0x00007ffaea87431f in virDomainEventDispatch (event=0x25e9fa0, callbacks=0x24eaac0, 
    dispatch=<value optimized out>, opaque=0x261ec10) at conf/domain_event.c:1136
#23 0x00007ffaea8743ba in virDomainEventQueueDispatch (queue=0x7fffce3ef3f0, callbacks=0x24eaac0, 
    dispatch=0x7ffaea8d9900 <remoteDomainEventDispatchFunc>, opaque=0x261ec10)
    at conf/domain_event.c:1153
#24 0x00007ffaea87458e in virDomainEventStateFlush (state=0x25f2ae0, 
    dispatchFunc=0x7ffaea8d9900 <remoteDomainEventDispatchFunc>, opaque=0x261ec10)
    at conf/domain_event.c:1195
#25 0x00007ffaea8d98d5 in remoteDomainEventQueueFlush (timer=<value optimized out>, 
    opaque=0x259a000) at remote/remote_driver.c:4077
#26 0x00007ffaea82c976 in virEventPollDispatchTimeouts () at util/event_poll.c:421
#27 virEventPollRunOnce () at util/event_poll.c:607
#28 0x00007ffaea82b405 in virEventRunDefaultImpl () at util/event.c:247
#29 0x00007ffaeacaff8e in libvirt_virEventRunDefaultImpl (self=<value optimized out>, 
    args=<value optimized out>) at libvirt.c:3598
#30 0x0000003016ce965b in PyEval_EvalFrameEx () from /usr/lib64/libpython2.7.so.1.0
#31 0x0000003016cea71d in PyEval_EvalFrameEx () from /usr/lib64/libpython2.7.so.1.0
#32 0x0000003016ceb04d in PyEval_EvalCodeEx () from /usr/lib64/libpython2.7.so.1.0
#33 0x0000003016ceb162 in PyEval_EvalCode () from /usr/lib64/libpython2.7.so.1.0
#34 0x0000003016d0639c in ?? () from /usr/lib64/libpython2.7.so.1.0
#35 0x0000003016d071d0 in PyRun_FileExFlags () from /usr/lib64/libpython2.7.so.1.0
#36 0x0000003016d07daf in PyRun_SimpleFileExFlags () from /usr/lib64/libpython2.7.so.1.0
#37 0x0000003016d198ce in Py_Main () from /usr/lib64/libpython2.7.so.1.0
#38 0x000000300a01ee5d in __libc_start_main () from /lib64/libc.so.6
#39 0x0000000000400649 in _start ()
-------------- next part --------------
17:45:29.078: 7634: info : libvirt version: 0.9.4
17:45:29.078: 7634: debug : virNetSocketNew:116 : localAddr=0x7fff8f547e20 remoteAddr=0x7fff8f547d90 fd=7 errfd=-1 pid=0
17:45:29.078: 7634: debug : virNetSocketNew:174 : sock=0x1203900 localAddrStr=127.0.0.1;0 remoteAddrStr=127.0.0.1;0
17:45:29.078: 7634: debug : virNetClientNew:160 : client=0x7f9faaf54010 refs=2
17:45:29.078: 7634: debug : virNetMessageNew:44 : msg=0x7f9faaf13010
17:45:29.078: 7634: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:29.078: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=0 proc=66 type=0 length=28 dispatch=(nil)
17:45:29.078: 7634: debug : virNetClientIO:1118 : We have the buck 0x119bf30 0x119bf30
17:45:29.079: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 36 total (32 more)
17:45:29.079: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 66 type 1 status 0 serial 0
17:45:29.079: 7634: debug : virNetClientIOEventLoop:973 : Giving up the buck 0x119bf30 (nil)
17:45:29.080: 7634: debug : virNetClientIO:1145 : All done with our call (nil) 0x119bf30 0
17:45:29.080: 7634: debug : virNetMessageFree:57 : msg=0x7f9faaf13010
17:45:29.080: 7634: debug : virNetMessageNew:44 : msg=0x1203ac0
17:45:29.080: 7634: debug : virNetMessageEncodePayload:255 : Encode length as 56
17:45:29.080: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=1 proc=1 type=0 length=56 dispatch=(nil)
17:45:29.080: 7634: debug : virNetClientIO:1118 : We have the buck 0x119bf30 0x119bf30
17:45:29.087: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:29.087: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 1 type 1 status 0 serial 1
17:45:29.087: 7634: debug : virNetClientIOEventLoop:973 : Giving up the buck 0x119bf30 (nil)
17:45:29.087: 7634: debug : virNetClientIO:1145 : All done with our call (nil) 0x119bf30 0
17:45:29.087: 7634: debug : virNetMessageFree:57 : msg=0x1203ac0
17:45:29.087: 7634: debug : virNetMessageNew:44 : msg=0x1204070
17:45:29.087: 7634: debug : virNetMessageEncodePayload:255 : Encode length as 44
17:45:29.087: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=2 proc=24 type=0 length=44 dispatch=(nil)
17:45:29.087: 7634: debug : virNetClientIO:1118 : We have the buck 0x119bf30 0x119bf30
17:45:29.088: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 56 total (52 more)
17:45:29.088: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 24 type 1 status 0 serial 2
17:45:29.088: 7634: debug : virNetClientIOEventLoop:973 : Giving up the buck 0x119bf30 (nil)
17:45:29.088: 7634: debug : virNetClientIO:1145 : All done with our call (nil) 0x119bf30 0
17:45:29.088: 7634: debug : virNetMessageFree:57 : msg=0x1204070
17:45:29.088: 7634: debug : virNetMessageNew:44 : msg=0x1204420
17:45:29.088: 7634: debug : virNetMessageEncodePayload:255 : Encode length as 60
17:45:29.088: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=3 proc=212 type=0 length=60 dispatch=(nil)
17:45:29.088: 7634: debug : virNetClientIO:1118 : We have the buck 0x119bf30 0x119bf30
17:45:29.089: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 36 total (32 more)
17:45:29.089: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 212 type 1 status 0 serial 3
17:45:29.089: 7634: debug : virNetClientIOEventLoop:973 : Giving up the buck 0x119bf30 (nil)
17:45:29.089: 7634: debug : virNetClientIO:1145 : All done with our call (nil) 0x119bf30 0
17:45:29.089: 7634: debug : virNetMessageFree:57 : msg=0x1204420
17:45:29.090: 7634: debug : virNetMessageNew:44 : msg=0x1204610
17:45:29.090: 7634: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:29.090: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=4 proc=105 type=0 length=28 dispatch=(nil)
17:45:29.090: 7634: debug : virNetClientIO:1118 : We have the buck 0x119bfa0 0x119bfa0
17:45:29.090: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 32 total (28 more)
17:45:29.090: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 105 type 1 status 0 serial 4
17:45:29.090: 7634: debug : virNetClientIOEventLoop:973 : Giving up the buck 0x119bfa0 (nil)
17:45:29.090: 7634: debug : virNetClientIO:1145 : All done with our call (nil) 0x119bfa0 0
17:45:29.090: 7634: debug : virNetMessageFree:57 : msg=0x1204610
17:45:33.554: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:33.560: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 64 total (60 more)
17:45:33.560: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 107 type 2 status 0 serial 1
17:45:33.560: 7634: debug : virNetClientProgramDispatch:213 : prog=536903814 ver=1 type=2 status=0 serial=1 proc=107
17:45:33.560: 7634: debug : virNetMessageNew:44 : msg=0x1205630
17:45:33.560: 7634: debug : virNetMessageEncodePayload:255 : Encode length as 60
17:45:33.560: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=5 proc=212 type=0 length=60 dispatch=(nil)
17:45:33.560: 7634: debug : virNetClientIO:1118 : We have the buck 0x118ecb0 0x118ecb0
17:45:33.562: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 36 total (32 more)
17:45:33.562: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 212 type 1 status 0 serial 5
17:45:33.562: 7634: debug : virNetClientIOEventLoop:973 : Giving up the buck 0x118ecb0 (nil)
17:45:33.562: 7634: debug : virNetClientIO:1145 : All done with our call (nil) 0x118ecb0 0
17:45:33.562: 7634: debug : virNetMessageFree:57 : msg=0x1205630
17:45:33.562: 7634: debug : virNetMessageNew:44 : msg=0x1205ff0
17:45:33.562: 7634: debug : virNetMessageEncodePayload:255 : Encode length as 64
17:45:33.562: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=6 proc=201 type=0 length=64 dispatch=(nil)
17:45:33.562: 7634: debug : virNetClientIO:1118 : We have the buck 0x119bfa0 0x119bfa0
17:45:33.564: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:33.564: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 1 status 0 serial 6
17:45:33.564: 7634: debug : virNetClientIOEventLoop:973 : Giving up the buck 0x119bfa0 (nil)
17:45:33.564: 7634: debug : virNetClientIO:1145 : All done with our call (nil) 0x119bfa0 0
17:45:33.564: 7634: debug : virNetMessageFree:57 : msg=0x1205ff0
17:45:33.564: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:33.564: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:36.374: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:36.374: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 49 total (45 more)
17:45:36.374: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:36.374: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:36.374: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 21 length 21 EOF 0
17:45:36.374: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=21 1
17:45:36.374: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:36.374: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=21
17:45:36.374: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x12067a0 nbytes=1024 nonblock=1
17:45:36.374: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 21
17:45:36.374: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:36.374: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:36.374: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:36.374: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 29 total (25 more)
17:45:36.374: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:36.375: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:36.375: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 1 length 1 EOF 0
17:45:36.375: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=1 1
17:45:36.375: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:36.375: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=1
17:45:36.375: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x12067a0 nbytes=1024 nonblock=1
17:45:36.375: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 1
17:45:36.375: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:36.375: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:36.385: 7634: debug : virNetClientStreamSendPacket:329 : st=0x11d41c0 status=2 data=0x1206c94 nbytes=9
17:45:36.385: 7634: debug : virNetMessageNew:44 : msg=0x1206ea0
17:45:36.385: 7634: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 37
17:45:36.385: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=6 proc=201 type=3 length=37 dispatch=(nil)
17:45:36.385: 7634: debug : virNetClientIO:1118 : We have the buck 0x119bfa0 0x119bfa0
17:45:36.385: 7634: debug : virNetClientIOEventLoop:973 : Giving up the buck 0x119bfa0 (nil)
17:45:36.385: 7634: debug : virNetClientIO:1145 : All done with our call (nil) 0x119bfa0 0
17:45:36.385: 7634: debug : virNetMessageFree:57 : msg=0x1206ea0
17:45:37.163: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:37.163: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 89 total (85 more)
17:45:37.163: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:37.163: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:37.163: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 61 length 61 EOF 0
17:45:37.163: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=61 1
17:45:37.163: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:37.164: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=61
17:45:37.164: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:37.164: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 61
17:45:37.164: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:37.164: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:37.164: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:37.164: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 90 total (86 more)
17:45:37.164: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:37.164: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:37.164: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 62 length 62 EOF 0
17:45:37.164: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=62 1
17:45:37.165: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:37.165: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=62
17:45:37.165: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:37.165: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 62
17:45:37.165: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:37.165: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:37.166: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:37.166: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 77 total (73 more)
17:45:37.166: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:37.166: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:37.166: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 49 length 49 EOF 0
17:45:37.166: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=49 1
17:45:37.166: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:37.166: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=49
17:45:37.166: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:37.166: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 49
17:45:37.166: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:37.166: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:37.172: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:37.172: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 52 total (48 more)
17:45:37.172: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:37.172: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:37.172: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 24 length 24 EOF 0
17:45:37.172: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=24 1
17:45:37.172: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:37.172: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=24
17:45:37.172: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:37.172: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 24
17:45:37.173: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:37.173: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:37.173: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:37.173: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 40 total (36 more)
17:45:37.173: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:37.173: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:37.173: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 12 length 12 EOF 0
17:45:37.173: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=12 1
17:45:37.173: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:37.173: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=12
17:45:37.173: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:37.173: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 12
17:45:37.173: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:37.173: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:37.175: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:37.175: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 35 total (31 more)
17:45:37.175: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:37.175: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:37.175: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 7 length 7 EOF 0
17:45:37.175: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=7 1
17:45:37.175: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:37.175: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=7
17:45:37.175: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:37.175: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 7
17:45:37.175: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:37.175: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:37.176: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:37.176: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 54 total (50 more)
17:45:37.176: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:37.176: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:37.176: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 26 length 26 EOF 0
17:45:37.176: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=26 1
17:45:37.176: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:37.176: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=26
17:45:37.176: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:37.176: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 26
17:45:37.176: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:37.176: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:37.177: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:37.177: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 55 total (51 more)
17:45:37.177: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:37.177: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:37.177: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 27 length 27 EOF 0
17:45:37.177: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=27 1
17:45:37.177: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:37.178: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=27
17:45:37.178: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:37.178: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 27
17:45:37.178: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:37.178: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:37.343: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:37.343: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 34 total (30 more)
17:45:37.343: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:37.343: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:37.343: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 6 length 6 EOF 0
17:45:37.343: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=6 1
17:45:37.343: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:37.343: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=6
17:45:37.343: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:37.343: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 6
17:45:37.343: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:37.343: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:37.344: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:37.344: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 56 total (52 more)
17:45:37.344: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:37.344: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:37.344: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 28 length 28 EOF 0
17:45:37.344: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=28 1
17:45:37.344: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:37.344: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=28
17:45:37.344: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:37.344: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 28
17:45:37.344: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:37.345: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:37.345: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:37.345: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 54 total (50 more)
17:45:37.345: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:37.345: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:37.345: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 26 length 26 EOF 0
17:45:37.345: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=26 1
17:45:37.345: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:37.346: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=26
17:45:37.346: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:37.346: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 26
17:45:37.346: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:37.346: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:38.343: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:38.343: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 34 total (30 more)
17:45:38.343: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:38.343: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:38.343: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 6 length 6 EOF 0
17:45:38.343: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=6 1
17:45:38.343: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:38.344: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=6
17:45:38.344: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:38.344: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 6
17:45:38.344: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:38.344: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:38.345: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:38.345: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 58 total (54 more)
17:45:38.345: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:38.345: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:38.345: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 30 length 30 EOF 0
17:45:38.345: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=30 1
17:45:38.345: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:38.345: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=30
17:45:38.345: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:38.345: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 30
17:45:38.345: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:38.345: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:38.346: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:38.346: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 52 total (48 more)
17:45:38.346: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:38.346: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:38.346: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 24 length 24 EOF 0
17:45:38.346: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=24 1
17:45:38.346: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:38.346: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=24
17:45:38.346: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x11d41c0 client=0x7f9faaf54010 data=0x1206ea0 nbytes=1024 nonblock=1
17:45:38.346: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 24
17:45:38.346: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:38.346: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:40.301: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:40.301: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 64 total (60 more)
17:45:40.301: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 107 type 2 status 0 serial 1
17:45:40.301: 7634: debug : virNetClientProgramDispatch:213 : prog=536903814 ver=1 type=2 status=0 serial=1 proc=107
17:45:40.301: 7634: debug : virNetMessageNew:44 : msg=0x1206ea0
17:45:40.302: 7634: debug : virNetMessageEncodePayload:255 : Encode length as 60
17:45:40.302: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=7 proc=212 type=0 length=60 dispatch=(nil)
17:45:40.302: 7634: debug : virNetClientIO:1118 : We have the buck 0x11e67d0 0x11e67d0
17:45:40.302: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:40.302: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 6
17:45:40.302: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:40.302: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 0 length 0 EOF 1
17:45:40.302: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:40.302: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:40.302: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 136 total (132 more)
17:45:40.302: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 1 serial 6
17:45:40.302: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:40.302: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 36 total (32 more)
17:45:40.302: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 212 type 1 status 0 serial 7
17:45:40.302: 7634: debug : virNetClientIOEventLoop:973 : Giving up the buck 0x11e67d0 (nil)
17:45:40.302: 7634: debug : virNetClientIO:1145 : All done with our call (nil) 0x11e67d0 0
17:45:40.302: 7634: debug : virNetMessageFree:57 : msg=0x1206ea0
17:45:40.303: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=0
17:45:40.303: 7634: error : virNetClientStreamRaiseError:209 : stream had I/O failure
17:45:40.303: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:42.887: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:42.887: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 64 total (60 more)
17:45:42.887: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 107 type 2 status 0 serial 1
17:45:42.887: 7634: debug : virNetClientProgramDispatch:213 : prog=536903814 ver=1 type=2 status=0 serial=1 proc=107
17:45:42.888: 7634: debug : virNetMessageNew:44 : msg=0x1208030
17:45:42.888: 7634: debug : virNetMessageEncodePayload:255 : Encode length as 60
17:45:42.888: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=8 proc=212 type=0 length=60 dispatch=(nil)
17:45:42.888: 7634: debug : virNetClientIO:1118 : We have the buck 0x118ecb0 0x118ecb0
17:45:42.892: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 36 total (32 more)
17:45:42.892: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 212 type 1 status 0 serial 8
17:45:42.892: 7634: debug : virNetClientIOEventLoop:973 : Giving up the buck 0x118ecb0 (nil)
17:45:42.892: 7634: debug : virNetClientIO:1145 : All done with our call (nil) 0x118ecb0 0
17:45:42.892: 7634: debug : virNetMessageFree:57 : msg=0x1208030
17:45:42.893: 7634: debug : virNetMessageNew:44 : msg=0x1208150
17:45:42.893: 7634: debug : virNetMessageEncodePayload:255 : Encode length as 64
17:45:42.893: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=9 proc=201 type=0 length=64 dispatch=(nil)
17:45:42.893: 7634: debug : virNetClientIO:1118 : We have the buck 0x119bfa0 0x119bfa0
17:45:42.898: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:42.898: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 1 status 0 serial 9
17:45:42.899: 7634: debug : virNetClientIOEventLoop:973 : Giving up the buck 0x119bfa0 (nil)
17:45:42.899: 7634: debug : virNetClientIO:1145 : All done with our call (nil) 0x119bfa0 0
17:45:42.899: 7634: debug : virNetMessageFree:57 : msg=0x1208150
17:45:42.899: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:42.899: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:45.714: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:45.714: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 50 total (46 more)
17:45:45.714: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:45.714: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:45.714: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 22 length 22 EOF 0
17:45:45.714: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=22 1
17:45:45.714: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:45.714: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=22
17:45:45.714: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:45.714: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 22
17:45:45.714: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:45.714: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:45.725: 7634: debug : virNetClientStreamSendPacket:329 : st=0x12067a0 status=2 data=0x1208174 nbytes=9
17:45:45.725: 7634: debug : virNetMessageNew:44 : msg=0x1208190
17:45:45.725: 7634: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 37
17:45:45.725: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=9 proc=201 type=3 length=37 dispatch=(nil)
17:45:45.725: 7634: debug : virNetClientIO:1118 : We have the buck 0x119bfa0 0x119bfa0
17:45:45.725: 7634: debug : virNetClientIOEventLoop:973 : Giving up the buck 0x119bfa0 (nil)
17:45:45.725: 7634: debug : virNetClientIO:1145 : All done with our call (nil) 0x119bfa0 0
17:45:45.725: 7634: debug : virNetMessageFree:57 : msg=0x1208190
17:45:46.501: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:46.501: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 53 total (49 more)
17:45:46.501: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:46.501: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:46.501: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 25 length 25 EOF 0
17:45:46.501: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=25 1
17:45:46.501: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:46.502: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=25
17:45:46.502: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:46.502: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 25
17:45:46.502: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:46.502: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:46.502: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:46.502: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 87 total (83 more)
17:45:46.502: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:46.502: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:46.502: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 59 length 59 EOF 0
17:45:46.502: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=59 1
17:45:46.502: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:46.503: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=59
17:45:46.503: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:46.503: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 59
17:45:46.503: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:46.503: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:46.503: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:46.503: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 76 total (72 more)
17:45:46.503: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:46.503: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:46.503: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 48 length 48 EOF 0
17:45:46.503: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=48 1
17:45:46.503: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:46.504: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=48
17:45:46.504: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:46.504: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 48
17:45:46.504: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:46.504: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:46.504: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:46.504: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 68 total (64 more)
17:45:46.504: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:46.504: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:46.504: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 40 length 40 EOF 0
17:45:46.504: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=40 1
17:45:46.504: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:46.505: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=40
17:45:46.505: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:46.505: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 40
17:45:46.505: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:46.505: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:46.510: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:46.510: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 38 total (34 more)
17:45:46.510: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:46.510: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:46.510: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 10 length 10 EOF 0
17:45:46.510: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=10 1
17:45:46.510: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:46.510: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=10
17:45:46.510: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:46.510: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 10
17:45:46.510: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:46.510: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:46.510: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:46.510: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 36 total (32 more)
17:45:46.510: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:46.510: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:46.510: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 8 length 8 EOF 0
17:45:46.510: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=8 1
17:45:46.510: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:46.510: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=8
17:45:46.510: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:46.510: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 8
17:45:46.510: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:46.510: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:46.511: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:46.511: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 46 total (42 more)
17:45:46.511: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:46.511: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:46.511: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 18 length 18 EOF 0
17:45:46.511: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=18 1
17:45:46.511: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:46.512: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=18
17:45:46.512: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:46.512: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 18
17:45:46.512: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:46.512: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:46.518: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:46.518: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 88 total (84 more)
17:45:46.518: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:46.518: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:46.518: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 60 length 60 EOF 0
17:45:46.518: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=60 1
17:45:46.518: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:46.518: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=60
17:45:46.518: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:46.518: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 60
17:45:46.518: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:46.518: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:46.685: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:46.685: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 42 total (38 more)
17:45:46.685: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:46.685: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:46.685: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 14 length 14 EOF 0
17:45:46.685: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=14 1
17:45:46.685: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:46.686: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=14
17:45:46.686: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:46.686: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 14
17:45:46.686: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:46.686: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:46.686: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:46.686: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 61 total (57 more)
17:45:46.686: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:46.686: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:46.686: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 33 length 33 EOF 0
17:45:46.686: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=33 1
17:45:46.686: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:46.687: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=33
17:45:46.687: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:46.687: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 33
17:45:46.687: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:46.687: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:46.688: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:46.688: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 41 total (37 more)
17:45:46.688: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:46.688: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:46.688: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 13 length 13 EOF 0
17:45:46.688: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=13 1
17:45:46.688: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:46.688: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=13
17:45:46.688: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:46.688: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 13
17:45:46.688: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:46.688: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:47.685: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:47.685: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 42 total (38 more)
17:45:47.685: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:47.685: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:47.685: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 14 length 14 EOF 0
17:45:47.685: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=14 1
17:45:47.685: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:47.686: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=14
17:45:47.686: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:47.686: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 14
17:45:47.686: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:47.686: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:47.686: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:47.686: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 61 total (57 more)
17:45:47.686: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:47.686: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:47.686: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 33 length 33 EOF 0
17:45:47.686: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=33 1
17:45:47.686: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:47.687: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=33
17:45:47.687: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:47.687: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 33
17:45:47.687: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:47.687: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:47.687: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:47.687: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 41 total (37 more)
17:45:47.687: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:47.687: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:47.687: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 13 length 13 EOF 0
17:45:47.687: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=13 1
17:45:47.687: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:47.688: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=13
17:45:47.688: 7634: debug : virNetClientStreamRecvPacket:381 : st=0x12067a0 client=0x7f9faaf54010 data=0x1208150 nbytes=1024 nonblock=1
17:45:47.688: 7634: debug : virNetClientStreamRecvPacket:414 : After IO 13
17:45:47.688: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:47.688: 7634: debug : virNetClientStreamEventTimerUpdate:83 : Disabling event timer
17:45:49.750: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:49.750: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 64 total (60 more)
17:45:49.750: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 107 type 2 status 0 serial 1
17:45:49.750: 7634: debug : virNetClientProgramDispatch:213 : prog=536903814 ver=1 type=2 status=0 serial=1 proc=107
17:45:49.751: 7634: debug : virNetMessageNew:44 : msg=0x1208150
17:45:49.751: 7634: debug : virNetMessageEncodePayload:255 : Encode length as 60
17:45:49.751: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=10 proc=212 type=0 length=60 dispatch=(nil)
17:45:49.751: 7634: debug : virNetClientIO:1118 : We have the buck 0x118ecd0 0x118ecd0
17:45:49.751: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:49.751: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 2 serial 9
17:45:49.751: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:49.751: 7634: debug : virNetClientStreamQueuePacket:310 : Stream incoming data offset 0 length 0 EOF 1
17:45:49.751: 7634: debug : virNetClientStreamEventTimerUpdate:75 : Check timer offset=0 1
17:45:49.751: 7634: debug : virNetClientStreamEventTimerUpdate:80 : Enabling event timer
17:45:49.751: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 136 total (132 more)
17:45:49.751: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 201 type 3 status 1 serial 9
17:45:49.751: 7634: debug : virNetClientCallDispatchStream:619 : Found call (nil)
17:45:49.754: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 36 total (32 more)
17:45:49.754: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 212 type 1 status 0 serial 10
17:45:49.754: 7634: debug : virNetClientIOEventLoop:973 : Giving up the buck 0x118ecd0 (nil)
17:45:49.754: 7634: debug : virNetClientIO:1145 : All done with our call (nil) 0x118ecd0 0
17:45:49.754: 7634: debug : virNetMessageFree:57 : msg=0x1208150
17:45:49.754: 7634: debug : virNetClientStreamEventTimer:106 : Got Timer dispatch 1 1 offset=0
17:45:49.754: 7634: error : virNetClientStreamRaiseError:209 : stream had I/O failure
17:45:49.754: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:53.154: 7634: debug : virNetClientIncomingEvent:1165 : Event fired 0x1203900 1
17:45:53.154: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 64 total (60 more)
17:45:53.154: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 107 type 2 status 0 serial 1
17:45:53.154: 7634: debug : virNetClientProgramDispatch:213 : prog=536903814 ver=1 type=2 status=0 serial=1 proc=107
17:45:53.154: 7634: debug : virNetMessageNew:44 : msg=0x12087a0
17:45:53.154: 7634: debug : virNetMessageEncodePayload:255 : Encode length as 60
17:45:53.154: 7634: debug : virNetClientIO:1050 : Outgoing message prog=536903814 version=1 serial=11 proc=212 type=0 length=60 dispatch=(nil)
17:45:53.154: 7634: debug : virNetClientIO:1118 : We have the buck 0x115f950 0x115f950
17:46:04.206: 7634: debug : virNetMessageDecodeLength:125 : Got length, now need 64 total (60 more)
17:46:04.206: 7634: debug : virNetClientCallDispatch:677 : Incoming message prog 536903814 vers 1 proc 107 type 2 status 0 serial 1
17:46:04.206: 7634: debug : virNetClientProgramDispatch:213 : prog=536903814 ver=1 type=2 status=0 serial=1 proc=107
17:49:07.626: 7698: info : libvirt version: 0.9.4
17:49:07.626: 7698: error : virNetSocketNewConnectUNIX:505 : Failed to connect socket to '/usr/local/upstream_libvirt/install/var/run/libvirt/libvirt-sock': No such file or directory
-------------- next part --------------
17:44:58.140: 7603: info : libvirt version: 0.9.4
17:44:58.140: 7603: debug : virNetServerMDNSAddGroup:473 : Adding group 'Virtualization Host elanor'
17:44:58.141: 7603: debug : virNetServerNew:365 : srv=0x19a7c00 refs=1
17:44:58.141: 7603: debug : virNetServerProgramNew:65 : prog=0x19a4200 refs=1
17:44:58.141: 7603: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=2
17:44:58.141: 7603: debug : virNetServerProgramNew:65 : prog=0x19a5890 refs=1
17:44:58.141: 7603: debug : virNetServerProgramRef:86 : prog=0x19a5890 refs=2
17:44:58.141: 7603: debug : virNetSocketNew:116 : localAddr=0x7fff0838cdc0 remoteAddr=(nil) fd=11 errfd=-1 pid=0
17:44:58.141: 7603: debug : virNetSocketNew:174 : sock=0x19a9b00 localAddrStr=127.0.0.1;0 remoteAddrStr=(null)
17:44:58.141: 7603: debug : virNetSocketNew:116 : localAddr=0x7fff0838cdc0 remoteAddr=(nil) fd=12 errfd=-1 pid=0
17:44:58.141: 7603: debug : virNetSocketNew:174 : sock=0x19a9cc0 localAddrStr=127.0.0.1;0 remoteAddrStr=(null)
17:44:58.149: 7603: debug : virNetServerRef:378 : srv=0x19a7c00 refs=2
17:44:58.150: 7603: debug : virNetServerMDNSStart:451 : Starting client 0x19a6c60
17:44:58.158: 7603: debug : virNetServerMDNSTimeoutNew:336 : Add timeout TV (nil)
17:44:58.158: 7603: debug : virNetServerMDNSTimeoutNew:351 : Trigger timed for 1313617498 158371      0 0
17:44:58.158: 7603: debug : virNetServerMDNSWatchNew:282 : New handle 0x19af380 FD 5 Event 1
17:44:58.158: 7603: debug : virNetServerMDNSTimeoutUpdate:383 : Update timeout 0x19ac250 TV 0x301b00df20
17:44:58.158: 7603: debug : virNetServerMDNSTimeoutNew:336 : Add timeout TV 0x7fff0838cca0
17:44:58.158: 7603: debug : virNetServerMDNSTimeoutNew:351 : Trigger timed for 1313617498 158536      1313617523 158521
17:44:58.158: 7603: debug : virNetServerMDNSTimeoutFree:404 : Free timeout 0x19ac370
17:44:58.158: 7603: debug : virNetServerMDNSTimeoutNew:336 : Add timeout TV 0x7fff0838cca0
17:44:58.158: 7603: debug : virNetServerMDNSTimeoutNew:351 : Trigger timed for 1313617498 158865      1313617523 158850
17:44:58.159: 7603: debug : virNetServerMDNSTimeoutFree:404 : Free timeout 0x19af7b0
17:44:58.159: 7603: debug : virNetServerMDNSTimeoutNew:336 : Add timeout TV 0x7fff0838cca0
17:44:58.159: 7603: debug : virNetServerMDNSTimeoutNew:351 : Trigger timed for 1313617498 159122      1313617523 159108
17:44:58.159: 7603: debug : virNetServerMDNSTimeoutFree:404 : Free timeout 0x19acd60
17:44:58.159: 7603: debug : virNetServerMDNSTimeoutNew:336 : Add timeout TV 0x7fff0838ccb0
17:44:58.159: 7603: debug : virNetServerMDNSTimeoutNew:351 : Trigger timed for 1313617498 159392      1313617523 159377
17:44:58.160: 7603: debug : virNetServerMDNSTimeoutFree:404 : Free timeout 0x19aff90
17:44:58.160: 7603: debug : virNetServerMDNSTimeoutNew:336 : Add timeout TV 0x7fff0838cc80
17:44:58.160: 7603: debug : virNetServerMDNSTimeoutNew:351 : Trigger timed for 1313617498 160377      1313617523 160362
17:44:58.160: 7603: debug : virNetServerMDNSTimeoutFree:404 : Free timeout 0x19acb60
17:44:58.160: 7603: debug : virNetServerMDNSTimeoutNew:336 : Add timeout TV 0x7fff0838cc80
17:44:58.160: 7603: debug : virNetServerMDNSTimeoutNew:351 : Trigger timed for 1313617498 160821      1313617523 160806
17:44:58.161: 7603: debug : virNetServerMDNSTimeoutFree:404 : Free timeout 0x19b0000
17:44:58.161: 7603: debug : virNetServerMDNSClientCallback:201 : Callback state=2
17:44:58.161: 7603: debug : virNetServerMDNSClientCallback:208 : Client running 0x19abc70
17:44:58.161: 7603: debug : virNetServerMDNSCreateServices:139 : Adding services to 'Virtualization Host elanor'
17:44:58.161: 7603: debug : virNetServerRun:673 : srv=0x19a7c00 quit=0
17:44:58.161: 7603: debug : virNetServerMDNSTimeoutDispatch:318 : Dispatch timeout 0x19ac250 1
17:44:58.161: 7603: debug : virNetServerMDNSTimeoutUpdate:383 : Update timeout 0x19ac250 TV (nil)
17:44:58.162: 7609: error : networkStartNetworkVirtual:1720 : cannot create bridge 'virbr0': File exists
17:44:58.455: 7609: debug : virNetServerFree:751 : srv=0x19a7c00 refs=2
17:45:29.078: 7603: debug : virNetSocketNew:116 : localAddr=0x7fff0838ccb0 remoteAddr=0x7fff0838cc20 fd=18 errfd=-1 pid=0
17:45:29.078: 7603: debug : virNetSocketNew:174 : sock=0x19b2950 localAddrStr=127.0.0.1;0 remoteAddrStr=127.0.0.1;0
17:45:29.078: 7603: debug : virNetServerClientNew:305 : sock=0x19b2950 auth=2 tls=(nil)
17:45:29.078: 7603: debug : virNetMessageNew:44 : msg=0x7f1a67869010
17:45:29.078: 7603: debug : virNetServerClientNew:331 : client=0x19b2b80 refs=1
17:45:29.078: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x7f1a67869010 tx=(nil)
17:45:29.078: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.078: 7603: debug : virNetServerClientRegisterEvent:180 : Registering client event callback 1
17:45:29.078: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=3
17:45:29.078: 7603: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=3
17:45:29.079: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:29.079: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x7f1a67869010 tx=(nil)
17:45:29.079: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.079: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2b80 message=0x7f1a67869010
17:45:29.079: 7603: debug : virNetMessageNew:44 : msg=0x7f1a67828010
17:45:29.079: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x7f1a67828010 tx=(nil)
17:45:29.079: 7604: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2b80 message=0x7f1a67869010
17:45:29.079: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.079: 7604: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:29.079: 7604: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
17:45:29.079: 7604: debug : virNetMessageEncodePayload:255 : Encode length as 36
17:45:29.079: 7604: debug : virNetServerClientSendMessage:980 : msg=0x7f1a67869010 proc=66 len=36 offset=0
17:45:29.079: 7604: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x7f1a67828010 tx=0x7f1a67869010
17:45:29.079: 7604: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:29.079: 7604: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:29.079: 7604: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=3
17:45:29.079: 7603: debug : virNetMessageFree:57 : msg=0x7f1a67869010
17:45:29.079: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x7f1a67828010 tx=(nil)
17:45:29.079: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.080: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 56 total (52 more)
17:45:29.080: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x7f1a67828010 tx=(nil)
17:45:29.080: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.080: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2b80 message=0x7f1a67828010
17:45:29.080: 7605: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2b80 message=0x7f1a67828010
17:45:29.080: 7605: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:29.080: 7605: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=1
17:45:29.080: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:29.080: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:29.080: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.086: 7605: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:29.086: 7605: debug : virNetServerClientSendMessage:980 : msg=0x7f1a67828010 proc=1 len=28 offset=0
17:45:29.086: 7605: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x7f1a67828010
17:45:29.086: 7605: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:29.087: 7605: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:29.087: 7605: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=3
17:45:29.087: 7603: debug : virNetMessageFree:57 : msg=0x7f1a67828010
17:45:29.087: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:29.087: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.088: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 44 total (40 more)
17:45:29.088: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:29.088: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.088: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2b80 message=0x19b7e80
17:45:29.088: 7606: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2b80 message=0x19b7e80
17:45:29.088: 7606: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:29.088: 7606: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=24
17:45:29.088: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:29.088: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:29.088: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.088: 7606: debug : virNetMessageEncodePayload:255 : Encode length as 56
17:45:29.088: 7606: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=24 len=56 offset=0
17:45:29.088: 7606: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:45:29.088: 7606: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:29.088: 7606: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:29.088: 7606: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=3
17:45:29.088: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:29.088: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:29.088: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.089: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 60 total (56 more)
17:45:29.089: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:29.089: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.089: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2b80 message=0x19f7ed0
17:45:29.089: 7607: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2b80 message=0x19f7ed0
17:45:29.089: 7607: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:29.089: 7607: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=212
17:45:29.089: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:29.089: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:29.089: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.089: 7607: debug : virNetMessageEncodePayload:255 : Encode length as 36
17:45:29.089: 7607: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=212 len=36 offset=0
17:45:29.089: 7607: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x19f7ed0
17:45:29.089: 7607: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:29.089: 7607: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:29.089: 7607: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=3
17:45:29.089: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:29.089: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:29.089: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.090: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:29.090: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:29.090: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.090: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2b80 message=0x19b7e80
17:45:29.090: 7608: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2b80 message=0x19b7e80
17:45:29.090: 7608: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:29.090: 7608: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=105
17:45:29.090: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:29.090: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:29.090: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:29.090: 7608: debug : virNetMessageEncodePayload:255 : Encode length as 32
17:45:29.090: 7608: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=105 len=32 offset=0
17:45:29.090: 7608: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:45:29.090: 7608: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:29.090: 7608: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:29.090: 7608: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=3
17:45:29.090: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:29.090: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:29.090: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.261: 7603: debug : virNetSocketNew:116 : localAddr=0x7fff0838ccb0 remoteAddr=0x7fff0838cc20 fd=21 errfd=-1 pid=0
17:45:33.261: 7603: debug : virNetSocketNew:174 : sock=0x19b2530 localAddrStr=127.0.0.1;0 remoteAddrStr=127.0.0.1;0
17:45:33.261: 7603: debug : virNetServerClientNew:305 : sock=0x19b2530 auth=2 tls=(nil)
17:45:33.261: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:33.261: 7603: debug : virNetServerClientNew:331 : client=0x19b2760 refs=1
17:45:33.261: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:33.261: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.261: 7603: debug : virNetServerClientRegisterEvent:180 : Registering client event callback 1
17:45:33.261: 7603: debug : virNetServerClientRef:346 : client=0x19b2760 refs=3
17:45:33.261: 7603: debug : virNetServerClientFree:538 : client=0x19b2760 refs=3
17:45:33.261: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:33.261: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:33.261: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.261: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2760 message=0x19b7e80
17:45:33.261: 7604: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2760 message=0x19b7e80
17:45:33.261: 7604: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:33.261: 7604: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
17:45:33.261: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:33.261: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:33.261: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.261: 7604: debug : virNetMessageEncodePayload:255 : Encode length as 36
17:45:33.261: 7604: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=66 len=36 offset=0
17:45:33.262: 7604: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=0x19b7e80
17:45:33.262: 7604: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:33.262: 7604: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:33.262: 7604: debug : virNetServerClientFree:538 : client=0x19b2760 refs=3
17:45:33.262: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:33.262: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:33.262: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.262: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 40 total (36 more)
17:45:33.262: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:33.262: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.262: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2760 message=0x1a37f20
17:45:33.262: 7605: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2760 message=0x1a37f20
17:45:33.262: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:33.262: 7605: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:33.263: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:33.263: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.263: 7605: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=1
17:45:33.269: 7605: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:33.269: 7605: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=1 len=28 offset=0
17:45:33.269: 7605: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37f20
17:45:33.269: 7605: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:33.269: 7605: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:33.269: 7605: debug : virNetServerClientFree:538 : client=0x19b2760 refs=3
17:45:33.270: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:33.270: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:33.270: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.270: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:33.270: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:33.270: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.270: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2760 message=0x19b7e80
17:45:33.270: 7606: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2760 message=0x19b7e80
17:45:33.270: 7606: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:33.270: 7606: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=110
17:45:33.270: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:33.270: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:33.270: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.270: 7606: debug : virNetMessageEncodePayload:255 : Encode length as 48
17:45:33.270: 7606: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=110 len=48 offset=0
17:45:33.270: 7606: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=0x19b7e80
17:45:33.270: 7606: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:33.270: 7606: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:33.270: 7606: debug : virNetServerClientFree:538 : client=0x19b2760 refs=3
17:45:33.271: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:33.271: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:33.271: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.271: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 36 total (32 more)
17:45:33.271: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:33.271: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.271: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2760 message=0x1a37f20
17:45:33.271: 7607: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2760 message=0x1a37f20
17:45:33.271: 7607: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:33.271: 7607: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=23
17:45:33.271: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:33.271: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:33.271: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.271: 7607: debug : virNetMessageEncodePayload:255 : Encode length as 56
17:45:33.271: 7607: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=23 len=56 offset=0
17:45:33.271: 7607: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37f20
17:45:33.271: 7607: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:33.271: 7607: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:33.271: 7607: debug : virNetServerClientFree:538 : client=0x19b2760 refs=3
17:45:33.272: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:33.272: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:33.272: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.272: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 56 total (52 more)
17:45:33.272: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:33.272: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.272: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2760 message=0x19b7e80
17:45:33.272: 7608: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2760 message=0x19b7e80
17:45:33.272: 7608: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:33.272: 7608: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=9
17:45:33.272: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:33.272: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:33.272: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.298: 7608: warning : qemuDomainObjTaint:1091 : Domain id=1 name='foo' uuid=32ad945f-7e78-c33a-e96d-39f25e025d81 is tainted: high-privileges
17:45:33.298: 7608: warning : qemuDomainObjTaint:1091 : Domain id=1 name='foo' uuid=32ad945f-7e78-c33a-e96d-39f25e025d81 is tainted: custom-argv
17:45:33.553: 7608: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:33.553: 7608: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=9 len=28 offset=0
17:45:33.553: 7608: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=0x19b7e80
17:45:33.553: 7608: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:33.553: 7608: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:33.553: 7608: debug : virNetServerClientFree:538 : client=0x19b2760 refs=3
17:45:33.553: 7603: debug : virNetMessageNew:44 : msg=0x1a77f70
17:45:33.553: 7603: debug : virNetMessageEncodePayload:255 : Encode length as 64
17:45:33.553: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a77f70 proc=107 len=64 offset=0
17:45:33.553: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x1a77f70
17:45:33.553: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:33.554: 7603: debug : virNetMessageFree:57 : msg=0x1a77f70
17:45:33.560: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:33.560: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.560: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:33.560: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:33.560: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.561: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 60 total (56 more)
17:45:33.561: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:33.561: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.561: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2b80 message=0x19f7ed0
17:45:33.561: 7604: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2b80 message=0x19f7ed0
17:45:33.561: 7604: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:33.561: 7604: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=212
17:45:33.561: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:33.561: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:33.561: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.561: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 44 total (40 more)
17:45:33.561: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:33.561: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.561: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2760 message=0x1a37f20
17:45:33.561: 7605: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2760 message=0x1a37f20
17:45:33.561: 7605: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:33.561: 7605: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=24
17:45:33.561: 7604: debug : virNetMessageEncodePayload:255 : Encode length as 36
17:45:33.561: 7604: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=212 len=36 offset=0
17:45:33.561: 7604: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x19f7ed0
17:45:33.561: 7604: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:33.561: 7604: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:33.561: 7604: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=3
17:45:33.561: 7603: debug : virNetMessageNew:44 : msg=0x1a77f70
17:45:33.561: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a77f70 tx=(nil)
17:45:33.561: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.561: 7605: debug : virNetMessageEncodePayload:255 : Encode length as 56
17:45:33.561: 7605: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=24 len=56 offset=0
17:45:33.561: 7605: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a77f70 tx=0x1a37f20
17:45:33.561: 7605: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:33.561: 7605: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:33.561: 7605: debug : virNetServerClientFree:538 : client=0x19b2760 refs=3
17:45:33.562: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:33.562: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:33.562: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.563: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:33.563: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a77f70 tx=(nil)
17:45:33.563: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.563: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 64 total (60 more)
17:45:33.563: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:33.563: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.563: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2b80 message=0x19b7e80
17:45:33.563: 7606: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2b80 message=0x19b7e80
17:45:33.563: 7606: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:33.563: 7606: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=201
17:45:33.563: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:33.563: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:33.563: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.563: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:33.563: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a77f70 tx=(nil)
17:45:33.563: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.563: 7606: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:33.563: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2760 message=0x1a77f70
17:45:33.563: 7607: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2760 message=0x1a77f70
17:45:33.563: 7607: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=5
17:45:33.563: 7607: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=2
17:45:33.564: 7606: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=4
17:45:33.564: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:33.564: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:33.564: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.564: 7606: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:33.564: 7606: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=201 len=28 offset=0
17:45:33.564: 7607: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:33.564: 7606: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:45:33.564: 7607: debug : virNetServerClientSendMessage:980 : msg=0x1a77f70 proc=2 len=28 offset=0
17:45:33.564: 7606: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:33.564: 7607: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=0x1a77f70
17:45:33.564: 7607: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:33.564: 7606: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=5
17:45:33.564: 7606: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=4
17:45:33.564: 7607: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:33.564: 7607: debug : virNetServerClientFree:538 : client=0x19b2760 refs=3
17:45:33.564: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:33.564: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:33.564: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.566: 7603: debug : virNetMessageFree:57 : msg=0x1a77f70
17:45:33.566: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:33.566: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:33.566: 7603: debug : virNetServerClientClose:576 : client=0x19b2760 refs=2
17:45:33.566: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:33.566: 7603: debug : virNetServerClientFree:538 : client=0x19b2760 refs=2
17:45:33.566: 7603: debug : virNetServerClientFree:538 : client=0x19b2760 refs=1
17:45:33.567: 7603: debug : virNetSocketFree:669 : sock=0x19b2530 fd=21
17:45:36.371: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:36.371: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=4
17:45:36.371: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37f20 data=0x19b7e80 len=21
17:45:36.371: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 49
17:45:36.371: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:36.371: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=201 len=49 offset=0
17:45:36.371: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x1a37f20
17:45:36.371: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:36.372: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:36.372: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:36.372: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:36.373: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:36.373: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=5
17:45:36.373: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37f20 data=0x19b7e80 len=1
17:45:36.373: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 29
17:45:36.373: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:36.373: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=201 len=29 offset=0
17:45:36.373: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x1a37f20
17:45:36.373: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:36.373: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:36.373: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:36.373: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:36.385: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 37 total (33 more)
17:45:36.385: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:36.385: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:36.385: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:36.385: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:36.385: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:36.385: 7603: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=0 len=0 offset=0
17:45:36.385: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x19f7ed0
17:45:36.385: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:36.386: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:36.386: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:36.386: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:37.163: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:37.163: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=6
17:45:37.163: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=61
17:45:37.163: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 89
17:45:37.163: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:37.163: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=89 offset=0
17:45:37.163: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:37.163: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:37.163: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:37.163: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:37.163: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:37.164: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:37.164: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=7
17:45:37.164: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=62
17:45:37.164: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 90
17:45:37.164: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:37.164: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=90 offset=0
17:45:37.164: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:37.164: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:37.164: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:37.164: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:37.164: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:37.165: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:37.165: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=8
17:45:37.165: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=49
17:45:37.165: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 77
17:45:37.165: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:37.165: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=77 offset=0
17:45:37.165: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:37.165: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:37.165: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:37.165: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:37.165: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:37.172: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:37.172: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=9
17:45:37.172: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=24
17:45:37.172: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 52
17:45:37.172: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:37.172: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=52 offset=0
17:45:37.172: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:37.172: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:37.172: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:37.172: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:37.172: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:37.173: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:37.173: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=10
17:45:37.173: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=12
17:45:37.173: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 40
17:45:37.173: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:37.173: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=40 offset=0
17:45:37.173: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:37.173: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:37.174: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:37.174: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:37.174: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:37.175: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:37.175: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=11
17:45:37.175: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=7
17:45:37.175: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 35
17:45:37.175: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:37.175: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=35 offset=0
17:45:37.175: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:37.175: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:37.175: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:37.175: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:37.175: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:37.176: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:37.176: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=12
17:45:37.176: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=26
17:45:37.176: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 54
17:45:37.176: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:37.176: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=54 offset=0
17:45:37.176: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:37.176: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:37.177: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:37.177: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:37.177: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:37.177: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:37.177: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=13
17:45:37.177: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=27
17:45:37.177: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 55
17:45:37.177: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:37.177: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=55 offset=0
17:45:37.177: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:37.177: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:37.178: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:37.178: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:37.178: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:37.343: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:37.343: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=14
17:45:37.343: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=6
17:45:37.343: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 34
17:45:37.343: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:37.343: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=34 offset=0
17:45:37.343: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:37.343: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:37.344: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:37.344: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:37.344: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:37.344: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:37.344: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=15
17:45:37.344: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=28
17:45:37.344: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 56
17:45:37.344: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:37.344: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=56 offset=0
17:45:37.344: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:37.344: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:37.345: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:37.345: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:37.345: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:37.345: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:37.345: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=16
17:45:37.345: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=26
17:45:37.345: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 54
17:45:37.345: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:37.345: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=54 offset=0
17:45:37.345: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:37.345: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:37.346: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:37.346: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:37.346: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.343: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:38.343: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=17
17:45:38.343: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=6
17:45:38.343: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 34
17:45:38.343: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:38.343: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=34 offset=0
17:45:38.343: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:38.343: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:38.343: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:38.343: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:38.343: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.344: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:38.344: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=18
17:45:38.344: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=30
17:45:38.344: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 58
17:45:38.344: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:38.344: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=58 offset=0
17:45:38.344: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:38.344: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:38.344: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:38.344: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:38.344: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.345: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:38.345: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=19
17:45:38.345: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=24
17:45:38.345: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 52
17:45:38.345: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:38.345: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=52 offset=0
17:45:38.345: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:38.345: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:38.345: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:38.346: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:38.346: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.368: 7603: debug : virNetSocketNew:116 : localAddr=0x7fff0838ccb0 remoteAddr=0x7fff0838cc20 fd=21 errfd=-1 pid=0
17:45:38.368: 7603: debug : virNetSocketNew:174 : sock=0x19a6f00 localAddrStr=127.0.0.1;0 remoteAddrStr=127.0.0.1;0
17:45:38.368: 7603: debug : virNetServerClientNew:305 : sock=0x19a6f00 auth=2 tls=(nil)
17:45:38.368: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:38.369: 7603: debug : virNetServerClientNew:331 : client=0x19b2530 refs=1
17:45:38.369: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:38.369: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.369: 7603: debug : virNetServerClientRegisterEvent:180 : Registering client event callback 1
17:45:38.369: 7603: debug : virNetServerClientRef:346 : client=0x19b2530 refs=3
17:45:38.369: 7603: debug : virNetServerClientFree:538 : client=0x19b2530 refs=3
17:45:38.369: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:38.369: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:38.369: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.369: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2530 message=0x19f7ed0
17:45:38.369: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:38.369: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:38.369: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.369: 7608: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2530 message=0x19f7ed0
17:45:38.369: 7608: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:38.369: 7608: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
17:45:38.369: 7608: debug : virNetMessageEncodePayload:255 : Encode length as 36
17:45:38.369: 7608: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=66 len=36 offset=0
17:45:38.369: 7608: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=0x19f7ed0
17:45:38.369: 7608: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:38.369: 7608: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:38.369: 7608: debug : virNetServerClientFree:538 : client=0x19b2530 refs=3
17:45:38.370: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:38.370: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:38.370: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.380: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 40 total (36 more)
17:45:38.380: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:38.380: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.380: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2530 message=0x1a37f20
17:45:38.380: 7604: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2530 message=0x1a37f20
17:45:38.380: 7604: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:38.380: 7604: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=1
17:45:38.381: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:38.381: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:38.381: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.391: 7604: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:38.391: 7604: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=1 len=28 offset=0
17:45:38.391: 7604: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x1a37f20
17:45:38.391: 7604: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:38.391: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:38.391: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:38.391: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.391: 7604: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:38.391: 7604: debug : virNetServerClientFree:538 : client=0x19b2530 refs=3
17:45:38.392: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:38.392: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:38.392: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.392: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2530 message=0x19f7ed0
17:45:38.392: 7605: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2530 message=0x19f7ed0
17:45:38.392: 7605: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:38.392: 7605: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=110
17:45:38.392: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:38.392: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:38.392: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.392: 7605: debug : virNetMessageEncodePayload:255 : Encode length as 48
17:45:38.392: 7605: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=110 len=48 offset=0
17:45:38.392: 7605: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=0x19f7ed0
17:45:38.392: 7605: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:38.392: 7605: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:38.392: 7605: debug : virNetServerClientFree:538 : client=0x19b2530 refs=3
17:45:38.392: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:38.392: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:38.392: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.393: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 36 total (32 more)
17:45:38.393: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:38.393: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.393: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2530 message=0x1a37f20
17:45:38.393: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:38.393: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:38.393: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.393: 7606: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2530 message=0x1a37f20
17:45:38.393: 7606: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:38.393: 7606: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=23
17:45:38.393: 7606: debug : virNetMessageEncodePayload:255 : Encode length as 56
17:45:38.393: 7606: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=23 len=56 offset=0
17:45:38.393: 7606: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x1a37f20
17:45:38.393: 7606: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:38.393: 7606: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:38.393: 7606: debug : virNetServerClientFree:538 : client=0x19b2530 refs=3
17:45:38.394: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:38.394: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:38.394: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.394: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 56 total (52 more)
17:45:38.394: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:38.394: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:38.394: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2530 message=0x19f7ed0
17:45:38.394: 7607: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2530 message=0x19f7ed0
17:45:38.394: 7607: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:38.394: 7607: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=12
17:45:38.394: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:38.394: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:38.394: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:40.299: 7607: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:40.299: 7607: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=12 len=28 offset=0
17:45:40.299: 7607: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=0x19f7ed0
17:45:40.299: 7607: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:40.299: 7607: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:40.299: 7607: debug : virNetServerClientFree:538 : client=0x19b2530 refs=3
17:45:40.300: 7603: debug : virNetMessageNew:44 : msg=0x1a77f70
17:45:40.300: 7603: debug : virNetMessageEncodePayload:255 : Encode length as 64
17:45:40.300: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a77f70 proc=107 len=64 offset=0
17:45:40.300: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a77f70
17:45:40.300: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:40.300: 7603: debug : virNetMessageNew:44 : msg=0x1af7fb0
17:45:40.300: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=20
17:45:40.300: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1af7fb0 data=0x1ab7fc0 len=0
17:45:40.300: 7603: debug : virNetMessageEncodePayloadEmpty:345 : Encode length as 28
17:45:40.300: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:40.300: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1af7fb0 proc=201 len=28 offset=0
17:45:40.300: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a77f70
17:45:40.300: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:40.300: 7603: error : daemonStreamEvent:205 : stream had I/O failure
17:45:40.300: 7603: debug : virNetMessageNew:44 : msg=0x1b38000
17:45:40.300: 7603: debug : virNetServerProgramSendError:122 : prog=536903814 ver=1 proc=201 type=3 serial=6 msg=0x1b38000 rerr=0x7fff0838ccc0
17:45:40.300: 7603: debug : virNetMessageEncodePayload:255 : Encode length as 136
17:45:40.300: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1b38000 proc=201 len=136 offset=0
17:45:40.300: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a77f70
17:45:40.300: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:40.301: 7603: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:40.301: 7603: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=20
17:45:40.301: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:40.301: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:40.301: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:40.301: 7603: debug : virNetMessageFree:57 : msg=0x1a77f70
17:45:40.301: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1af7fb0
17:45:40.301: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:40.301: 7603: error : virStreamEventUpdateCallback:13844 : invalid connection pointer in virStreamEventUpdateCallback
17:45:40.301: 7603: debug : virNetMessageFree:57 : msg=0x1af7fb0
17:45:40.301: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1b38000
17:45:40.301: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:40.301: 7603: debug : virNetMessageFree:57 : msg=0x1b38000
17:45:40.301: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:40.301: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:40.301: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:40.301: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:40.301: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:40.301: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2530 message=0x1a37f20
17:45:40.301: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:40.301: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:40.301: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:40.302: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 60 total (56 more)
17:45:40.302: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:40.302: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:40.302: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2b80 message=0x19b7e80
17:45:40.302: 7608: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2530 message=0x1a37f20
17:45:40.302: 7608: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:40.302: 7603: debug : virNetMessageNew:44 : msg=0x1a77f70
17:45:40.302: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a77f70 tx=(nil)
17:45:40.302: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:40.302: 7608: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=2
17:45:40.302: 7608: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:40.302: 7608: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=2 len=28 offset=0
17:45:40.302: 7604: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2b80 message=0x19b7e80
17:45:40.302: 7604: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:40.302: 7604: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=7 proc=212
17:45:40.302: 7608: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x1a37f20
17:45:40.302: 7608: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:40.302: 7608: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:40.302: 7608: debug : virNetServerClientFree:538 : client=0x19b2530 refs=3
17:45:40.302: 7604: debug : virNetMessageEncodePayload:255 : Encode length as 36
17:45:40.302: 7604: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=212 len=36 offset=0
17:45:40.302: 7604: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a77f70 tx=0x19b7e80
17:45:40.302: 7604: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:40.302: 7604: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:40.302: 7604: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=20
17:45:40.302: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:40.302: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a77f70 tx=(nil)
17:45:40.302: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:40.304: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:40.304: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:40.304: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:40.304: 7603: debug : virNetServerClientClose:576 : client=0x19b2530 refs=2
17:45:40.304: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:40.304: 7603: debug : virNetServerClientFree:538 : client=0x19b2530 refs=2
17:45:40.304: 7603: debug : virNetServerClientFree:538 : client=0x19b2530 refs=1
17:45:40.305: 7603: debug : virNetSocketFree:669 : sock=0x19a6f00 fd=21
17:45:42.594: 7603: debug : virNetSocketNew:116 : localAddr=0x7fff0838ccb0 remoteAddr=0x7fff0838cc20 fd=21 errfd=-1 pid=0
17:45:42.594: 7603: debug : virNetSocketNew:174 : sock=0x19a6f00 localAddrStr=127.0.0.1;0 remoteAddrStr=127.0.0.1;0
17:45:42.594: 7603: debug : virNetServerClientNew:305 : sock=0x19a6f00 auth=2 tls=(nil)
17:45:42.594: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:42.594: 7603: debug : virNetServerClientNew:331 : client=0x19b2530 refs=1
17:45:42.594: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:42.594: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.594: 7603: debug : virNetServerClientRegisterEvent:180 : Registering client event callback 1
17:45:42.594: 7603: debug : virNetServerClientRef:346 : client=0x19b2530 refs=3
17:45:42.594: 7603: debug : virNetServerClientFree:538 : client=0x19b2530 refs=3
17:45:42.594: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:42.594: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:42.594: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.594: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2530 message=0x19b7e80
17:45:42.594: 7605: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2530 message=0x19b7e80
17:45:42.594: 7605: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:42.594: 7605: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
17:45:42.594: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:42.594: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:42.594: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.594: 7605: debug : virNetMessageEncodePayload:255 : Encode length as 36
17:45:42.594: 7605: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=66 len=36 offset=0
17:45:42.594: 7605: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:45:42.594: 7605: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:42.594: 7605: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:42.594: 7605: debug : virNetServerClientFree:538 : client=0x19b2530 refs=3
17:45:42.595: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:42.595: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:42.595: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.595: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 40 total (36 more)
17:45:42.595: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:42.595: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.595: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2530 message=0x19f7ed0
17:45:42.595: 7606: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2530 message=0x19f7ed0
17:45:42.595: 7606: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:42.595: 7606: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=1
17:45:42.595: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:42.595: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:42.595: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.602: 7606: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:42.602: 7606: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=1 len=28 offset=0
17:45:42.602: 7606: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x19f7ed0
17:45:42.602: 7606: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:42.602: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:42.602: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:42.602: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.603: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:42.603: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:42.603: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.603: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2530 message=0x19b7e80
17:45:42.603: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:42.603: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:42.603: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.603: 7606: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:42.603: 7606: debug : virNetServerClientFree:538 : client=0x19b2530 refs=4
17:45:42.603: 7606: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2530 message=0x19b7e80
17:45:42.603: 7606: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:42.603: 7606: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=110
17:45:42.603: 7606: debug : virNetMessageEncodePayload:255 : Encode length as 48
17:45:42.603: 7606: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=110 len=48 offset=0
17:45:42.603: 7606: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:45:42.603: 7606: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:42.603: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:42.603: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:42.603: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.604: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 36 total (32 more)
17:45:42.604: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:42.604: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.604: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2530 message=0x19f7ed0
17:45:42.604: 7608: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2530 message=0x19f7ed0
17:45:42.604: 7608: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:42.604: 7608: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=23
17:45:42.604: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:42.604: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:42.604: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.604: 7608: debug : virNetMessageEncodePayload:255 : Encode length as 56
17:45:42.604: 7608: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=23 len=56 offset=0
17:45:42.604: 7608: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x19f7ed0
17:45:42.604: 7608: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:42.604: 7608: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:42.604: 7608: debug : virNetServerClientFree:538 : client=0x19b2530 refs=4
17:45:42.605: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:42.605: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:42.605: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.605: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 56 total (52 more)
17:45:42.605: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:42.605: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.605: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2530 message=0x19b7e80
17:45:42.605: 7604: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2530 message=0x19b7e80
17:45:42.605: 7604: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:42.605: 7604: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=9
17:45:42.605: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:42.605: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:42.605: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.608: 7606: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:42.608: 7606: debug : virNetServerClientFree:538 : client=0x19b2530 refs=4
17:45:42.637: 7604: warning : qemuDomainObjTaint:1091 : Domain id=2 name='foo' uuid=32ad945f-7e78-c33a-e96d-39f25e025d81 is tainted: high-privileges
17:45:42.637: 7604: warning : qemuDomainObjTaint:1091 : Domain id=2 name='foo' uuid=32ad945f-7e78-c33a-e96d-39f25e025d81 is tainted: custom-argv
17:45:42.885: 7604: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:42.885: 7604: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=9 len=28 offset=0
17:45:42.885: 7604: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:45:42.885: 7604: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:42.885: 7604: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:42.885: 7604: debug : virNetServerClientFree:538 : client=0x19b2530 refs=3
17:45:42.886: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:42.886: 7603: debug : virNetMessageEncodePayload:255 : Encode length as 64
17:45:42.886: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=107 len=64 offset=0
17:45:42.886: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a77f70 tx=0x1a37f20
17:45:42.886: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:42.888: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:42.888: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a77f70 tx=(nil)
17:45:42.888: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.889: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:42.889: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:42.889: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.889: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 60 total (56 more)
17:45:42.889: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a77f70 tx=(nil)
17:45:42.889: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.889: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2b80 message=0x1a77f70
17:45:42.889: 7605: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2b80 message=0x1a77f70
17:45:42.889: 7605: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:42.889: 7605: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=8 proc=212
17:45:42.890: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:42.890: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:42.890: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.890: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 44 total (40 more)
17:45:42.890: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:42.890: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.890: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2530 message=0x19f7ed0
17:45:42.890: 7608: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2530 message=0x19f7ed0
17:45:42.890: 7608: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:42.890: 7608: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=24
17:45:42.891: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:42.891: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:42.891: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.891: 7605: debug : virNetMessageEncodePayload:255 : Encode length as 36
17:45:42.891: 7605: debug : virNetServerClientSendMessage:980 : msg=0x1a77f70 proc=212 len=36 offset=0
17:45:42.891: 7605: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a77f70
17:45:42.891: 7605: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:42.891: 7608: debug : virNetMessageEncodePayload:255 : Encode length as 56
17:45:42.891: 7608: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=24 len=56 offset=0
17:45:42.891: 7608: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=0x19f7ed0
17:45:42.891: 7608: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:42.891: 7605: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:42.891: 7605: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=20
17:45:42.891: 7608: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:42.891: 7608: debug : virNetServerClientFree:538 : client=0x19b2530 refs=3
17:45:42.893: 7603: debug : virNetMessageFree:57 : msg=0x1a77f70
17:45:42.893: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:42.893: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.894: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:42.894: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:42.894: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.894: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 64 total (60 more)
17:45:42.894: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:42.894: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.894: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2b80 message=0x19b7e80
17:45:42.895: 7607: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2b80 message=0x19b7e80
17:45:42.895: 7607: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:42.895: 7607: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=9 proc=201
17:45:42.895: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:42.895: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:42.895: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.895: 7607: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:42.896: 7607: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=21
17:45:42.896: 7607: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:42.896: 7607: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=201 len=28 offset=0
17:45:42.896: 7607: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:45:42.896: 7607: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:42.896: 7607: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:42.896: 7607: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=21
17:45:42.897: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:42.897: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:42.897: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.897: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2530 message=0x1a37f20
17:45:42.897: 7606: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2530 message=0x1a37f20
17:45:42.897: 7606: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:42.897: 7606: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=2
17:45:42.898: 7603: debug : virNetMessageNew:44 : msg=0x1a77f70
17:45:42.898: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a77f70 tx=(nil)
17:45:42.898: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.898: 7606: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:42.898: 7606: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=2 len=28 offset=0
17:45:42.898: 7606: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a77f70 tx=0x1a37f20
17:45:42.898: 7606: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:42.898: 7606: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:42.898: 7606: debug : virNetServerClientFree:538 : client=0x19b2530 refs=3
17:45:42.899: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:42.899: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:42.899: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.900: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:42.900: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a77f70 tx=(nil)
17:45:42.900: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:42.900: 7603: debug : virNetServerClientClose:576 : client=0x19b2530 refs=2
17:45:42.900: 7603: debug : virNetMessageFree:57 : msg=0x1a77f70
17:45:42.900: 7603: debug : virNetServerClientFree:538 : client=0x19b2530 refs=2
17:45:42.900: 7603: debug : virNetServerClientFree:538 : client=0x19b2530 refs=1
17:45:42.901: 7603: debug : virNetSocketFree:669 : sock=0x19a6f00 fd=21
17:45:45.713: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:45.713: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=21
17:45:45.713: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37f20 data=0x19b7e80 len=22
17:45:45.713: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 50
17:45:45.713: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:45.713: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=201 len=50 offset=0
17:45:45.713: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x1a37f20
17:45:45.713: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:45.713: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:45.714: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:45.714: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:45.725: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 37 total (33 more)
17:45:45.725: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:45.725: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:45.725: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:45.725: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:45.725: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:45.726: 7603: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=0 len=0 offset=0
17:45:45.726: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x19f7ed0
17:45:45.726: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:45.726: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:45.726: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:45.726: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:46.501: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:46.501: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=22
17:45:46.501: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=25
17:45:46.501: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 53
17:45:46.501: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:46.501: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=53 offset=0
17:45:46.501: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:46.501: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:46.501: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:46.501: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:46.501: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:46.502: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:46.502: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=23
17:45:46.502: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=59
17:45:46.502: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 87
17:45:46.502: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:46.502: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=87 offset=0
17:45:46.502: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:46.502: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:46.502: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:46.502: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:46.502: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:46.503: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:46.503: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=24
17:45:46.503: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=48
17:45:46.503: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 76
17:45:46.503: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:46.503: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=76 offset=0
17:45:46.503: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:46.503: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:46.503: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:46.503: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:46.503: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:46.504: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:46.504: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=25
17:45:46.504: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=40
17:45:46.504: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 68
17:45:46.504: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:46.504: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=68 offset=0
17:45:46.504: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:46.504: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:46.504: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:46.504: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:46.504: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:46.509: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:46.509: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=26
17:45:46.509: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=10
17:45:46.509: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 38
17:45:46.509: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:46.509: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=38 offset=0
17:45:46.509: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:46.509: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:46.509: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:46.509: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:46.509: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:46.509: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:46.509: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=27
17:45:46.509: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=8
17:45:46.509: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 36
17:45:46.509: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:46.509: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=36 offset=0
17:45:46.510: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:46.510: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:46.510: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:46.510: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:46.510: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:46.511: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:46.511: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=28
17:45:46.511: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=18
17:45:46.511: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 46
17:45:46.511: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:46.511: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=46 offset=0
17:45:46.511: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:46.511: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:46.511: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:46.511: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:46.511: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:46.518: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:46.518: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=29
17:45:46.518: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=60
17:45:46.518: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 88
17:45:46.518: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:46.518: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=88 offset=0
17:45:46.518: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:46.518: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:46.518: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:46.518: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:46.518: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:46.685: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:46.685: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=30
17:45:46.685: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=14
17:45:46.685: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 42
17:45:46.685: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:46.685: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=42 offset=0
17:45:46.685: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:46.685: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:46.685: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:46.685: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:46.685: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:46.686: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:46.686: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=31
17:45:46.686: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=33
17:45:46.686: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 61
17:45:46.686: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:46.686: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=61 offset=0
17:45:46.686: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:46.686: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:46.686: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:46.686: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:46.686: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:46.687: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:46.687: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=32
17:45:46.687: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=13
17:45:46.687: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 41
17:45:46.687: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:46.687: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=41 offset=0
17:45:46.687: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:46.687: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:46.687: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:46.687: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:46.687: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.685: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:47.685: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=33
17:45:47.685: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=14
17:45:47.685: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 42
17:45:47.685: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:47.685: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=42 offset=0
17:45:47.685: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:47.685: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:47.685: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:47.685: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:47.685: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.686: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:47.686: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=34
17:45:47.686: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=33
17:45:47.686: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 61
17:45:47.686: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:47.686: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=61 offset=0
17:45:47.686: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:47.686: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:47.686: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:47.686: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:47.686: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.687: 7603: debug : virNetMessageNew:44 : msg=0x1a37ec0
17:45:47.687: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=35
17:45:47.687: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1a37ec0 data=0x19f7ed0 len=13
17:45:47.687: 7603: debug : virNetMessageEncodePayloadRaw:320 : Encode length as 41
17:45:47.687: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:47.687: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37ec0 proc=201 len=41 offset=0
17:45:47.687: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a37ec0
17:45:47.687: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:47.687: 7603: debug : virNetMessageFree:57 : msg=0x1a37ec0
17:45:47.687: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:47.687: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.836: 7603: debug : virNetSocketNew:116 : localAddr=0x7fff0838ccb0 remoteAddr=0x7fff0838cc20 fd=21 errfd=-1 pid=0
17:45:47.836: 7603: debug : virNetSocketNew:174 : sock=0x19b2530 localAddrStr=127.0.0.1;0 remoteAddrStr=127.0.0.1;0
17:45:47.836: 7603: debug : virNetServerClientNew:305 : sock=0x19b2530 auth=2 tls=(nil)
17:45:47.836: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:47.836: 7603: debug : virNetServerClientNew:331 : client=0x19b27d0 refs=1
17:45:47.836: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:47.836: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.836: 7603: debug : virNetServerClientRegisterEvent:180 : Registering client event callback 1
17:45:47.836: 7603: debug : virNetServerClientRef:346 : client=0x19b27d0 refs=3
17:45:47.836: 7603: debug : virNetServerClientFree:538 : client=0x19b27d0 refs=3
17:45:47.839: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:47.839: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:47.839: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.840: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b27d0 message=0x19f7ed0
17:45:47.840: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:47.840: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:47.840: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.840: 7604: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b27d0 message=0x19f7ed0
17:45:47.840: 7604: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:47.840: 7604: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
17:45:47.840: 7604: debug : virNetMessageEncodePayload:255 : Encode length as 36
17:45:47.840: 7604: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=66 len=36 offset=0
17:45:47.840: 7604: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=0x19f7ed0
17:45:47.840: 7604: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:47.840: 7604: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:47.840: 7604: debug : virNetServerClientFree:538 : client=0x19b27d0 refs=3
17:45:47.840: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:47.840: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:47.840: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.841: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 40 total (36 more)
17:45:47.841: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:47.841: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.841: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b27d0 message=0x1a37f20
17:45:47.841: 7605: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b27d0 message=0x1a37f20
17:45:47.841: 7605: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:47.841: 7605: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=1
17:45:47.841: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:47.841: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:47.841: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.848: 7605: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:47.848: 7605: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=1 len=28 offset=0
17:45:47.848: 7605: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x1a37f20
17:45:47.848: 7605: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:47.848: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:47.848: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:47.848: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.848: 7605: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:47.848: 7605: debug : virNetServerClientFree:538 : client=0x19b27d0 refs=3
17:45:47.848: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:47.848: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:47.848: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.848: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b27d0 message=0x19f7ed0
17:45:47.848: 7608: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b27d0 message=0x19f7ed0
17:45:47.848: 7608: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:47.848: 7608: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=110
17:45:47.848: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:47.848: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:47.848: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.849: 7608: debug : virNetMessageEncodePayload:255 : Encode length as 48
17:45:47.849: 7608: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=110 len=48 offset=0
17:45:47.849: 7608: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=0x19f7ed0
17:45:47.849: 7608: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:47.849: 7608: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:47.849: 7608: debug : virNetServerClientFree:538 : client=0x19b27d0 refs=3
17:45:47.849: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:47.849: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:47.849: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.849: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 36 total (32 more)
17:45:47.849: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:47.849: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.850: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b27d0 message=0x1a37f20
17:45:47.850: 7607: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b27d0 message=0x1a37f20
17:45:47.850: 7607: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:47.850: 7607: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=23
17:45:47.850: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:47.850: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:47.850: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.850: 7607: debug : virNetMessageEncodePayload:255 : Encode length as 56
17:45:47.850: 7607: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=23 len=56 offset=0
17:45:47.850: 7607: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x1a37f20
17:45:47.850: 7607: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:47.850: 7607: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:47.850: 7607: debug : virNetServerClientFree:538 : client=0x19b27d0 refs=3
17:45:47.850: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:47.850: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:47.850: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.850: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 56 total (52 more)
17:45:47.850: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:47.850: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:47.851: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b27d0 message=0x19f7ed0
17:45:47.851: 7606: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b27d0 message=0x19f7ed0
17:45:47.851: 7606: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:47.851: 7606: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=12
17:45:47.851: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:47.851: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:47.851: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:49.748: 7606: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:49.748: 7606: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=12 len=28 offset=0
17:45:49.748: 7606: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=0x19f7ed0
17:45:49.748: 7606: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:49.748: 7606: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:49.748: 7606: debug : virNetServerClientFree:538 : client=0x19b27d0 refs=3
17:45:49.749: 7603: debug : virNetMessageNew:44 : msg=0x1a77f70
17:45:49.749: 7603: debug : virNetMessageEncodePayload:255 : Encode length as 64
17:45:49.749: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a77f70 proc=107 len=64 offset=0
17:45:49.749: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a77f70
17:45:49.749: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:49.749: 7603: debug : virNetMessageNew:44 : msg=0x1af7fb0
17:45:49.749: 7603: debug : virNetServerClientRef:346 : client=0x19b2b80 refs=36
17:45:49.749: 7603: debug : virNetServerProgramSendStreamData:419 : client=0x19b2b80 msg=0x1af7fb0 data=0x1ab7fc0 len=0
17:45:49.749: 7603: debug : virNetMessageEncodePayloadEmpty:345 : Encode length as 28
17:45:49.749: 7603: debug : virNetServerProgramSendStreamData:447 : Total 0
17:45:49.749: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1af7fb0 proc=201 len=28 offset=0
17:45:49.749: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a77f70
17:45:49.749: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:49.749: 7603: error : daemonStreamEvent:205 : stream had I/O failure
17:45:49.749: 7603: debug : virNetMessageNew:44 : msg=0x1b38000
17:45:49.749: 7603: debug : virNetServerProgramSendError:122 : prog=536903814 ver=1 proc=201 type=3 serial=9 msg=0x1b38000 rerr=0x7fff0838ccc0
17:45:49.749: 7603: debug : virNetMessageEncodePayload:255 : Encode length as 136
17:45:49.749: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1b38000 proc=201 len=136 offset=0
17:45:49.749: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1a77f70
17:45:49.749: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:49.749: 7603: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:49.749: 7603: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=36
17:45:49.749: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:49.749: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:49.749: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:49.750: 7603: debug : virNetMessageFree:57 : msg=0x1a77f70
17:45:49.750: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1af7fb0
17:45:49.750: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:49.750: 7603: error : virStreamEventUpdateCallback:13844 : invalid connection pointer in virStreamEventUpdateCallback
17:45:49.750: 7603: debug : virNetMessageFree:57 : msg=0x1af7fb0
17:45:49.750: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x1b38000
17:45:49.750: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:49.750: 7603: debug : virNetMessageFree:57 : msg=0x1b38000
17:45:49.750: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:49.750: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:49.750: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:49.750: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x1a37f20 tx=(nil)
17:45:49.750: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:49.750: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b27d0 message=0x1a37f20
17:45:49.750: 7604: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b27d0 message=0x1a37f20
17:45:49.750: 7604: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:49.750: 7604: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=2
17:45:49.750: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:49.750: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:49.750: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:49.750: 7604: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:49.750: 7604: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=2 len=28 offset=0
17:45:49.750: 7604: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x1a37f20
17:45:49.750: 7604: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:49.750: 7604: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:49.750: 7604: debug : virNetServerClientFree:538 : client=0x19b27d0 refs=3
17:45:49.752: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:49.752: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:49.752: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:49.752: 7603: debug : virNetServerClientClose:576 : client=0x19b27d0 refs=2
17:45:49.752: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:49.752: 7603: debug : virNetServerClientFree:538 : client=0x19b27d0 refs=2
17:45:49.752: 7603: debug : virNetServerClientFree:538 : client=0x19b27d0 refs=1
17:45:49.753: 7603: debug : virNetSocketFree:669 : sock=0x19b2530 fd=21
17:45:49.753: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 60 total (56 more)
17:45:49.753: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:49.753: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:49.753: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2b80 message=0x19b7e80
17:45:49.753: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=(nil) tx=(nil)
17:45:49.753: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=0
17:45:49.753: 7605: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2b80 message=0x19b7e80
17:45:49.753: 7605: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:49.753: 7605: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=10 proc=212
17:45:49.753: 7605: debug : virNetMessageEncodePayload:255 : Encode length as 36
17:45:49.753: 7605: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=212 len=36 offset=0
17:45:49.753: 7605: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=(nil) tx=0x19b7e80
17:45:49.753: 7605: debug : virNetServerClientCalculateHandleMode:160 : mode=2
17:45:49.753: 7605: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:49.754: 7605: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=36
17:45:49.754: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:49.754: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=(nil) tx=(nil)
17:45:49.754: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=0
17:45:52.872: 7603: debug : virNetSocketNew:116 : localAddr=0x7fff0838ccb0 remoteAddr=0x7fff0838cc20 fd=21 errfd=-1 pid=0
17:45:52.872: 7603: debug : virNetSocketNew:174 : sock=0x19b2c40 localAddrStr=127.0.0.1;0 remoteAddrStr=127.0.0.1;0
17:45:52.872: 7603: debug : virNetServerClientNew:305 : sock=0x19b2c40 auth=2 tls=(nil)
17:45:52.872: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:52.872: 7603: debug : virNetServerClientNew:331 : client=0x19b2e70 refs=1
17:45:52.872: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:52.872: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.872: 7603: debug : virNetServerClientRegisterEvent:180 : Registering client event callback 1
17:45:52.872: 7603: debug : virNetServerClientRef:346 : client=0x19b2e70 refs=3
17:45:52.872: 7603: debug : virNetServerClientFree:538 : client=0x19b2e70 refs=3
17:45:52.872: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:52.872: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:52.872: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.872: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2e70 message=0x19b7e80
17:45:52.872: 7608: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2e70 message=0x19b7e80
17:45:52.872: 7608: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:52.872: 7608: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
17:45:52.872: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:52.872: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:52.872: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.872: 7608: debug : virNetMessageEncodePayload:255 : Encode length as 36
17:45:52.872: 7608: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=66 len=36 offset=0
17:45:52.872: 7608: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:45:52.872: 7608: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:52.872: 7608: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:52.872: 7608: debug : virNetServerClientFree:538 : client=0x19b2e70 refs=3
17:45:52.873: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:52.873: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:52.873: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.873: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 40 total (36 more)
17:45:52.873: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:52.873: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.873: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2e70 message=0x19f7ed0
17:45:52.873: 7607: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2e70 message=0x19f7ed0
17:45:52.873: 7607: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:52.873: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:52.873: 7607: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=1
17:45:52.873: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:52.873: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.881: 7607: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:52.881: 7607: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=1 len=28 offset=0
17:45:52.881: 7607: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x19f7ed0
17:45:52.881: 7607: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:52.881: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:52.881: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:52.881: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.882: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:52.882: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:52.882: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.882: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2e70 message=0x19b7e80
17:45:52.882: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:52.882: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:52.882: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.882: 7606: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2e70 message=0x19b7e80
17:45:52.882: 7606: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:52.882: 7606: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=110
17:45:52.882: 7606: debug : virNetMessageEncodePayload:255 : Encode length as 48
17:45:52.882: 7606: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=110 len=48 offset=0
17:45:52.882: 7606: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:45:52.882: 7606: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:52.882: 7606: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:52.882: 7606: debug : virNetServerClientFree:538 : client=0x19b2e70 refs=4
17:45:52.882: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:52.882: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:52.882: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.883: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 36 total (32 more)
17:45:52.883: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:52.883: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.883: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2e70 message=0x19f7ed0
17:45:52.883: 7604: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2e70 message=0x19f7ed0
17:45:52.883: 7604: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=4
17:45:52.883: 7604: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=23
17:45:52.883: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:52.883: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:52.883: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.883: 7604: debug : virNetMessageEncodePayload:255 : Encode length as 56
17:45:52.883: 7604: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=23 len=56 offset=0
17:45:52.883: 7604: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x19f7ed0
17:45:52.883: 7604: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:52.883: 7607: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=4
17:45:52.883: 7607: debug : virNetServerClientFree:538 : client=0x19b2e70 refs=4
17:45:52.883: 7604: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:52.883: 7604: debug : virNetServerClientFree:538 : client=0x19b2e70 refs=3
17:45:52.883: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:52.883: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:52.883: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.884: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 56 total (52 more)
17:45:52.884: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:52.884: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.884: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2e70 message=0x19b7e80
17:45:52.884: 7605: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2e70 message=0x19b7e80
17:45:52.884: 7605: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:52.884: 7605: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=9
17:45:52.884: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:52.884: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:52.884: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:52.916: 7605: warning : qemuDomainObjTaint:1091 : Domain id=3 name='foo' uuid=32ad945f-7e78-c33a-e96d-39f25e025d81 is tainted: high-privileges
17:45:52.916: 7605: warning : qemuDomainObjTaint:1091 : Domain id=3 name='foo' uuid=32ad945f-7e78-c33a-e96d-39f25e025d81 is tainted: custom-argv
17:45:53.153: 7605: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:53.153: 7605: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=9 len=28 offset=0
17:45:53.153: 7605: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:45:53.153: 7605: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:53.153: 7605: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:53.153: 7605: debug : virNetServerClientFree:538 : client=0x19b2e70 refs=3
17:45:53.153: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:45:53.153: 7603: debug : virNetMessageEncodePayload:255 : Encode length as 64
17:45:53.153: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=107 len=64 offset=0
17:45:53.153: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=(nil) tx=0x1a37f20
17:45:53.153: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=2
17:45:53.154: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:45:53.154: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=(nil) tx=(nil)
17:45:53.154: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=0
17:45:53.154: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:53.155: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:53.155: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:53.155: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 44 total (40 more)
17:45:53.155: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:53.155: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:53.155: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2e70 message=0x19f7ed0
17:45:53.155: 7608: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2e70 message=0x19f7ed0
17:45:53.155: 7608: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:53.155: 7608: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=24
17:45:53.155: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:45:53.155: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:53.155: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:53.155: 7608: debug : virNetMessageEncodePayload:255 : Encode length as 56
17:45:53.155: 7608: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=24 len=56 offset=0
17:45:53.155: 7608: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x19f7ed0
17:45:53.155: 7608: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:53.155: 7608: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:53.155: 7608: debug : virNetServerClientFree:538 : client=0x19b2e70 refs=3
17:45:53.156: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:53.156: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:53.156: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:53.156: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:45:53.156: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:45:53.156: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:53.156: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19b2e70 message=0x19b7e80
17:45:53.156: 7606: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19b2e70 message=0x19b7e80
17:45:53.156: 7606: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:45:53.156: 7606: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=2
17:45:53.156: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:45:53.156: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:53.156: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:53.156: 7606: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:45:53.156: 7606: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=2 len=28 offset=0
17:45:53.156: 7606: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:45:53.156: 7606: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:45:53.156: 7606: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:45:53.156: 7606: debug : virNetServerClientFree:538 : client=0x19b2e70 refs=3
17:45:53.158: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:45:53.158: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:45:53.158: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:45:53.158: 7603: debug : virNetServerClientClose:576 : client=0x19b2e70 refs=2
17:45:53.158: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:45:53.158: 7603: debug : virNetServerClientFree:538 : client=0x19b2e70 refs=2
17:45:53.158: 7603: debug : virNetServerClientFree:538 : client=0x19b2e70 refs=1
17:45:53.159: 7603: debug : virNetSocketFree:669 : sock=0x19b2c40 fd=21
17:46:02.283: 7603: debug : virNetSocketNew:116 : localAddr=0x7fff0838ccb0 remoteAddr=0x7fff0838cc20 fd=21 errfd=-1 pid=0
17:46:02.283: 7603: debug : virNetSocketNew:174 : sock=0x19b2c40 localAddrStr=127.0.0.1;0 remoteAddrStr=127.0.0.1;0
17:46:02.283: 7603: debug : virNetServerClientNew:305 : sock=0x19b2c40 auth=2 tls=(nil)
17:46:02.283: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:46:02.283: 7603: debug : virNetServerClientNew:331 : client=0x19a6f70 refs=1
17:46:02.283: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:46:02.283: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.283: 7603: debug : virNetServerClientRegisterEvent:180 : Registering client event callback 1
17:46:02.283: 7603: debug : virNetServerClientRef:346 : client=0x19a6f70 refs=3
17:46:02.283: 7603: debug : virNetServerClientFree:538 : client=0x19a6f70 refs=3
17:46:02.283: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:46:02.283: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:46:02.283: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.283: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19a6f70 message=0x19b7e80
17:46:02.283: 7607: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19a6f70 message=0x19b7e80
17:46:02.283: 7607: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:46:02.283: 7607: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
17:46:02.283: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:46:02.283: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:46:02.283: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.283: 7607: debug : virNetMessageEncodePayload:255 : Encode length as 36
17:46:02.283: 7607: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=66 len=36 offset=0
17:46:02.283: 7607: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:46:02.283: 7607: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:46:02.284: 7607: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:46:02.284: 7607: debug : virNetServerClientFree:538 : client=0x19a6f70 refs=3
17:46:02.284: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:46:02.284: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:46:02.284: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.284: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 40 total (36 more)
17:46:02.284: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:46:02.284: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.284: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19a6f70 message=0x19f7ed0
17:46:02.285: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:46:02.285: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:46:02.285: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.285: 7604: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19a6f70 message=0x19f7ed0
17:46:02.285: 7604: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:46:02.285: 7604: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=1
17:46:02.292: 7604: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:46:02.292: 7604: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=1 len=28 offset=0
17:46:02.292: 7604: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x19f7ed0
17:46:02.292: 7604: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:46:02.292: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:46:02.292: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:46:02.292: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.292: 7604: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:46:02.292: 7604: debug : virNetServerClientFree:538 : client=0x19a6f70 refs=3
17:46:02.292: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:46:02.292: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:46:02.292: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.292: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19a6f70 message=0x19b7e80
17:46:02.293: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:46:02.293: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:46:02.293: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.293: 7605: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19a6f70 message=0x19b7e80
17:46:02.293: 7605: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:46:02.293: 7605: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=110
17:46:02.293: 7605: debug : virNetMessageEncodePayload:255 : Encode length as 48
17:46:02.293: 7605: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=110 len=48 offset=0
17:46:02.293: 7605: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:46:02.293: 7605: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:46:02.293: 7605: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:46:02.293: 7605: debug : virNetServerClientFree:538 : client=0x19a6f70 refs=3
17:46:02.293: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:46:02.293: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:46:02.293: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.294: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 36 total (32 more)
17:46:02.294: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:46:02.294: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.294: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19a6f70 message=0x19f7ed0
17:46:02.294: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:46:02.294: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:46:02.294: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.294: 7608: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19a6f70 message=0x19f7ed0
17:46:02.294: 7608: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:46:02.294: 7608: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=23
17:46:02.294: 7608: debug : virNetMessageEncodePayload:255 : Encode length as 56
17:46:02.294: 7608: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=23 len=56 offset=0
17:46:02.294: 7608: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x19f7ed0
17:46:02.294: 7608: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:46:02.294: 7608: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:46:02.294: 7608: debug : virNetServerClientFree:538 : client=0x19a6f70 refs=3
17:46:02.299: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:46:02.299: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:46:02.299: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.302: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 56 total (52 more)
17:46:02.302: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:46:02.302: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.302: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19a6f70 message=0x19b7e80
17:46:02.303: 7603: debug : virNetMessageNew:44 : msg=0x19f7ed0
17:46:02.303: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:46:02.303: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:02.303: 7606: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19a6f70 message=0x19b7e80
17:46:02.303: 7606: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:46:02.303: 7606: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=12
17:46:04.205: 7606: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:46:04.205: 7606: debug : virNetServerClientSendMessage:980 : msg=0x19b7e80 proc=12 len=28 offset=0
17:46:04.205: 7606: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=0x19b7e80
17:46:04.205: 7606: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:46:04.205: 7606: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:46:04.205: 7606: debug : virNetServerClientFree:538 : client=0x19a6f70 refs=3
17:46:04.205: 7603: debug : virNetMessageNew:44 : msg=0x1a37f20
17:46:04.205: 7603: debug : virNetMessageEncodePayload:255 : Encode length as 64
17:46:04.205: 7603: debug : virNetServerClientSendMessage:980 : msg=0x1a37f20 proc=107 len=64 offset=0
17:46:04.205: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=(nil) tx=0x1a37f20
17:46:04.205: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=2
17:46:04.205: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:46:04.205: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:46:04.205: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:04.206: 7603: debug : virNetMessageFree:57 : msg=0x1a37f20
17:46:04.206: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=(nil) tx=(nil)
17:46:04.206: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=0
17:46:04.206: 7603: debug : virNetMessageDecodeLength:125 : Got length, now need 28 total (24 more)
17:46:04.206: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19f7ed0 tx=(nil)
17:46:04.206: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:04.206: 7603: debug : virNetServerDispatchNewMessage:188 : server=0x19a7c00 client=0x19a6f70 message=0x19f7ed0
17:46:04.206: 7607: debug : virNetServerHandleJob:136 : server=0x19a7c00 client=0x19a6f70 message=0x19f7ed0
17:46:04.206: 7607: debug : virNetServerProgramRef:86 : prog=0x19a4200 refs=3
17:46:04.206: 7607: debug : virNetServerProgramDispatch:233 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=2
17:46:04.206: 7603: debug : virNetMessageNew:44 : msg=0x19b7e80
17:46:04.206: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:46:04.206: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:04.206: 7607: debug : virNetMessageEncodePayload:255 : Encode length as 28
17:46:04.206: 7607: debug : virNetServerClientSendMessage:980 : msg=0x19f7ed0 proc=2 len=28 offset=0
17:46:04.206: 7607: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=0x19f7ed0
17:46:04.206: 7607: debug : virNetServerClientCalculateHandleMode:160 : mode=3
17:46:04.206: 7607: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=3
17:46:04.206: 7607: debug : virNetServerClientFree:538 : client=0x19a6f70 refs=3
17:46:04.206: 7603: debug : virNetMessageFree:57 : msg=0x19f7ed0
17:46:04.206: 7603: debug : virNetServerClientCalculateHandleMode:130 : tls=(nil) hs=-1, rx=0x19b7e80 tx=(nil)
17:46:04.206: 7603: debug : virNetServerClientCalculateHandleMode:160 : mode=1
17:46:04.206: 7603: debug : virNetServerClientClose:576 : client=0x19a6f70 refs=2
17:46:04.207: 7603: debug : virNetMessageFree:57 : msg=0x19b7e80
17:46:04.207: 7603: debug : virNetServerClientFree:538 : client=0x19a6f70 refs=2
17:46:04.207: 7603: debug : virNetServerClientFree:538 : client=0x19a6f70 refs=1
17:46:04.207: 7603: debug : virNetSocketFree:669 : sock=0x19b2c40 fd=21
17:46:21.273: 7603: debug : virNetServerQuit:737 : Quit requested 0x19a7c00
17:46:21.274: 7603: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=2
17:46:21.274: 7603: debug : virNetServerProgramFree:458 : prog=0x19a5890 refs=2
17:46:21.274: 7603: debug : virNetServerFree:751 : srv=0x19a7c00 refs=1
17:46:21.274: 7603: debug : virNetServerProgramFree:458 : prog=0x19a4200 refs=1
17:46:21.274: 7603: debug : virNetServerProgramFree:458 : prog=0x19a5890 refs=1
17:46:21.274: 7603: debug : virNetServerClientClose:576 : client=0x19b2b80 refs=35
17:46:21.274: 7603: debug : virNetServerClientFree:538 : client=0x19b2b80 refs=35
-------------- next part --------------
#!/usr/bin/python -u
import sys, os, logging, libvirt, tty, termios, atexit

def reset_term():
    termios.tcsetattr(0, termios.TCSADRAIN, attrs)

def error_handler(unused, error):
    # The console stream errors on VM shutdown; we don't care, right?
    # if (error[0] == libvirt.VIR_ERR_RPC and
    #    error[1] == libvirt.VIR_FROM_STREAMS):
    #    return
    logging.warn(error)

class Console(object):
    def __init__(self, uri, uuid):
        self.uri = uri
        self.uuid = uuid
        self.connection = libvirt.open(uri)
        self.domain = self.connection.lookupByUUIDString(uuid)
        self.state = self.domain.state(0)
        self.connection.domainEventRegister(lifecycle_callback, self)
        self.stream = None
        self.run_console = True
        logging.info("%s initial state %d, reason %d",
                     self.uuid, self.state[0], self.state[1])

def check_console(console):
    if (console.state[0] == libvirt.VIR_DOMAIN_RUNNING or
        console.state[0] == libvirt.VIR_DOMAIN_PAUSED):
        if console.stream == None:
            console.stream = console.connection.newStream(libvirt.VIR_STREAM_NONBLOCK)
            console.domain.openConsole(None, console.stream, 0)
            console.stream.eventAddCallback(libvirt.VIR_STREAM_EVENT_READABLE, stream_callback, console)
    else:
        if console.stream:
            console.stream.eventRemoveCallback()
            console.stream = None

    return console.run_console

def stdin_callback(watch, fd, events, console):
    readbuf = os.read(fd, 1024)
    if readbuf.startswith(""):
        console.run_console = False
        return
    if console.stream:
        console.stream.send(readbuf)

def stream_callback(stream, events, console):
    try:
        received_data = console.stream.recv(1024)
    except:
        return
    os.write(0, received_data)

def lifecycle_callback (connection, domain, event, detail, console):
    console.state = console.domain.state(0)
    logging.info("%s transitioned to state %d, reason %d",
                 console.uuid, console.state[0], console.state[1])

# main
uri = sys.argv[1]
uuid = sys.argv[2]

logging.basicConfig(filename='msg.log', level=logging.DEBUG)
logging.info("URI: %s", uri)
logging.info("UUID: %s", uuid)

libvirt.virEventRegisterDefaultImpl()
libvirt.registerErrorHandler(error_handler, None)

atexit.register(reset_term)
attrs = termios.tcgetattr(0)
tty.setraw(0)

console = Console(uri, uuid)
console.stdin_watch = libvirt.virEventAddHandle(0, libvirt.VIR_EVENT_HANDLE_READABLE, stdin_callback, console)

while check_console(console):
    libvirt.virEventRunDefaultImpl()


More information about the libvir-list mailing list