<html dir="ltr">
<head>
<meta http-equiv="Content-Type" content="text/html; charset=iso-8859-1">
<style id="owaParaStyle" type="text/css">P {margin-top:0;margin-bottom:0;}</style>
</head>
<body ocsi="0" fpstyle="1">
<div style="direction: ltr;font-family: Tahoma;color: #000000;font-size: 10pt;">Package: libvirt-bin<br>
Version: 0.9.12-11.1<br>
Severity: normal<br>
<br>
Dear Maintainer,<br>
I already made a bug with redhat but am unsure whether it is actually an upstream issue.<br>
You can find it here https://bugzilla.redhat.com/show_bug.cgi?id=969497.<br>
<br>
This bug seems to happen every time I try to virsh destroy (or use appropriate libvirt API call)<br>
while already having an open console via virsh console. Exact steps to reproduce:<br>
<br>
1. Start domain using virsh start <domain><br>
2. Connect to domain console using virsh console <domain><br>
3. In another terminal call virsh destroy <domain><br>
<br>
I get the following messages when I run virsh destroy:<br>
error: Failed to destroy domain wheezy_system<br>
error: End of file while reading data: Input/output error<br>
<br>
I also get the following in the log (WARNING: lots of text below):<br>
Caught Segmentation violation dumping internal log buffer:<br>
<br>
<br>
====== start of log =====<br>
<br>
\001 14:34:30.118+00007959: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce500828f0 tx=(nil)<br>
2013-05-31 14:34:30.118+00007959: debug : virNetServerClientCalculateHandleMode:167 : mode=1<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.118+00007959: debug : virEventRunDefaultImpl:244 : running default event implementation<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=15 e=1 d=0<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=11, f=21 e=25 d=0<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=8 w=14, f=16 e=1 d=0<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=9 w=15, f=19 e=1 d=0<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=10 w=16, f=20 e=1 d=0<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 6 timers<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010873615<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010875118<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollCalculateTimeout:351 : Timeout at 1370010873615 due in 3497 ms<br>
2013-05-31 14:34:30.118+00007959: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=11 timeout=3497<br>
2013-05-31 14:34:30.118+00007962: debug : remoteDispatchOpenHelper:10494 : server=0xe2e920 client=0x7fce50000f60 msg=0x7fce50042160 rerr=0x7fce5a9d2c90 args=0xfe55d0 ret=0xf42850<br>
2013-05-31 14:34:30.118+00007962: debug : remoteDispatchOpen:682 : priv=0xe2f900 conn=(nil)<br>
2013-05-31 14:34:30.118+00007962: debug : virConnectOpen:1383 : name=<br>
2013-05-31 14:34:30.118+00007962: debug : virConnectGetConfigFile:1008 : Loading config file '/etc/libvirt/libvirt.conf'<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1186 : no name, allowing driver auto-select<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1226 : trying driver 0 (Test) ...<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1232 : driver 0 Test returned DECLINED<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1226 : trying driver 1 (Xen) ...<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1232 : driver 1 Xen returned DECLINED<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1226 : trying driver 2 (OPENVZ) ...<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1232 : driver 2 OPENVZ returned DECLINED<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1226 : trying driver 3 (VMWARE) ...<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1232 : driver 3 VMWARE returned DECLINED<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1226 : trying driver 4 (PHYP) ...<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1232 : driver 4 PHYP returned DECLINED<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1226 : trying driver 5 (VBOX) ...<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1232 : driver 5 VBOX returned DECLINED<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1226 : trying driver 6 (remote) ...<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1232 : driver 6 remote returned DECLINED<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1226 : trying driver 7 (QEMU) ...<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1232 : driver 7 QEMU returned SUCCESS<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1254 : network driver 0 Test returned DECLINED<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1254 : network driver 1 VBOX returned DECLINED<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1254 : network driver 2 remote returned DECLINED<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1254 : network driver 3 Network returned SUCCESS<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1269 : interface driver 0 Test returned DECLINED<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1269 : interface driver 1 PHYP returned DECLINED<br>
2013-05-31 14:34:30.118+00007962: debug : do_open:1269 : interface driver 2 remote returned DECLINED<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1269 : interface driver 3 Interface returned SUCCESS<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1285 : storage driver 0 Test returned DECLINED<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1285 : storage driver 1 PHYP returned DECLINED<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1285 : storage driver 2 VBOX returned DECLINED<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1285 : storage driver 3 remote returned DECLINED<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1285 : storage driver 4 storage returned SUCCESS<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1301 : node driver 0 Test returned DECLINED<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1301 : node driver 1 remote returned DECLINED<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1301 : node driver 2 udevDeviceMonitor returned SUCCESS<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1317 : secret driver 0 Test returned DECLINED<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1317 : secret driver 1 remote returned DECLINED<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1317 : secret driver 2 secret returned SUCCESS<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1333 : nwfilter driver 0 Test returned DECLINED<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1333 : nwfilter driver 1 remote returned DECLINED<br>
2013-05-31 14:34:30.126+00007962: debug : do_open:1333 : nwfilter driver 2 nwfilter returned SUCCESS<br>
2013-05-31 14:34:30.126+00007962: debug : virNetMessageEncodePayload:351 : Encode length as 28<br>
2013-05-31 14:34:30.126+00007962: debug : virNetServerClientSendMessage:1109 : msg=0x7fce50042160 proc=1 len=28 offset=0<br>
2013-05-31 14:34:30.126+00007962: debug : virNetServerClientSendMessage:1119 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fce50000f60 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2<br>
2013-05-31 14:34:30.126+00007962: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce500828f0 tx=0x7fce50042160<br>
2013-05-31 14:34:30.126+00007962: debug : virNetServerClientCalculateHandleMode:167 : mode=3<br>
2013-05-31 14:34:30.126+00007962: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=3<br>
2013-05-31 14:34:30.126+00007962: debug : virEventPollInterruptLocked:706 : Interrupting<br>
2013-05-31 14:34:30.126+00007962: debug : virNetServerProgramFree:534 : prog=0xe39bc0 refs=4<br>
2013-05-31 14:34:30.126+00007962: debug : virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x7fce50000f60 refs=4<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollRunOnce:630 : Poll got 1 event(s)<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchTimeouts:415 : Dispatch 6<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchHandles:460 : Dispatch 11<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchHandles:474 : i=0 w=1<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchHandles:474 : i=1 w=2<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchHandles:474 : i=2 w=3<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchHandles:474 : i=3 w=4<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchHandles:474 : i=4 w=5<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchHandles:474 : i=5 w=6<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchHandles:474 : i=6 w=7<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchHandles:474 : i=7 w=11<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchHandles:474 : i=8 w=14<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchHandles:474 : i=9 w=15<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollDispatchHandles:474 : i=10 w=16<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.126+00007959: debug : virEventRunDefaultImpl:244 : running default event implementation<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0<br>
2013-05-31 14:34:30.126+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=15 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=11, f=21 e=25 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=8 w=14, f=16 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=9 w=15, f=19 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=10 w=16, f=20 e=5 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 6 timers<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010873615<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010875118<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCalculateTimeout:351 : Timeout at 1370010873615 due in 3488 ms<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=11 timeout=3488<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollRunOnce:630 : Poll got 1 event(s)<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchTimeouts:415 : Dispatch 6<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:460 : Dispatch 11<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=0 w=1<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=1 w=2<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=2 w=3<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=3 w=4<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=4 w=5<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=5 w=6<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=6 w=7<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=7 w=11<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=8 w=14<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=9 w=15<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=10 w=16<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=16 events=2<br>
2013-05-31 14:34:30.127+00007959: debug : virNetMessageFree:75 : msg=0x7fce50042160 nfds=0 cb=(nil)<br>
2013-05-31 14:34:30.127+00007959: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce500828f0 tx=(nil)<br>
2013-05-31 14:34:30.127+00007959: debug : virNetServerClientCalculateHandleMode:167 : mode=1<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.127+00007959: debug : virEventRunDefaultImpl:244 : running default event implementation<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=15 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=11, f=21 e=25 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=8 w=14, f=16 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=9 w=15, f=19 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=10 w=16, f=20 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 6 timers<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010873615<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010875118<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCalculateTimeout:351 : Timeout at 1370010873615 due in 3488 ms<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=11 timeout=3488<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollRunOnce:630 : Poll got 1 event(s)<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchTimeouts:415 : Dispatch 6<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:460 : Dispatch 11<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=0 w=1<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=1 w=2<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=2 w=3<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=3 w=4<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=4 w=5<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=5 w=6<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=6 w=7<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=7 w=11<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=8 w=14<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=9 w=15<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=10 w=16<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=16 events=1<br>
2013-05-31 14:34:30.127+00007959: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)<br>
2013-05-31 14:34:30.127+00007959: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce500828f0 tx=(nil)<br>
2013-05-31 14:34:30.127+00007959: debug : virNetServerClientCalculateHandleMode:167 : mode=1<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.127+00007959: debug : virNetServerClientDispatchRead:889 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fce50000f60 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3<br>
2013-05-31 14:34:30.127+00007959: debug : virKeepAliveCheckMessage:424 : ka=0x7fce50000d30, client=0x7fce50000f60, msg=0x7fce500828f0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollUpdateTimeout:259 : EVENT_POLL_UPDATE_TIMEOUT: timer=17 frequency=5000<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.127+00007959: debug : virNetServerDispatchNewMessage:196 : server=0xe2e920 client=0x7fce50000f60 message=0x7fce500828f0<br>
2013-05-31 14:34:30.127+00007959: debug : virNetServerProgramRef:87 : prog=0xe39bc0 refs=4<br>
2013-05-31 14:34:30.127+00007960: debug : virNetServerHandleJob:135 : server=0xe2e920 client=0x7fce50000f60 message=0x7fce500828f0 prog=0xe39bc0<br>
2013-05-31 14:34:30.127+00007960: debug : virNetServerProgramDispatch:276 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110<br>
2013-05-31 14:34:30.127+00007959: debug : virNetMessageNew:48 : msg=0x7fce50042160 tracked=1<br>
2013-05-31 14:34:30.127+00007959: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce50042160 tx=(nil)<br>
2013-05-31 14:34:30.127+00007959: debug : virNetServerClientCalculateHandleMode:167 : mode=1<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.127+00007959: debug : virEventRunDefaultImpl:244 : running default event implementation<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0<br>
2013-05-31 14:34:30.127+00007960: debug : remoteDispatchGetURIHelper:6794 : server=0xe2e920 client=0x7fce50000f60 msg=0x7fce500828f0 rerr=0x7fce5b9d4c90 args=0xf42850 ret=0x10bc600<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0<br>
2013-05-31 14:34:30.127+00007960: debug : virConnectGetURI:1756 : conn=0xf45440<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0<br>
2013-05-31 14:34:30.127+00007960: debug : virNetMessageEncodePayload:351 : Encode length as 48<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0<br>
2013-05-31 14:34:30.127+00007960: debug : virNetServerClientSendMessage:1109 : msg=0x7fce500828f0 proc=110 len=48 offset=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=15 e=1 d=0<br>
2013-05-31 14:34:30.127+00007960: debug : virNetServerClientSendMessage:1119 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fce50000f60 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=11, f=21 e=25 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=8 w=14, f=16 e=1 d=0<br>
2013-05-31 14:34:30.127+00007960: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce50042160 tx=0x7fce500828f0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=9 w=15, f=19 e=1 d=0<br>
2013-05-31 14:34:30.127+00007960: debug : virNetServerClientCalculateHandleMode:167 : mode=3<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=10 w=16, f=20 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 6 timers<br>
2013-05-31 14:34:30.127+00007960: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=3<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010873615<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010875127<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCalculateTimeout:351 : Timeout at 1370010873615 due in 3488 ms<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=11 timeout=3488<br>
2013-05-31 14:34:30.127+00007960: debug : virEventPollInterruptLocked:706 : Interrupting<br>
2013-05-31 14:34:30.127+00007960: debug : virNetServerProgramFree:534 : prog=0xe39bc0 refs=4<br>
2013-05-31 14:34:30.127+00007960: debug : virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x7fce50000f60 refs=4<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollRunOnce:630 : Poll got 1 event(s)<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchTimeouts:415 : Dispatch 6<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:460 : Dispatch 11<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=0 w=1<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=1 w=2<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=2 w=3<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=3 w=4<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=4 w=5<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=5 w=6<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=6 w=7<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=7 w=11<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=8 w=14<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=9 w=15<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollDispatchHandles:474 : i=10 w=16<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.127+00007959: debug : virEventRunDefaultImpl:244 : running default event implementation<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=15 e=1 d=0<br>
2013-05-31 14:34:30.127+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=11, f=21 e=25 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=8 w=14, f=16 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=9 w=15, f=19 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=10 w=16, f=20 e=5 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 6 timers<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010873615<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010875127<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCalculateTimeout:351 : Timeout at 1370010873615 due in 3487 ms<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=11 timeout=3487<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollRunOnce:630 : Poll got 1 event(s)<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchTimeouts:415 : Dispatch 6<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:460 : Dispatch 11<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=0 w=1<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=1 w=2<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=2 w=3<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=3 w=4<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=4 w=5<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=5 w=6<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=6 w=7<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=7 w=11<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=8 w=14<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=9 w=15<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=10 w=16<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=16 events=2<br>
2013-05-31 14:34:30.128+00007959: debug : virNetMessageFree:75 : msg=0x7fce500828f0 nfds=0 cb=(nil)<br>
2013-05-31 14:34:30.128+00007959: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce50042160 tx=(nil)<br>
2013-05-31 14:34:30.128+00007959: debug : virNetServerClientCalculateHandleMode:167 : mode=1<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.128+00007959: debug : virEventRunDefaultImpl:244 : running default event implementation<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=15 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=11, f=21 e=25 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=8 w=14, f=16 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=9 w=15, f=19 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=10 w=16, f=20 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 6 timers<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010873615<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010875127<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCalculateTimeout:351 : Timeout at 1370010873615 due in 3487 ms<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=11 timeout=3487<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollRunOnce:630 : Poll got 1 event(s)<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchTimeouts:415 : Dispatch 6<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:460 : Dispatch 11<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=0 w=1<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=1 w=2<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=2 w=3<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=3 w=4<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=4 w=5<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=5 w=6<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=6 w=7<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=7 w=11<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=8 w=14<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=9 w=15<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:474 : i=10 w=16<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=16 events=1<br>
2013-05-31 14:34:30.128+00007959: debug : virNetMessageDecodeLength:149 : Got length, now need 48 total (44 more)<br>
2013-05-31 14:34:30.128+00007959: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce50042160 tx=(nil)<br>
2013-05-31 14:34:30.128+00007959: debug : virNetServerClientCalculateHandleMode:167 : mode=1<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.128+00007959: debug : virNetServerClientDispatchRead:889 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fce50000f60 len=48 prog=536903814 vers=1 proc=23 type=0 status=0 serial=4<br>
2013-05-31 14:34:30.128+00007959: debug : virKeepAliveCheckMessage:424 : ka=0x7fce50000d30, client=0x7fce50000f60, msg=0x7fce50042160<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollUpdateTimeout:259 : EVENT_POLL_UPDATE_TIMEOUT: timer=17 frequency=5000<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.128+00007959: debug : virNetServerDispatchNewMessage:196 : server=0xe2e920 client=0x7fce50000f60 message=0x7fce50042160<br>
2013-05-31 14:34:30.128+00007959: debug : virNetServerProgramRef:87 : prog=0xe39bc0 refs=4<br>
2013-05-31 14:34:30.128+00007964: debug : virNetServerHandleJob:135 : server=0xe2e920 client=0x7fce50000f60 message=0x7fce50042160 prog=0xe39bc0<br>
2013-05-31 14:34:30.128+00007964: debug : virNetServerProgramDispatch:276 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=23<br>
2013-05-31 14:34:30.128+00007959: debug : virNetMessageNew:48 : msg=0x7fce500828f0 tracked=1<br>
2013-05-31 14:34:30.128+00007959: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce500828f0 tx=(nil)<br>
2013-05-31 14:34:30.128+00007959: debug : virNetServerClientCalculateHandleMode:167 : mode=1<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.128+00007959: debug : virEventRunDefaultImpl:244 : running default event implementation<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=15 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=11, f=21 e=25 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=8 w=14, f=16 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=9 w=15, f=19 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=10 w=16, f=20 e=1 d=0<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 6 timers<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010873615<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010875128<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollCalculateTimeout:351 : Timeout at 1370010873615 due in 3487 ms<br>
2013-05-31 14:34:30.128+00007959: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=11 timeout=3487<br>
2013-05-31 14:34:30.129+00007964: debug : remoteDispatchDomainLookupByNameHelper:2748 : server=0xe2e920 client=0x7fce50000f60 msg=0x7fce50042160 rerr=0x7fce599d0c90 args=0x10bc600 ret=0x10bcb20<br>
2013-05-31 14:34:30.129+00007964: debug : virDomainLookupByName:2178 : conn=0xf45440, name=wheezy_system<br>
2013-05-31 14:34:30.129+00007964: debug : virDomainFree:2345 : dom=0x10bcae0, (VM: name=wheezy_system, uuid=d1c016b1-e7c0-4ce5-a039-2f06f7c7f8dd)<br>
2013-05-31 14:34:30.129+00007964: debug : virUnrefDomain:276 : unref domain 0x10bcae0 wheezy_system 1<br>
2013-05-31 14:34:30.129+00007964: debug : virReleaseDomain:238 : release domain 0x10bcae0 wheezy_system d1c016b1-e7c0-4ce5-a039-2f06f7c7f8dd<br>
2013-05-31 14:34:30.129+00007964: debug : virReleaseDomain:246 : unref connection 0xf45440 2<br>
2013-05-31 14:34:30.129+00007964: debug : virNetMessageEncodePayload:351 : Encode length as 68<br>
2013-05-31 14:34:30.129+00007964: debug : virNetServerClientSendMessage:1109 : msg=0x7fce50042160 proc=23 len=68 offset=0<br>
2013-05-31 14:34:30.129+00007964: debug : virNetServerClientSendMessage:1119 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fce50000f60 len=68 prog=536903814 vers=1 proc=23 type=1 status=0 serial=4<br>
2013-05-31 14:34:30.129+00007964: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce500828f0 tx=0x7fce50042160<br>
2013-05-31 14:34:30.129+00007964: debug : virNetServerClientCalculateHandleMode:167 : mode=3<br>
2013-05-31 14:34:30.129+00007964: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=3<br>
2013-05-31 14:34:30.129+00007964: debug : virEventPollInterruptLocked:706 : Interrupting<br>
2013-05-31 14:34:30.129+00007964: debug : virNetServerProgramFree:534 : prog=0xe39bc0 refs=4<br>
2013-05-31 14:34:30.129+00007964: debug : virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x7fce50000f60 refs=4<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollRunOnce:630 : Poll got 1 event(s)<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchTimeouts:415 : Dispatch 6<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:460 : Dispatch 11<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=0 w=1<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=1 w=2<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=2 w=3<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=3 w=4<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=4 w=5<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=5 w=6<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=6 w=7<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=7 w=11<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=8 w=14<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=9 w=15<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=10 w=16<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.129+00007959: debug : virEventRunDefaultImpl:244 : running default event implementation<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=15 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=11, f=21 e=25 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=8 w=14, f=16 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=9 w=15, f=19 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=10 w=16, f=20 e=5 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 6 timers<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010873615<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010875128<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCalculateTimeout:351 : Timeout at 1370010873615 due in 3486 ms<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=11 timeout=3486<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollRunOnce:630 : Poll got 1 event(s)<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchTimeouts:415 : Dispatch 6<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:460 : Dispatch 11<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=0 w=1<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=1 w=2<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=2 w=3<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=3 w=4<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=4 w=5<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=5 w=6<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=6 w=7<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=7 w=11<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=8 w=14<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=9 w=15<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=10 w=16<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=16 events=2<br>
2013-05-31 14:34:30.129+00007959: debug : virNetMessageFree:75 : msg=0x7fce50042160 nfds=0 cb=(nil)<br>
2013-05-31 14:34:30.129+00007959: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce500828f0 tx=(nil)<br>
2013-05-31 14:34:30.129+00007959: debug : virNetServerClientCalculateHandleMode:167 : mode=1<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.129+00007959: debug : virEventRunDefaultImpl:244 : running default event implementation<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=15 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=11, f=21 e=25 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=8 w=14, f=16 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=9 w=15, f=19 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=10 w=16, f=20 e=1 d=0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 6 timers<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010873615<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010875128<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCalculateTimeout:351 : Timeout at 1370010873615 due in 3486 ms<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=11 timeout=3486<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollRunOnce:630 : Poll got 1 event(s)<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchTimeouts:415 : Dispatch 6<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:460 : Dispatch 11<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=0 w=1<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=1 w=2<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=2 w=3<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=3 w=4<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=4 w=5<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=5 w=6<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=6 w=7<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=7 w=11<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=8 w=14<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=9 w=15<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:474 : i=10 w=16<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=16 events=1<br>
2013-05-31 14:34:30.129+00007959: debug : virNetMessageDecodeLength:149 : Got length, now need 68 total (64 more)<br>
2013-05-31 14:34:30.129+00007959: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce500828f0 tx=(nil)<br>
2013-05-31 14:34:30.129+00007959: debug : virNetServerClientCalculateHandleMode:167 : mode=1<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.129+00007959: debug : virNetServerClientDispatchRead:889 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fce50000f60 len=68 prog=536903814 vers=1 proc=12 type=0 status=0 serial=5<br>
2013-05-31 14:34:30.129+00007959: debug : virKeepAliveCheckMessage:424 : ka=0x7fce50000d30, client=0x7fce50000f60, msg=0x7fce500828f0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollUpdateTimeout:259 : EVENT_POLL_UPDATE_TIMEOUT: timer=17 frequency=5000<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.129+00007959: debug : virNetServerDispatchNewMessage:196 : server=0xe2e920 client=0x7fce50000f60 message=0x7fce500828f0<br>
2013-05-31 14:34:30.129+00007959: debug : virNetServerProgramRef:87 : prog=0xe39bc0 refs=4<br>
2013-05-31 14:34:30.129+00007959: debug : virNetMessageNew:48 : msg=0x7fce50042160 tracked=1<br>
2013-05-31 14:34:30.129+00007959: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce50042160 tx=(nil)<br>
2013-05-31 14:34:30.129+00007959: debug : virNetServerClientCalculateHandleMode:167 : mode=1<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.129+00007961: debug : virNetServerHandleJob:135 : server=0xe2e920 client=0x7fce50000f60 message=0x7fce500828f0 prog=0xe39bc0<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.129+00007961: debug : virNetServerProgramDispatch:276 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=12<br>
2013-05-31 14:34:30.129+00007959: debug : virEventRunDefaultImpl:244 : running default event implementation<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.129+00007961: debug : remoteDispatchDomainDestroyHelper:1040 : server=0xe2e920 client=0x7fce50000f60 msg=0x7fce500828f0 rerr=0x7fce5b1d3c90 args=0x10bf040 ret=0xf42850<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.129+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0<br>
2013-05-31 14:34:30.130+00007961: debug : virDomainDestroy:2236 : dom=0x10bf000, (VM: name=wheezy_system, uuid=d1c016b1-e7c0-4ce5-a039-2f06f7c7f8dd)<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=15 e=1 d=0<br>
2013-05-31 14:34:30.130+00007961: debug : qemuProcessKill:3781 : vm=wheezy_system pid=8016 flags=1<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=11, f=21 e=25 d=0<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=8 w=14, f=16 e=1 d=0<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=9 w=15, f=19 e=1 d=0<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=10 w=16, f=20 e=1 d=0<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 6 timers<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010873615<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010875129<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollCalculateTimeout:351 : Timeout at 1370010873615 due in 3485 ms<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=11 timeout=3485<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollRunOnce:630 : Poll got 1 event(s)<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollDispatchTimeouts:415 : Dispatch 6<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollDispatchHandles:460 : Dispatch 11<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollDispatchHandles:474 : i=0 w=1<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollDispatchHandles:474 : i=1 w=2<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollDispatchHandles:474 : i=2 w=3<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollDispatchHandles:474 : i=3 w=4<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollDispatchHandles:474 : i=4 w=5<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollDispatchHandles:474 : i=5 w=6<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollDispatchHandles:474 : i=6 w=7<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollDispatchHandles:474 : i=7 w=11<br>
2013-05-31 14:34:30.130+00007959: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1<br>
2013-05-31 14:34:30.130+00007959: debug : qemuMonitorRef:239 : QEMU_MONITOR_REF: mon=0xf7bab0 refs=3<br>
2013-05-31 14:34:30.130+00007959: debug : qemuMonitorIOProcess:365 : QEMU_MONITOR_IO_PROCESS: mon=0xf7bab0 buf={"timestamp": {"seconds": 1370010870, "microseconds": 130177}, "event": "SHUTDOWN"}<br>
len=85<br>
2013-05-31 14:34:30.130+00007959: debug : qemuMonitorJSONIOProcessLine:139 : Line [{"timestamp": {"seconds": 1370010870, "microseconds": 130177}, "event": "SHUTDOWN"}]<br>
2013-05-31 14:34:30.130+00007959: debug : virJSONValueFromString:947 : string={"timestamp": {"seconds": 1370010870, "microseconds": 130177}, "event": "SHUTDOWN"}<br>
2013-05-31 14:34:30.130+00007959: debug : virJSONParserHandleStartMap:824 : parser=0x7fff8affd540<br>
2013-05-31 14:34:30.130+00007959: debug : virJSONParserHandleMapKey:805 : parser=0x7fff8affd540 key=0x7fce500015b2<br>
2013-05-31 14:34:30.130+00007959: debug : virJSONParserHandleStartMap:824 : parser=0x7fff8affd540<br>
2013-05-31 14:34:30.130+00007959: debug : virJSONParserHandleMapKey:805 : parser=0x7fff8affd540 key=0x7fce500015c0<br>
2013-05-31 14:34:30.130+00007959: debug : virJSONParserHandleNumber:764 : parser=0x7fff8affd540 str=(null)<br>
2013-05-31 14:34:30.130+00007959: debug : virJSONParserHandleMapKey:805 : parser=0x7fff8affd540 key=0x7fce500015d7<br>
2013-05-31 14:34:30.130+00007959: debug : virJSONParserHandleNumber:764 : parser=0x7fff8affd540 str=(null)<br>
2013-05-31 14:34:30.130+00007959: debug : virJSONParserHandleEndMap:853 : parser=0x7fff8affd540<br>
2013-05-31 14:34:30.130+00007959: debug : virJSONParserHandleMapKey:805 : parser=0x7fff8affd540 key=0x7fce500015f0<br>
2013-05-31 14:34:30.130+00007959: debug : virJSONParserHandleString:785 : parser=0x7fff8affd540 str=0x7fce500015f9<br>
2013-05-31 14:34:30.130+00007959: debug : virJSONParserHandleEndMap:853 : parser=0x7fff8affd540<br>
2013-05-31 14:34:30.130+00007959: debug : virJSONValueFromString:991 : result=0x7fce50001ee0<br>
2013-05-31 14:34:30.130+00007959: debug : qemuMonitorJSONIOProcessLine:154 : QEMU_MONITOR_RECV_EVENT: mon=0xf7bab0 event={"timestamp": {"seconds": 1370010870, "microseconds": 130177}, "event": "SHUTDOWN"}<br>
2013-05-31 14:34:30.130+00007959: debug : qemuMonitorJSONIOProcessEvent:111 : mon=0xf7bab0 obj=0x7fce50001ee0<br>
2013-05-31 14:34:30.130+00007959: debug : qemuMonitorJSONIOProcessEvent:125 : handle SHUTDOWN handler=0x4aaf70 data=(nil)<br>
2013-05-31 14:34:30.130+00007959: debug : qemuMonitorEmitShutdown:945 : mon=0xf7bab0<br>
2013-05-31 14:34:30.130+00007959: debug : qemuMonitorRef:239 : QEMU_MONITOR_REF: mon=0xf7bab0 refs=4<br>
2013-05-31 14:34:30.130+00007959: debug : qemuProcessHandleShutdown:636 : vm=0xf7a110<br>
2013-05-31 14:34:30.730+00007961: debug : virDomainObjRef:1644 : obj=0xf7a110 refs=3<br>
2013-05-31 14:34:30.730+00007961: debug : virDomainObjUnref:1651 : obj=0xf7a110 refs=2<br>
2013-05-31 14:34:30.730+00007961: debug : virDomainObjRef:1644 : obj=0xf7a110 refs=3<br>
2013-05-31 14:34:30.730+00007961: debug : qemuDomainObjBeginJobInternal:807 : Starting job: destroy (async=none)<br>
2013-05-31 14:34:30.730+00007961: debug : qemuProcessStop:3884 : Shutting down VM 'wheezy_system' pid=8016 migrated=0<br>
2013-05-31 14:34:30.730+00007961: debug : qemuMonitorClose:797 : QEMU_MONITOR_CLOSE: mon=0xf7bab0 refs=4<br>
2013-05-31 14:34:30.730+00007961: debug : virEventPollRemoveHandle:180 : EVENT_POLL_REMOVE_HANDLE: watch=11<br>
2013-05-31 14:34:30.730+00007961: debug : virEventPollRemoveHandle:193 : mark delete 7 21<br>
2013-05-31 14:34:30.730+00007961: debug : virEventPollInterruptLocked:706 : Interrupting<br>
2013-05-31 14:34:30.730+00007961: debug : qemuMonitorUnref:248 : QEMU_MONITOR_UNREF: mon=0xf7bab0 refs=3<br>
2013-05-31 14:34:30.730+00007961: debug : qemuProcessKill:3781 : vm=wheezy_system pid=8016 flags=5<br>
2013-05-31 14:34:30.730+00007961: debug : qemuDomainCleanupRun:1995 : driver=0xe41b60, vm=wheezy_system<br>
2013-05-31 14:34:30.730+00007961: debug : qemuProcessAutoDestroyRemove:4329 : vm=wheezy_system<br>
2013-05-31 14:34:30.730+00007961: debug : qemuDriverCloseCallbackUnset:578 : vm=wheezy_system, uuid=d1c016b1-e7c0-4ce5-a039-2f06f7c7f8dd, cb=0x491940<br>
2013-05-31 14:34:30.730+00007961: debug : virSecurityDACRestoreSecurityAllLabel:516 : Restoring security label on wheezy_system migrated=0<br>
2013-05-31 14:34:30.730+00007961: info : virSecurityDACRestoreSecurityFileLabel:144 : Restoring DAC user and group on '/build-pool/d1c016b1-e7c0-4ce5-a039-2f06f7c7f8dd.img'<br>
2013-05-31 14:34:30.730+00007961: info : virSecurityDACSetOwnership:100 : Setting DAC user and group on '/build-pool/d1c016b1-e7c0-4ce5-a039-2f06f7c7f8dd.img' to '0:0'<br>
2013-05-31 14:34:30.730+00007961: debug : networkReleaseActualDevice:3109 : Nothing to release to network build-net<br>
2013-05-31 14:34:30.730+00007961: debug : qemuDomainObjEndJob:937 : Stopping job: destroy (async=none)<br>
2013-05-31 14:34:30.730+00007961: debug : virDomainObjUnref:1651 : obj=0xf7a110 refs=2<br>
2013-05-31 14:34:30.730+00007961: debug : virDomainObjUnref:1651 : obj=0xf7a110 refs=1<br>
2013-05-31 14:34:30.730+00007961: debug : virDomainFree:2345 : dom=0x10bf000, (VM: name=wheezy_system, uuid=d1c016b1-e7c0-4ce5-a039-2f06f7c7f8dd)<br>
2013-05-31 14:34:30.730+00007961: debug : virUnrefDomain:276 : unref domain 0x10bf000 wheezy_system 1<br>
2013-05-31 14:34:30.730+00007961: debug : virReleaseDomain:238 : release domain 0x10bf000 wheezy_system d1c016b1-e7c0-4ce5-a039-2f06f7c7f8dd<br>
2013-05-31 14:34:30.730+00007961: debug : virReleaseDomain:246 : unref connection 0xf45440 2<br>
2013-05-31 14:34:30.730+00007961: debug : virNetMessageEncodePayload:351 : Encode length as 28<br>
2013-05-31 14:34:30.730+00007961: debug : virNetServerClientSendMessage:1109 : msg=0x7fce500828f0 proc=12 len=28 offset=0<br>
2013-05-31 14:34:30.731+00007961: debug : virNetServerClientSendMessage:1119 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fce50000f60 len=28 prog=536903814 vers=1 proc=12 type=1 status=0 serial=5<br>
2013-05-31 14:34:30.731+00007961: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce50042160 tx=0x7fce500828f0<br>
2013-05-31 14:34:30.731+00007961: debug : virNetServerClientCalculateHandleMode:167 : mode=3<br>
2013-05-31 14:34:30.731+00007961: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=3<br>
2013-05-31 14:34:30.731+00007961: debug : virEventPollInterruptLocked:706 : Interrupting<br>
2013-05-31 14:34:30.731+00007961: debug : virNetServerProgramFree:534 : prog=0xe39bc0 refs=4<br>
2013-05-31 14:34:30.731+00007961: debug : virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x7fce50000f60 refs=4<br>
2013-05-31 14:34:30.731+00007959: debug : qemuProcessHandleShutdown:647 : Ignoring SHUTDOWN event from inactive domain wheezy_system<br>
2013-05-31 14:34:30.731+00007959: debug : qemuMonitorUnref:248 : QEMU_MONITOR_UNREF: mon=0xf7bab0 refs=2<br>
2013-05-31 14:34:30.731+00007959: debug : qemuMonitorJSONIOProcess:210 : Total used 85 bytes out of 85 available in buffer<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=13<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.731+00007959: debug : qemuMonitorUnref:248 : QEMU_MONITOR_UNREF: mon=0xf7bab0 refs=1<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:474 : i=8 w=14<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:474 : i=9 w=15<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:474 : i=10 w=16<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 11<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollCleanupHandles:567 : EVENT_POLL_PURGE_HANDLE: watch=11<br>
2013-05-31 14:34:30.731+00007959: debug : qemuMonitorUnref:248 : QEMU_MONITOR_UNREF: mon=0xf7bab0 refs=0<br>
2013-05-31 14:34:30.731+00007959: debug : qemuMonitorFree:225 : mon=0xf7bab0<br>
2013-05-31 14:34:30.731+00007959: debug : virDomainObjUnref:1651 : obj=0xf7a110 refs=0<br>
2013-05-31 14:34:30.731+00007959: debug : virDomainObjFree:1627 : obj=0xf7a110<br>
2013-05-31 14:34:30.731+00007959: debug : virStreamFree:15495 : stream=0xf44890<br>
2013-05-31 14:34:30.731+00007959: debug : virUnrefStream:1126 : unref stream 0xf44890 3<br>
2013-05-31 14:34:30.731+00007959: debug : virEventRunDefaultImpl:244 : running default event implementation<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollCleanupTimeouts:506 : Cleanup 6<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollCleanupHandles:554 : Cleanup 10<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=15 e=1 d=0<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=14, f=16 e=1 d=0<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=8 w=15, f=19 e=1 d=0<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollMakePollFDs:383 : Prepare n=9 w=16, f=20 e=5 d=0<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 6 timers<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010873615<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1370010875129<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollCalculateTimeout:351 : Timeout at 1370010873615 due in 2884 ms<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=10 timeout=2884<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollRunOnce:630 : Poll got 4 event(s)<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchTimeouts:415 : Dispatch 6<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:460 : Dispatch 10<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:474 : i=0 w=1<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:474 : i=1 w=2<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:474 : i=2 w=3<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:474 : i=3 w=4<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:474 : i=4 w=5<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:474 : i=5 w=6<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1<br>
2013-05-31 14:34:30.731+00007959: debug : udevEventHandleCallback:1467 : udev action: 'remove'<br>
2013-05-31 14:34:30.731+00007959: debug : udevRemoveOneDevice:1218 : Failed to find device to remove that has udev name '/sys/devices/virtual/net/vnet0/queues/rx-0'<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:474 : i=6 w=7<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:474 : i=7 w=14<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:474 : i=8 w=15<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=13<br>
2013-05-31 14:34:30.731+00007959: debug : daemonStreamEvent:144 : st=0xf44890 events=13 EOF=0 closed=0<br>
2013-05-31 14:34:30.731+00007959: debug : daemonStreamHandleRead:722 : client=0x7fce500014d0, stream=0xf448c0 tx=1 closed=0<br>
2013-05-31 14:34:30.731+00007959: debug : virStreamRecv:15027 : stream=0xf44890, data=0x7fce500c2960, nbytes=262120<br>
2013-05-31 14:34:30.731+00007959: debug : virNetMessageNew:48 : msg=0x7fce50102950 tracked=0<br>
2013-05-31 14:34:30.731+00007959: debug : virNetServerProgramSendStreamData:495 : client=0x7fce500014d0 msg=0x7fce50102950 data=0x7fce500c2960 len=0<br>
2013-05-31 14:34:30.731+00007959: debug : virNetMessageEncodePayloadEmpty:441 : Encode length as 28<br>
2013-05-31 14:34:30.731+00007959: debug : virNetServerProgramSendStreamData:523 : Total 28<br>
2013-05-31 14:34:30.731+00007959: debug : virNetServerClientSendMessage:1109 : msg=0x7fce50102950 proc=201 len=28 offset=0<br>
2013-05-31 14:34:30.731+00007959: debug : virNetServerClientSendMessage:1119 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fce500014d0 len=28 prog=536903814 vers=1 proc=201 type=3 status=2 serial=6<br>
2013-05-31 14:34:30.731+00007959: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fce500020f0 tx=0x7fce50102950<br>
2013-05-31 14:34:30.731+00007959: debug : virNetServerClientCalculateHandleMode:167 : mode=3<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=3<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.731+00007959: debug : virStreamEventRemoveCallback:15365 : stream=0xf44890<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollRemoveHandle:180 : EVENT_POLL_REMOVE_HANDLE: watch=15<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollRemoveHandle:193 : mark delete 8 19<br>
2013-05-31 14:34:30.731+00007959: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 1632499744<br>
2013-05-31 14:34:30.731+00007959: debug : virStreamAbort:15449 : stream=0xf44890<br>
2013-05-31 14:34:30.731+00007959: debug : virFDStreamCloseInt:250 : st=0xf44890<br>
<br>
<br>
====== end of log =====<br>
<br>
-- System Information:<br>
Debian Release: 7.0<br>
Architecture: amd64 (x86_64)<br>
<br>
Kernel: Linux 3.2.0-4-amd64 (SMP w/16 CPU cores)<br>
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)<br>
Shell: /bin/sh linked to /bin/dash<br>
<br>
Versions of packages libvirt-bin depends on:<br>
ii adduser 3.113+nmu3<br>
ii gettext-base 0.18.1.1-9<br>
ii libavahi-client3 0.6.31-2<br>
ii libavahi-common3 0.6.31-2<br>
ii libblkid1 2.20.1-5.3<br>
ii libc6 2.13-38<br>
ii libcap-ng0 0.6.6-2<br>
ii libdbus-1-3 1.6.8-1<br>
ii libdevmapper1.02.1 2:1.02.74-7<br>
ii libgcrypt11 1.5.0-5<br>
ii libgnutls26 2.12.20-6<br>
ii libnetcf1 0.1.9-2<br>
ii libnl1 1.1-7<br>
ii libnuma1 2.0.8~rc4-1<br>
ii libparted0debian1 2.3-12<br>
ii libpcap0.8 1.3.0-1<br>
ii libpciaccess0 0.13.1-2<br>
ii libreadline6 6.2+dfsg-0.1<br>
ii libsasl2-2 2.1.25.dfsg1-6<br>
ii libssh2-1 1.4.2-1.1<br>
ii libudev0 175-7.1<br>
ii libvirt0 0.9.12-11.1<br>
ii libxenstore3.0 4.1.4-2<br>
ii libxml2 2.8.0+dfsg1-7+nmu1<br>
ii libyajl2 2.0.4-2<br>
ii logrotate 3.8.1-4<br>
<br>
Versions of packages libvirt-bin recommends:<br>
ii bridge-utils 1.5-6<br>
ii dmidecode 2.11-9<br>
ii dnsmasq-base 2.62-3+deb7u1<br>
ii ebtables 2.0.10.4-1<br>
ii gawk 1:4.0.1+dfsg-2.1<br>
ii iproute 20120521-3+b3<br>
ii iptables 1.4.14-3.1<br>
ii libxml2-utils 2.8.0+dfsg1-7+nmu1<br>
ii netcat-openbsd 1.105-7<br>
ii parted 2.3-12<br>
ii qemu-kvm 1.1.2+dfsg-6<br>
<br>
Versions of packages libvirt-bin suggests:<br>
pn policykit-1 <none><br>
pn radvd <none><br>
<br>
-- no debconf information<br>
</div>
</body>
</html>
<pre>
The information contained in this email message, including any attachments, is
for the sole use of the intended recipients(s) and may contain confidential and
privileged information. Any unauthorized review, use, disclosure or distribution
is prohibited. If you are not the intended recipient and have received this
communication in error, please contact the sender by reply e-mail and destroy all
copies of the original message.</pre>