Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 622766 Details for
Bug 863696
[F18] libguestfs fails to mount a disk image file(in this case qcow2) as 'root'
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
libvirtd.log with debug log 1
libvirtd.log (text/plain), 176.24 KB, created by
Kashyap Chamarthy
on 2012-10-06 17:10:10 UTC
(
hide
)
Description:
libvirtd.log with debug log 1
Filename:
MIME Type:
Creator:
Kashyap Chamarthy
Created:
2012-10-06 17:10:10 UTC
Size:
176.24 KB
patch
obsolete
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 0 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 10 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=3 events=1 >2012-10-06 16:41:37.486+0000: 23344: debug : virNetSocketNew:147 : localAddr=0x7fffd2843790 remoteAddr=0x7fffd2843820 fd=21 errfd=-1 pid=0 >2012-10-06 16:41:37.486+0000: 23344: debug : virObjectNew:110 : OBJECT_NEW: obj=0x1a31a50 classname=virNetSocket >2012-10-06 16:41:37.486+0000: 23344: debug : virNetSocketNew:204 : RPC_SOCKET_NEW: sock=0x1a31a50 fd=21 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0 >2012-10-06 16:41:37.486+0000: 23344: debug : virNetServerClientNew:403 : sock=0x1a31a50 auth=2 tls=(nil) >2012-10-06 16:41:37.486+0000: 23344: debug : virObjectNew:110 : OBJECT_NEW: obj=0x1a31ca0 classname=virNetServerClient >2012-10-06 16:41:37.486+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31a50 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollAddTimeout:220 : Used 0 timeout slots, adding at least 10 more >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollAddTimeout:243 : EVENT_POLL_ADD_TIMEOUT: timer=1 frequency=-1 cb=0x7f25f716aa30 opaque=0x1a31ca0 ff=(nil) >2012-10-06 16:41:37.486+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a31d80 tracked=1 >2012-10-06 16:41:37.486+0000: 23344: debug : virNetServerClientNewInternal:382 : RPC_SERVER_CLIENT_NEW: client=0x1a31ca0 sock=0x1a31a50 >2012-10-06 16:41:37.486+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a31d80 tx=(nil) >2012-10-06 16:41:37.486+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:37.486+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0 >2012-10-06 16:41:37.486+0000: 23344: debug : virNetServerClientRegisterEvent:199 : Registering client event callback 1 >2012-10-06 16:41:37.486+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31a50 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=12 fd=21 events=1 cb=0x7f25f71717c0 opaque=0x1a31a50 ff=0x7f25f7171850 >2012-10-06 16:41:37.486+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0 >2012-10-06 16:41:37.486+0000: 23344: debug : virKeepAliveNew:212 : client=0x1a31ca0, interval=5, count=5 >2012-10-06 16:41:37.486+0000: 23344: debug : virObjectNew:110 : OBJECT_NEW: obj=0x1a324c0 classname=virKeepAlive >2012-10-06 16:41:37.486+0000: 23344: debug : virKeepAliveNew:236 : RPC_KEEPALIVE_NEW: ka=0x1a324c0 client=0x1a31ca0 >2012-10-06 16:41:37.486+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0 >2012-10-06 16:41:37.486+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0 >2012-10-06 16:41:37.486+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31a50 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.487+0000: 23344: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more) >2012-10-06 16:41:37.487+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a31d80 tx=(nil) >2012-10-06 16:41:37.487+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.487+0000: 23344: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x1a31ca0 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0 >2012-10-06 16:41:37.487+0000: 23344: debug : virKeepAliveCheckMessage:384 : ka=0x1a324c0, client=0x1a31ca0, msg=0x1a31d80 >2012-10-06 16:41:37.487+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0 >2012-10-06 16:41:37.487+0000: 23344: debug : virNetServerDispatchNewMessage:218 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a31d80 >2012-10-06 16:41:37.487+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a23da0 >2012-10-06 16:41:37.487+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a274f0 tracked=1 >2012-10-06 16:41:37.487+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a274f0 tx=(nil) >2012-10-06 16:41:37.487+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.487+0000: 23350: debug : virNetServerHandleJob:189 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a31d80 prog=0x1a23da0 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.487+0000: 23350: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:37.487+0000: 23350: debug : remoteDispatchAuthListHelper:18 : server=0x1a0ff10 client=0x1a31ca0 msg=0x1a31d80 rerr=0x7f25e624bc70 args=0x7f25d8104b50 ret=0x7f25d80cdbd0 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:37.487+0000: 23350: info : remoteDispatchAuthList:2372 : Bypass polkit auth for privileged client pid:23504,uid:0 >2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23350: debug : virNetMessageEncodePayload:358 : Encode length as 36 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23350: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1a31d80 proc=66 len=36 offset=0 >2012-10-06 16:41:37.488+0000: 23350: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1a31ca0 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23350: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a274f0 tx=0x1a31d80 >2012-10-06 16:41:37.488+0000: 23350: debug : virNetServerClientCalculateHandleMode:183 : mode=3 >2012-10-06 16:41:37.488+0000: 23350: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:37.488+0000: 23350: debug : virEventPollInterruptLocked:701 : Interrupting >2012-10-06 16:41:37.488+0000: 23350: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a23da0 >2012-10-06 16:41:37.488+0000: 23350: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=5 d=0 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2 >2012-10-06 16:41:37.489+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a31d80 nfds=0 cb=(nil) >2012-10-06 16:41:37.489+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a274f0 tx=(nil) >2012-10-06 16:41:37.489+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.489+0000: 23344: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more) >2012-10-06 16:41:37.489+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a274f0 tx=(nil) >2012-10-06 16:41:37.489+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.490+0000: 23344: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x1a31ca0 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=1 >2012-10-06 16:41:37.490+0000: 23344: debug : virKeepAliveCheckMessage:384 : ka=0x1a324c0, client=0x1a31ca0, msg=0x1a274f0 >2012-10-06 16:41:37.490+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0 >2012-10-06 16:41:37.490+0000: 23344: debug : virNetServerDispatchNewMessage:218 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a274f0 >2012-10-06 16:41:37.490+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a23da0 >2012-10-06 16:41:37.490+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a26a70 tracked=1 >2012-10-06 16:41:37.490+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a26a70 tx=(nil) >2012-10-06 16:41:37.490+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:37.490+0000: 23346: debug : virNetServerHandleJob:189 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a274f0 prog=0x1a23da0 >2012-10-06 16:41:37.490+0000: 23346: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=1 >2012-10-06 16:41:37.490+0000: 23346: debug : remoteDispatchOpenHelper:11127 : server=0x1a0ff10 client=0x1a31ca0 msg=0x1a274f0 rerr=0x7f25e824fc70 args=0x7f25d0002480 ret=0x7f25d00023a0 >2012-10-06 16:41:37.490+0000: 23346: debug : remoteDispatchOpen:707 : priv=0x1a32130 conn=(nil) >2012-10-06 16:41:37.490+0000: 23346: debug : virConnectOpen:1331 : name= >2012-10-06 16:41:37.490+0000: 23346: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f25d00025f0 classname=virConnect >2012-10-06 16:41:37.491+0000: 23346: debug : virConnectGetConfigFile:953 : Loading config file '/etc/libvirt/libvirt.conf' >2012-10-06 16:41:37.491+0000: 23346: debug : virFileClose:72 : Closed fd 23 >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1131 : no name, allowing driver auto-select >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 0 (Test) ... >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 0 Test returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 1 (OPENVZ) ... >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 1 OPENVZ returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 2 (VMWARE) ... >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 2 VMWARE returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 3 (PHYP) ... >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 3 PHYP returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 4 (VBOX) ... >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 4 VBOX returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 5 (ESX) ... >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 5 ESX returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 6 (Hyper-V) ... >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 6 Hyper-V returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 7 (remote) ... >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 7 remote returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 8 (Xen) ... >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 8 Xen returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 9 (xenlight) ... >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 9 xenlight returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 10 (QEMU) ... >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 10 QEMU returned SUCCESS >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1202 : network driver 0 Test returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1202 : network driver 1 VBOX returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1202 : network driver 2 ESX returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1202 : network driver 3 Hyper-V returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1202 : network driver 4 remote returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1202 : network driver 5 Network returned SUCCESS >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1217 : interface driver 0 Test returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1217 : interface driver 1 PHYP returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1217 : interface driver 2 ESX returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1217 : interface driver 3 Hyper-V returned DECLINED >2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1217 : interface driver 4 remote returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1217 : interface driver 5 Interface returned SUCCESS >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 0 Test returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 1 PHYP returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 2 VBOX returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 3 ESX returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 4 Hyper-V returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 5 remote returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 6 storage returned SUCCESS >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1249 : node driver 0 Test returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1249 : node driver 1 ESX returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1249 : node driver 2 Hyper-V returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1249 : node driver 3 remote returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1249 : node driver 4 udevDeviceMonitor returned SUCCESS >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1265 : secret driver 0 Test returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1265 : secret driver 1 ESX returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1265 : secret driver 2 Hyper-V returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1265 : secret driver 3 remote returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1265 : secret driver 4 secret returned SUCCESS >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1281 : nwfilter driver 0 Test returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1281 : nwfilter driver 1 ESX returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1281 : nwfilter driver 2 Hyper-V returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1281 : nwfilter driver 3 remote returned DECLINED >2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1281 : nwfilter driver 4 nwfilter returned SUCCESS >2012-10-06 16:41:37.501+0000: 23346: debug : virNetMessageEncodePayload:358 : Encode length as 28 >2012-10-06 16:41:37.502+0000: 23346: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1a274f0 proc=1 len=28 offset=0 >2012-10-06 16:41:37.502+0000: 23346: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1a31ca0 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=1 >2012-10-06 16:41:37.502+0000: 23346: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a26a70 tx=0x1a274f0 >2012-10-06 16:41:37.502+0000: 23346: debug : virNetServerClientCalculateHandleMode:183 : mode=3 >2012-10-06 16:41:37.502+0000: 23346: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3 >2012-10-06 16:41:37.502+0000: 23346: debug : virEventPollInterruptLocked:701 : Interrupting >2012-10-06 16:41:37.502+0000: 23346: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a23da0 >2012-10-06 16:41:37.502+0000: 23346: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=5 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2 >2012-10-06 16:41:37.502+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a274f0 nfds=0 cb=(nil) >2012-10-06 16:41:37.502+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a26a70 tx=(nil) >2012-10-06 16:41:37.502+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.503+0000: 23344: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more) >2012-10-06 16:41:37.503+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a26a70 tx=(nil) >2012-10-06 16:41:37.503+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.503+0000: 23344: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x1a31ca0 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=2 >2012-10-06 16:41:37.503+0000: 23344: debug : virKeepAliveCheckMessage:384 : ka=0x1a324c0, client=0x1a31ca0, msg=0x1a26a70 >2012-10-06 16:41:37.503+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0 >2012-10-06 16:41:37.503+0000: 23344: debug : virNetServerDispatchNewMessage:218 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a26a70 >2012-10-06 16:41:37.503+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a23da0 >2012-10-06 16:41:37.503+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a32550 tracked=1 >2012-10-06 16:41:37.503+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32550 tx=(nil) >2012-10-06 16:41:37.503+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:37.503+0000: 23347: debug : virNetServerHandleJob:189 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a26a70 prog=0x1a23da0 >2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:37.504+0000: 23347: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=110 >2012-10-06 16:41:37.504+0000: 23347: debug : remoteDispatchGetURIHelper:7297 : server=0x1a0ff10 client=0x1a31ca0 msg=0x1a26a70 rerr=0x7f25e7a4ec70 args=0x7f25d40008c0 ret=0x7f25d40008e0 >2012-10-06 16:41:37.504+0000: 23347: debug : virConnectGetURI:1700 : conn=0x7f25d00025f0 >2012-10-06 16:41:37.504+0000: 23347: debug : virNetMessageEncodePayload:358 : Encode length as 48 >2012-10-06 16:41:37.504+0000: 23347: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1a26a70 proc=110 len=48 offset=0 >2012-10-06 16:41:37.504+0000: 23347: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1a31ca0 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=2 >2012-10-06 16:41:37.504+0000: 23347: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32550 tx=0x1a26a70 >2012-10-06 16:41:37.504+0000: 23347: debug : virNetServerClientCalculateHandleMode:183 : mode=3 >2012-10-06 16:41:37.504+0000: 23347: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3 >2012-10-06 16:41:37.504+0000: 23347: debug : virEventPollInterruptLocked:701 : Interrupting >2012-10-06 16:41:37.504+0000: 23347: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a23da0 >2012-10-06 16:41:37.504+0000: 23347: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=5 d=0 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2 >2012-10-06 16:41:37.504+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a26a70 nfds=0 cb=(nil) >2012-10-06 16:41:37.504+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32550 tx=(nil) >2012-10-06 16:41:37.504+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.505+0000: 23344: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more) >2012-10-06 16:41:37.505+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32550 tx=(nil) >2012-10-06 16:41:37.505+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.505+0000: 23344: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x1a31ca0 len=28 prog=536903814 vers=1 proc=7 type=0 status=0 serial=3 >2012-10-06 16:41:37.505+0000: 23344: debug : virKeepAliveCheckMessage:384 : ka=0x1a324c0, client=0x1a31ca0, msg=0x1a32550 >2012-10-06 16:41:37.505+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0 >2012-10-06 16:41:37.505+0000: 23344: debug : virNetServerDispatchNewMessage:218 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a32550 >2012-10-06 16:41:37.505+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a23da0 >2012-10-06 16:41:37.505+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a325c0 tracked=1 >2012-10-06 16:41:37.505+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a325c0 tx=(nil) >2012-10-06 16:41:37.505+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:37.506+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:37.506+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:37.506+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:37.506+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:37.506+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:37.506+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:37.506+0000: 23348: debug : virNetServerHandleJob:189 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a32550 prog=0x1a23da0 >2012-10-06 16:41:37.506+0000: 23348: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=7 >2012-10-06 16:41:37.506+0000: 23348: debug : remoteDispatchGetCapabilitiesHelper:6997 : server=0x1a0ff10 client=0x1a31ca0 msg=0x1a32550 rerr=0x7f25e724dc70 args=0x7f25c8000950 ret=0x7f25c80008c0 >2012-10-06 16:41:37.506+0000: 23348: debug : virConnectGetCapabilities:6418 : conn=0x7f25d00025f0 >2012-10-06 16:41:37.512+0000: 23348: debug : cpuNodeData:236 : arch=x86_64 >2012-10-06 16:41:37.512+0000: 23348: debug : cpuDecode:141 : cpu=0x7f25c8000b10, data=0x7f25c8000ca0, nmodels=0, preferred=(null) >2012-10-06 16:41:37.517+0000: 23348: debug : x86Decode:1362 : CPU vendor AMD of model Opteron_G4 differs from Intel; ignoring >2012-10-06 16:41:37.517+0000: 23348: debug : x86Decode:1362 : CPU vendor AMD of model Opteron_G3 differs from Intel; ignoring >2012-10-06 16:41:37.517+0000: 23348: debug : x86Decode:1362 : CPU vendor AMD of model Opteron_G2 differs from Intel; ignoring >2012-10-06 16:41:37.517+0000: 23348: debug : x86Decode:1362 : CPU vendor AMD of model Opteron_G1 differs from Intel; ignoring >2012-10-06 16:41:37.517+0000: 23348: debug : x86Decode:1362 : CPU vendor AMD of model phenom differs from Intel; ignoring >2012-10-06 16:41:37.517+0000: 23348: debug : x86Decode:1362 : CPU vendor AMD of model athlon differs from Intel; ignoring >2012-10-06 16:41:37.518+0000: 23348: debug : cpuDataFree:212 : arch=x86_64, data=0x7f25c8000ca0 >2012-10-06 16:41:37.518+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8107640 for /usr/bin/qemu-system-i386 >2012-10-06 16:41:37.518+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8107640 >2012-10-06 16:41:37.518+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8107160 for /usr/bin/qemu-kvm >2012-10-06 16:41:37.518+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8107160 >2012-10-06 16:41:37.518+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8107640 >2012-10-06 16:41:37.518+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8107160 >2012-10-06 16:41:37.519+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8019270 for /usr/bin/qemu-system-x86_64 >2012-10-06 16:41:37.519+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8019270 >2012-10-06 16:41:37.519+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8107160 for /usr/bin/qemu-kvm >2012-10-06 16:41:37.519+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8107160 >2012-10-06 16:41:37.519+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8019270 >2012-10-06 16:41:37.519+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8107160 >2012-10-06 16:41:37.519+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d80a10c0 for /usr/bin/qemu-system-arm >2012-10-06 16:41:37.519+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d80a10c0 >2012-10-06 16:41:37.519+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d80a10c0 >2012-10-06 16:41:37.519+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d80a1d60 for /usr/bin/qemu-system-mips >2012-10-06 16:41:37.519+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d80a1d60 >2012-10-06 16:41:37.519+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d80a1d60 >2012-10-06 16:41:37.520+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8105ab0 for /usr/bin/qemu-system-mipsel >2012-10-06 16:41:37.520+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8105ab0 >2012-10-06 16:41:37.520+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8105ab0 >2012-10-06 16:41:37.520+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8106840 for /usr/bin/qemu-system-sparc >2012-10-06 16:41:37.520+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8106840 >2012-10-06 16:41:37.520+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8106840 >2012-10-06 16:41:37.520+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8106f80 for /usr/bin/qemu-system-ppc >2012-10-06 16:41:37.521+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8106f80 >2012-10-06 16:41:37.521+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8106f80 >2012-10-06 16:41:37.521+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d80acdc0 for /usr/bin/qemu-system-ppc64 >2012-10-06 16:41:37.521+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d80acdc0 >2012-10-06 16:41:37.521+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d80acdc0 >2012-10-06 16:41:37.521+0000: 23348: debug : qemuCreateCapabilities:434 : Initialized caps for security driver "selinux" with DOI "0" >2012-10-06 16:41:37.521+0000: 23348: debug : qemuCreateCapabilities:434 : Initialized caps for security driver "dac" with DOI "0" >2012-10-06 16:41:37.521+0000: 23348: debug : virConnectGetCapabilities:6433 : conn=0x7f25d00025f0 ret=<capabilities> > > <host> > <uuid>401da052-c681-b601-f35c-00215e6fb9f4</uuid> > <cpu> > <arch>x86_64</arch> > <model>Penryn</model> > <vendor>Intel</vendor> > <topology sockets='1' cores='4' threads='1'/> > <feature name='osxsave'/> > <feature name='xsave'/> > <feature name='dca'/> > <feature name='pdcm'/> > <feature name='xtpr'/> > <feature name='tm2'/> > <feature name='vmx'/> > <feature name='ds_cpl'/> > <feature name='monitor'/> > <feature name='dtes64'/> > <feature name='pbe'/> > <feature name='tm'/> > <feature name='ht'/> > <feature name='ss'/> > <feature name='acpi'/> > <feature name='ds'/> > <feature name='vme'/> > </cpu> > <power_management> > <suspend_disk/> > <suspend_hybrid/> > </power_management> > <migration_features> > <live/> > <uri_transports> > <uri_transport>tcp</uri_transport> > </uri_transports> > </migration_features> > <topology> > <cells num='1'> > <cell id='0'> > <cpus num='4'> > <cpu id='0'/> > <cpu id='1'/> > <cpu id='2'/> > <cpu id='3'/> > </cpus> > </cell> > </cells> > </topology> > <secmodel> > <model>selinux</model> > <doi>0</doi> > </secmodel> > <secmodel> > <model>dac</model> > <doi>0</doi> > </secmodel> > </host> > > <guest> > <os_type>hvm</os_type> > <arch name='i686'> > <wordsize>32</wordsize> > <emulator>/usr/bin/qemu-system-i386</emulator> > <machine>isapc</machine> > <machine>none</machine> > <machine canonical='pc-1.2'>pc</machine> > <machine>pc-1.2</machine> > <machine>pc-1.1</machine> > <machine>pc-1.0</machine> > <machine>pc-0.15</machine> > <machine>pc-0.14</machine> > <machine>pc-0.13</machine> > <machine>pc-0.12</machine> > <machine>pc-0.11</machine> > <machine>pc-0.10</machine> > <domain type='qemu'> > </domain> > <domain type='kvm'> > <emulator>/usr/bin/qemu-kvm</emulator> > <machine>isapc</machine> > <machine>none</machine> > <machine canonical='pc-1.2'>pc</machine> > <machine>pc-1.2</machine> > <machine>pc-1.1</machine> > <machine>pc-1.0</machine> > <machine>pc-0.15</machine> > <machine>pc-0.14</machine> > <machine>pc-0.13</machine> > <machine>pc-0.12</machine> > <machine>pc-0.11</machine> > <machine>pc-0.10</machine> > </domain> > </arch> > <features> > <deviceboot/> > <acpi default='on' toggle='yes'/> > <apic default='on' toggle='no'/> > <pae/> > <nonpae/> > </features> > </guest> > > <guest> > <os_type>hvm</os_type> > <arch name='x86_64'> > <wordsize>64</wordsize> > <emulator>/usr/bin/qemu-system-x86_64</emulator> > <machine>isapc</machine> > <machine>none</machine> > <machine canonical='pc-1.2'>pc</machine> > <machine>pc-1.2</machine> > <machine>pc-1.1</machine> > <machine>pc-1.0</machine> > <machine>pc-0.15</machine> > <machine>pc-0.14</machine> > <machine>pc-0.13</machine> > <machine>pc-0.12</machine> > <machine>pc-0.11</machine> > <machine>pc-0.10</machine> > <domain type='qemu'> > </domain> > <domain type='kvm'> > <emulator>/usr/bin/qemu-kvm</emulator> > <machine>isapc</machine> > <machine>none</machine> > <machine canonical='pc-1.2'>pc</machine> > <machine>pc-1.2</machine> > <machine>pc-1.1</machine> > <machine>pc-1.0</machine> > <machine>pc-0.15</machine> > <machine>pc-0.14</machine> > <machine>pc-0.13</machine> > <machine>pc-0.12</machine> > <machine>pc-0.11</machine> > <machine>pc-0.10</machine> > </domain> > </arch> > <features> > <cpuselection/> > <deviceboot/> > <acpi default='on' toggle='yes'/> > <apic default='on' toggle='no'/> > </features> > </guest> > > <guest> > <os_type>hvm</os_type> > <arch name='arm'> > <wordsize>64</wordsize> > <emulator>/usr/bin/qemu-system-arm</emulator> > <machine>z2</machine> > <machine>none</machine> > <machine>collie</machine> > <machine>nuri</machine> > <machine>smdkc210</machine> > <machine>connex</machine> > <machine>verdex</machine> > <machine>highbank</machine> > <machine>integratorcp</machine> > <machine>kzm</machine> > <machine>mainstone</machine> > <machine>musicpal</machine> > <machine>n800</machine> > <machine>n810</machine> > <machine>sx1</machine> > <machine>sx1-v1</machine> > <machine>cheetah</machine> > <machine>realview-eb</machine> > <machine>realview-eb-mpcore</machine> > <machine>realview-pb-a8</machine> > <machine>realview-pbx-a9</machine> > <machine>akita</machine> > <machine>spitz</machine> > <machine>borzoi</machine> > <machine>terrier</machine> > <machine>lm3s811evb</machine> > <machine>lm3s6965evb</machine> > <machine>tosa</machine> > <machine>versatilepb</machine> > <machine>versatileab</machine> > <machine>vexpress-a9</machine> > <machine>vexpress-a15</machine> > <machine>xilinx-zynq-a9</machine> > <domain type='qemu'> > </domain> > <domain type='kvm'> > <emulator>/usr/bin/qemu-system-arm</emulator> > </domain> > </arch> > <features> > <deviceboot/> > </features> > </guest> > > <guest> > <os_type>hvm</os_type> > <arch name='mips'> > <wordsize>32</wordsize> > <emulator>/usr/bin/qemu-system-mips</emulator> > <machine>mips</machine> > <machine>none</machine> > <machine>magnum</machine> > <machine>pica61</machine> > <machine>malta</machine> > <machine>mipssim</machine> > <domain type='qemu'> > </domain> > <domain type='kvm'> > <emulator>/usr/bin/qemu-system-mips</emulator> > </domain> > </arch> > <features> > <deviceboot/> > </features> > </guest> > > <guest> > <os_type>hvm</os_type> > <arch name='mipsel'> > <wordsize>32</wordsize> > <emulator>/usr/bin/qemu-system-mipsel</emulator> > <machine>mips</machine> > <machine>none</machine> > <machine>magnum</machine> > <machine>pica61</machine> > <machine>malta</machine> > <machine>mipssim</machine> > <domain type='qemu'> > </domain> > <domain type='kvm'> > <emulator>/usr/bin/qemu-system-mipsel</emulator> > </domain> > </arch> > <features> > <deviceboot/> > </features> > </guest> > > <guest> > <os_type>hvm</os_type> > <arch name='sparc'> > <wordsize>32</wordsize> > <emulator>/usr/bin/qemu-system-sparc</emulator> > <machine>SS-2</machine> > <machine>none</machine> > <machine>leon3_generic</machine> > <machine>SS-5</machine> > <machine>SS-10</machine> > <machine>SS-600MP</machine> > <machine>SS-20</machine> > <machine>Voyager</machine> > <machine>LX</machine> > <machine>SS-4</machine> > <machine>SPARCClassic</machine> > <machine>SPARCbook</machine> > <machine>SS-1000</machine> > <machine>SS-2000</machine> > <domain type='qemu'> > </domain> > <domain type='kvm'> > <emulator>/usr/bin/qemu-system-sparc</emulator> > </domain> > </arch> > </guest> > > <guest> > <os_type>hvm</os_type> > <arch name='ppc'> > <wordsize>32</wordsize> > <emulator>/usr/bin/qemu-system-ppc</emulator> > <machine>mpc8544ds</machine> > <machine>none</machine> > <machine>ref405ep</machine> > <machine>taihu</machine> > <machine>bamboo</machine> > <machine>mac99</machine> > <machine>g3beige</machine> > <machine>prep</machine> > <machine>virtex-ml507</machine> > <machine>ppce500</machine> > <domain type='qemu'> > </domain> > <domain type='kvm'> > <emulator>/usr/bin/qemu-system-ppc</emulator> > </domain> > </arch> > <features> > <deviceboot/> > </features> > </guest> > > <guest> > <os_type>hvm</os_type> > <arch name='ppc64'> > <wordsize>64</wordsize> > <emulator>/usr/bin/qemu-system-ppc64</emulator> > <machine>mpc8544ds</machine> > <machine>none</machine> > <machine>ref405ep</machine> > <machine>taihu</machine> > <machine>bamboo</machine> > <machine>mac99</machine> > <machine>g3beige</machine> > <machine>prep</machine> > <machine>pseries</machine> > <machine>virtex-ml507</machine> > <machine>ppce500</machine> > <domain type='qemu'> > </domain> > <domain type='kvm'> > <emulator>/usr/bin/qemu-system-ppc64</emulator> > </domain> > </arch> > <features> > <cpuselection/> > <deviceboot/> > </features> > </guest> > ></capabilities> > >2012-10-06 16:41:37.521+0000: 23348: debug : virNetMessageEncodePayload:358 : Encode length as 8776 >2012-10-06 16:41:37.521+0000: 23348: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1a32550 proc=7 len=8776 offset=0 >2012-10-06 16:41:37.521+0000: 23348: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1a31ca0 len=8776 prog=536903814 vers=1 proc=7 type=1 status=0 serial=3 >2012-10-06 16:41:37.521+0000: 23348: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a325c0 tx=0x1a32550 >2012-10-06 16:41:37.521+0000: 23348: debug : virNetServerClientCalculateHandleMode:183 : mode=3 >2012-10-06 16:41:37.521+0000: 23348: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3 >2012-10-06 16:41:37.521+0000: 23348: debug : virEventPollInterruptLocked:701 : Interrupting >2012-10-06 16:41:37.522+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a23da0 >2012-10-06 16:41:37.522+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=5 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2 >2012-10-06 16:41:37.522+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a32550 nfds=0 cb=(nil) >2012-10-06 16:41:37.522+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a325c0 tx=(nil) >2012-10-06 16:41:37.522+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1 >2012-10-06 16:41:38.239+0000: 23344: debug : virNetMessageDecodeLength:152 : Got length, now need 1832 total (1828 more) >2012-10-06 16:41:38.239+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a325c0 tx=(nil) >2012-10-06 16:41:38.239+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:38.239+0000: 23344: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x1a31ca0 len=1832 prog=536903814 vers=1 proc=10 type=0 status=0 serial=4 >2012-10-06 16:41:38.239+0000: 23344: debug : virKeepAliveCheckMessage:384 : ka=0x1a324c0, client=0x1a31ca0, msg=0x1a325c0 >2012-10-06 16:41:38.239+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0 >2012-10-06 16:41:38.239+0000: 23344: debug : virNetServerDispatchNewMessage:218 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a325c0 >2012-10-06 16:41:38.239+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a23da0 >2012-10-06 16:41:38.239+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a32450 tracked=1 >2012-10-06 16:41:38.239+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32450 tx=(nil) >2012-10-06 16:41:38.239+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:38.239+0000: 23349: debug : virNetServerHandleJob:189 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a325c0 prog=0x1a23da0 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:38.239+0000: 23349: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=10 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:38.239+0000: 23349: debug : remoteDispatchDomainCreateXMLHelper:1151 : server=0x1a0ff10 client=0x1a31ca0 msg=0x1a325c0 rerr=0x7f25e6a4cc70 args=0x7f25cc000950 ret=0x7f25cc0008c0 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:38.239+0000: 23349: debug : virDomainCreateXML:1937 : conn=0x7f25d00025f0, xmlDesc=<?xml version="1.0"?> ><domain type="kvm" xmlns:qemu="http://libvirt.org/schemas/domain/qemu/1.0"> > <name>guestfs-b95l0l3zykx0219j</name> > <memory unit="MiB">500</memory> > <currentMemory unit="MiB">500</currentMemory> > <cpu model="host-model"/> > <vcpu>1</vcpu> > <clock offset="utc"/> > <os> > <type>hvm</type> > <kernel>/var/tmp/.guestfs-0/kernel.23504</kernel> > <initrd>/var/tmp/.guestfs-0/initrd.23504</initrd> > <cmdline>panic=1 console=ttyS0 udevtimeout=600 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 TERM=screen </cmdline> > </os> > <on_reboot>destroy</on_reboot> > <devices> > <controller type="scsi" index="0" model="virtio-scsi"/> > <disk device="disk" type="file"> > <source file="/export/vmimgs2/f17-base.qcow2"/> > <target dev="sda" bus="scsi"/> > <driver name="qemu" type="qcow2"/> > <address type="drive" controller="0" bus="0" target="0" unit="0"/> > </disk> > <disk type="file" device="disk"> > <source file="/var/tmp/.guestfs-0/root.23504"/> > <target dev="sdb" bus="scsi"/> > <driver name="qemu" type="raw" cache="unsafe"/> > <address type="drive" controller="0" bus="0" target="1" unit="0"/> > <shareable/> > </disk> > <serial type="unix"> > <source mode="connect" path="/tmp/libguestfsBKM1WI/console.sock"/> > <target port="0"/> > </serial> > <channel type="unix"> > <source mode="connect" path="/tmp/libguestfsBKM1WI/guestfsd.sock"/> > <target type="virtio" name="org.libguestfs.channel.0"/> > </channel> > </devices> > <qemu:commandline> > <qemu:arg value="-set"/> > <qemu:arg value="drive.drive-scsi0-0-0-0.snapshot=on"/> > <qemu:arg value="-set"/> > <qemu:arg value="drive.drive-scsi0-0-1-0.snapshot=on"/> > </qemu:commandline> ></domain> >, flags=2 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:38.240+0000: 23349: debug : virFileClose:72 : Closed fd 25 >2012-10-06 16:41:38.240+0000: 23349: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8107160 for /usr/bin/qemu-kvm >2012-10-06 16:41:38.240+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8107160 >2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.0 >2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.1 >2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.2 >2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.3 >2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.4 >2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.5 >2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.6 >2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.7 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.0 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.1 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.2 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.3 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.4 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.5 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.6 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.7 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.0 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.1 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.2 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.3 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.4 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.5 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.6 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.7 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressGetNextSlot:1322 : PCI addr 0:0:2.0 already in use >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressGetNextSlot:1327 : Found free PCI addr 0:0:3.0 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.0 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.1 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.2 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.3 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.4 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.5 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.6 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.7 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressGetNextSlot:1327 : Found free PCI addr 0:0:4.0 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.0 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.1 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.2 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.3 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.4 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.5 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.6 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.7 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressGetNextSlot:1327 : Found free PCI addr 0:0:5.0 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.0 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.1 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.2 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.3 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.4 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.5 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.6 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.7 >2012-10-06 16:41:38.241+0000: 23349: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f25cc0018a0 classname=virDomainObj >2012-10-06 16:41:38.241+0000: 23349: debug : virDomainObjNew:1782 : obj=0x7f25cc0018a0 >2012-10-06 16:41:38.241+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc0018a0 >2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainObjBeginJobInternal:807 : Starting job: modify (async=none) >2012-10-06 16:41:38.241+0000: 23349: debug : qemuProcessStart:3346 : Beginning VM startup process >2012-10-06 16:41:38.241+0000: 23349: debug : qemuProcessStart:3358 : Setting current domain def as transient >2012-10-06 16:41:38.241+0000: 23349: debug : qemuProcessStart:3384 : Preparing host devices >2012-10-06 16:41:38.241+0000: 23349: debug : qemuProcessStart:3388 : Preparing chr devices >2012-10-06 16:41:38.241+0000: 23349: debug : qemuProcessStart:3397 : Generating domain security label (if required) >2012-10-06 16:41:38.241+0000: 23349: debug : virSecuritySELinuxGenSecurityLabel:483 : label=QEMU >2012-10-06 16:41:38.241+0000: 23349: debug : virSecuritySELinuxGenSecurityLabel:505 : type=2 >2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxMCSFind:202 : Using sensitivity level 's0' cat min 0 max 1023 range 1024 >2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxMCSFind:207 : Try cat s0:c340,c535 >2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxMCSFind:233 : Found context 's0:c340,c535' >2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenNewContext:253 : basecontext=system_u:system_r:svirt_t:s0 mcs=s0:c340,c535 isObjectContext=0 >2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenNewContext:266 : process=system_u:system_r:virtd_t:s0-s0:c0.c1023 >2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenNewContext:307 : Generated context 'system_u:system_r:svirt_t:s0:c340,c535' >2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenNewContext:253 : basecontext=system_u:object_r:svirt_image_t:s0 mcs=s0:c340,c535 isObjectContext=1 >2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenNewContext:266 : process=system_u:system_r:virtd_t:s0-s0:c0.c1023 >2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenNewContext:307 : Generated context 'system_u:object_r:svirt_image_t:s0:c340,c535' >2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenSecurityLabel:591 : model=selinux label=system_u:system_r:svirt_t:s0:c340,c535 imagelabel=system_u:object_r:svirt_image_t:s0:c340,c535 baselabel=(null) >2012-10-06 16:41:38.245+0000: 23349: debug : qemuProcessStart:3406 : Ensuring no historical cgroup is lying around >2012-10-06 16:41:38.245+0000: 23349: debug : virCgroupNew:617 : New group /libvirt/qemu/guestfs-b95l0l3zykx0219j >2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /system/libvirtd.service >2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /system/libvirtd.service >2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in >2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in >2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in >2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in >2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in >2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupMakeGroup:535 : Make group /libvirt/qemu/guestfs-b95l0l3zykx0219j >2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.247+0000: 23349: debug : qemuProcessStart:3479 : Creating domain log file >2012-10-06 16:41:38.247+0000: 23349: debug : qemuProcessStart:3484 : Checking for KVM availability >2012-10-06 16:41:38.247+0000: 23349: debug : qemuProcessStart:3494 : Determining emulator version >2012-10-06 16:41:38.247+0000: 23349: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8107160 for /usr/bin/qemu-kvm >2012-10-06 16:41:38.247+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8107160 >2012-10-06 16:41:38.247+0000: 23349: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f25cc0042c0 classname=qemuCaps >2012-10-06 16:41:38.247+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8107160 >2012-10-06 16:41:38.247+0000: 23349: debug : qemuProcessStart:3503 : Checking for CDROM and floppy presence >2012-10-06 16:41:38.247+0000: 23349: debug : qemuProcessStart:3527 : Setting up domain cgroup (if required) >2012-10-06 16:41:38.247+0000: 23349: debug : virCgroupNew:617 : New group /libvirt/qemu/guestfs-b95l0l3zykx0219j >2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /system/libvirtd.service >2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /system/libvirtd.service >2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in >2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in >2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in >2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in >2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in >2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupMakeGroup:535 : Make group /libvirt/qemu/guestfs-b95l0l3zykx0219j >2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.250+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.250+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpuset/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.251+0000: 23349: debug : virCgroupCpuSetInherit:469 : Setting up inheritance /libvirt/qemu -> /libvirt/qemu/guestfs-b95l0l3zykx0219j >2012-10-06 16:41:38.251+0000: 23349: debug : virCgroupGetValueStr:361 : Get value /sys/fs/cgroup/cpuset/libvirt/qemu/cpuset.cpus >2012-10-06 16:41:38.251+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.251+0000: 23349: debug : virCgroupCpuSetInherit:482 : Inherit cpuset.cpus = 0-3 >2012-10-06 16:41:38.251+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/cpuset/libvirt/qemu/guestfs-b95l0l3zykx0219j/cpuset.cpus' to '0-3' >2012-10-06 16:41:38.251+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.251+0000: 23349: debug : virCgroupGetValueStr:361 : Get value /sys/fs/cgroup/cpuset/libvirt/qemu/cpuset.mems >2012-10-06 16:41:38.252+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.252+0000: 23349: debug : virCgroupCpuSetInherit:482 : Inherit cpuset.mems = 0 >2012-10-06 16:41:38.252+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/cpuset/libvirt/qemu/guestfs-b95l0l3zykx0219j/cpuset.mems' to '0' >2012-10-06 16:41:38.252+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.252+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.253+0000: 23349: debug : virCgroupGetValueStr:361 : Get value /sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/memory.use_hierarchy >2012-10-06 16:41:38.253+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.254+0000: 23349: debug : virCgroupSetMemoryUseHierarchy:517 : Setting up /libvirt/qemu/guestfs-b95l0l3zykx0219j/memory.use_hierarchy >2012-10-06 16:41:38.254+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/memory.use_hierarchy' to '1' >2012-10-06 16:41:38.254+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.254+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.254+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/freezer/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.255+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/blkio/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.256+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.deny' to 'a' >2012-10-06 16:41:38.256+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.256+0000: 23349: debug : qemuSetupDiskPathAllow:70 : Process path /export/vmimgs2/f17-base.qcow2 for disk >2012-10-06 16:41:38.257+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.257+0000: 23349: debug : qemuSetupDiskPathAllow:70 : Process path /var/tmp/.guestfs-0/root.23504 for disk >2012-10-06 16:41:38.258+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.258+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 136:* rw' >2012-10-06 16:41:38.258+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.258+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 1:3 rw' >2012-10-06 16:41:38.258+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.258+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 1:7 rw' >2012-10-06 16:41:38.259+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.259+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 1:5 rw' >2012-10-06 16:41:38.259+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.259+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 1:8 rw' >2012-10-06 16:41:38.259+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.260+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 1:9 rw' >2012-10-06 16:41:38.260+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.260+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 5:2 rw' >2012-10-06 16:41:38.260+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.260+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 10:232 rw' >2012-10-06 16:41:38.260+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.261+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 254:0 rw' >2012-10-06 16:41:38.262+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.262+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 10:228 rw' >2012-10-06 16:41:38.262+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.262+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/memory.limit_in_bytes' to '744488960' >2012-10-06 16:41:38.262+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.262+0000: 23349: debug : qemuProcessStart:3536 : Preparing monitor state >2012-10-06 16:41:38.263+0000: 23349: debug : qemuProcessStart:3572 : Assigning domain PCI addresses >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:993 : Remembering PCI addr 0:0:3.0 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.1 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.2 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.3 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.4 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.5 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.6 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.7 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:993 : Remembering PCI addr 0:0:1.2 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:993 : Remembering PCI addr 0:0:4.0 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.1 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.2 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.3 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.4 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.5 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.6 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.7 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:993 : Remembering PCI addr 0:0:5.0 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.1 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.2 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.3 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.4 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.5 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.6 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.7 (multifunction=off for function 0) >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.0 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.1 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.2 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.3 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.4 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.5 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.6 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.7 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.0 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.1 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.3 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.4 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.5 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.6 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.7 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.0 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.1 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.2 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.3 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.4 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.5 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.6 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.7 >2012-10-06 16:41:38.263+0000: 23349: debug : qemuProcessStart:3577 : Building emulator command line >2012-10-06 16:41:38.263+0000: 23349: debug : qemuBuildCommandLine:4403 : conn=0x7f25d00025f0 driver=0x7f25d808f590 def=0x7f25cc00abf0 mon=0x7f25cc00baf0 json=1 caps=0x7f25cc0042c0 migrateFrom=(null) migrateFD=-1 snapshot=(nil) vmop=0 >2012-10-06 16:41:38.263+0000: 23349: debug : cpuDataFree:212 : arch=x86_64, data=(nil) >2012-10-06 16:41:38.267+0000: 23349: warning : qemuDomainObjTaint:1364 : Domain id=8 name='guestfs-b95l0l3zykx0219j' uuid=b1d5e6b8-94d7-67df-b5b8-2a67e0c39d13 is tainted: custom-argv >2012-10-06 16:41:38.267+0000: 23349: debug : qemuProcessStart:3622 : Clear emulator capabilities: 1 >2012-10-06 16:41:38.267+0000: 23349: debug : virCommandRequireHandshake:2362 : Transfer handshake wait=27 notify=28, keep handshake wait=26 notify=29 >2012-10-06 16:41:38.268+0000: 23349: debug : virCommandRunAsync:2200 : About to run LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin /usr/bin/qemu-kvm -name guestfs-b95l0l3zykx0219j -S -M isapc -enable-kvm -m 500 -smp 1,sockets=1,cores=1,threads=1 -uuid b1d5e6b8-94d7-67df-b5b8-2a67e0c39d13 -nographic -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/guestfs-b95l0l3zykx0219j.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=utc -no-reboot -no-shutdown -no-acpi -kernel /var/tmp/.guestfs-0/kernel.23504 -initrd /var/tmp/.guestfs-0/initrd.23504 -append 'panic=1 console=ttyS0 udevtimeout=600 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 TERM=screen ' -device virtio-scsi-pci,id=scsi0,bus=pci.0,addr=0x3 -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x4 -drive file=/export/vmimgs2/f17-base.qcow2,if=none,id=drive-scsi0-0-0-0,format=qcow2 -device scsi-hd,bus=scsi0.0,channel=0,scsi-id=0,lun=0,drive=drive-scsi0-0-0-0,id=scsi0-0-0-0,bootindex=1 -drive file=/var/tmp/.guestfs-0/root.23504,if=none,id=drive-scsi0-0-1-0,format=raw,cache=unsafe -device scsi-hd,bus=scsi0.0,channel=0,scsi-id=1,lun=0,drive=drive-scsi0-0-1-0,id=scsi0-0-1-0 -chardev socket,id=charserial0,path=/tmp/libguestfsBKM1WI/console.sock -device isa-serial,chardev=charserial0,id=serial0 -chardev socket,id=charchannel0,path=/tmp/libguestfsBKM1WI/guestfsd.sock -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=org.libguestfs.channel.0 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x5 -set drive.drive-scsi0-0-0-0.snapshot=on -set drive.drive-scsi0-0-1-0.snapshot=on >2012-10-06 16:41:38.279+0000: 23349: debug : virFileClose:72 : Closed fd 30 >2012-10-06 16:41:38.279+0000: 23349: debug : virCommandRunAsync:2218 : Command result 0, with PID 23512 >2012-10-06 16:41:38.279+0000: 23349: debug : virFileClose:72 : Closed fd 27 >2012-10-06 16:41:38.279+0000: 23349: debug : virFileClose:72 : Closed fd 28 >2012-10-06 16:41:38.303+0000: 23349: debug : virCommandRun:2016 : Result status 0, stdout: '(null)' stderr: '(null)' >2012-10-06 16:41:38.303+0000: 23349: debug : virFileClose:72 : Closed fd 27 >2012-10-06 16:41:38.303+0000: 23349: debug : qemuProcessStart:3669 : Writing early domain status to disk >2012-10-06 16:41:38.303+0000: 23349: debug : virFileClose:72 : Closed fd 27 >2012-10-06 16:41:38.303+0000: 23349: debug : qemuProcessStart:3674 : Waiting for handshake from child >2012-10-06 16:41:38.303+0000: 23349: debug : virCommandHandshakeWait:2395 : Wait for handshake on 26 >2012-10-06 16:41:38.362+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.362+0000: 23349: debug : qemuProcessStart:3679 : Setting domain security labels >2012-10-06 16:41:38.362+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/export/vmimgs2/f17-base.qcow2' to 'system_u:object_r:svirt_image_t:s0:c340,c535' >2012-10-06 16:41:38.364+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.364+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/var/tmp/.guestfs-0/root.23504' to 'system_u:object_r:svirt_image_t:s0' >2012-10-06 16:41:38.364+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.365+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/tmp/libguestfsBKM1WI/console.sock' to 'system_u:object_r:svirt_image_t:s0:c340,c535' >2012-10-06 16:41:38.366+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/tmp/libguestfsBKM1WI/guestfsd.sock' to 'system_u:object_r:svirt_image_t:s0:c340,c535' >2012-10-06 16:41:38.366+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/var/tmp/.guestfs-0/kernel.23504' to 'system_u:object_r:virt_content_t:s0' >2012-10-06 16:41:38.367+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/var/tmp/.guestfs-0/initrd.23504' to 'system_u:object_r:virt_content_t:s0' >2012-10-06 16:41:38.367+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success >2012-10-06 16:41:38.367+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success >2012-10-06 16:41:38.368+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success >2012-10-06 16:41:38.368+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success >2012-10-06 16:41:38.368+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/export/vmimgs2/f17-base.qcow2' to '107:107' >2012-10-06 16:41:38.368+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.368+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success >2012-10-06 16:41:38.368+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success >2012-10-06 16:41:38.368+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success >2012-10-06 16:41:38.369+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success >2012-10-06 16:41:38.369+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/var/tmp/.guestfs-0/root.23504' to '107:107' >2012-10-06 16:41:38.369+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.369+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success >2012-10-06 16:41:38.370+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success >2012-10-06 16:41:38.370+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success >2012-10-06 16:41:38.370+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success >2012-10-06 16:41:38.370+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success >2012-10-06 16:41:38.370+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success >2012-10-06 16:41:38.370+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/var/tmp/.guestfs-0/kernel.23504' to '107:107' >2012-10-06 16:41:38.370+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/var/tmp/.guestfs-0/initrd.23504' to '107:107' >2012-10-06 16:41:38.370+0000: 23349: debug : qemuProcessStart:3708 : Labelling done, completing handshake to child >2012-10-06 16:41:38.370+0000: 23349: debug : virCommandHandshakeNotify:2462 : Notify handshake on 29 >2012-10-06 16:41:38.371+0000: 23349: debug : virFileClose:72 : Closed fd 29 >2012-10-06 16:41:38.371+0000: 23349: debug : qemuProcessStart:3712 : Handshake complete, child running >2012-10-06 16:41:38.371+0000: 23349: debug : qemuProcessStart:3723 : Waiting for monitor to show up >2012-10-06 16:41:38.371+0000: 23349: debug : qemuProcessWaitForMonitor:1603 : Connect monitor to 0x7f25cc0018a0 'guestfs-b95l0l3zykx0219j' >2012-10-06 16:41:38.371+0000: 23349: debug : virSecuritySELinuxSetSecurityDaemonSocketLabel:1639 : Setting VM guestfs-b95l0l3zykx0219j socket context system_u:system_r:virtd_t:s0:c340,c535 >2012-10-06 16:41:38.372+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc0018a0 >2012-10-06 16:41:38.576+0000: 23349: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f25cc003fb0 classname=qemuMonitor >2012-10-06 16:41:38.576+0000: 23349: debug : virEventPollInterruptLocked:701 : Interrupting >2012-10-06 16:41:38.576+0000: 23349: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=13 fd=27 events=13 cb=0x7f25df548030 opaque=0x7f25cc003fb0 ff=0x40a5a0 >2012-10-06 16:41:38.576+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc003fb0 >2012-10-06 16:41:38.576+0000: 23349: debug : qemuMonitorOpenInternal:765 : QEMU_MONITOR_NEW: mon=0x7f25cc003fb0 refs=2 fd=27 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:38.576+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc003fb0 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:38.576+0000: 23349: debug : qemuMonitorSetCapabilities:1142 : mon=0x7f25cc003fb0 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:38.576+0000: 23349: debug : virJSONValueToString:1068 : object=0x7f25cc002e20 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:38.576+0000: 23349: debug : virJSONValueToStringOne:999 : object=0x7f25cc002e20 type=0 gen=0x7f25cc0056a0 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13 >2012-10-06 16:41:38.576+0000: 23349: debug : virJSONValueToStringOne:999 : object=0x7f25cc003c90 type=2 gen=0x7f25cc0056a0 >2012-10-06 16:41:38.576+0000: 23349: debug : virJSONValueToStringOne:999 : object=0x7f25cc004330 type=2 gen=0x7f25cc0056a0 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:38.576+0000: 23349: debug : virJSONValueToString:1102 : result={"execute":"qmp_capabilities","id":"libvirt-1"} >2012-10-06 16:41:38.576+0000: 23349: debug : qemuMonitorJSONCommandWithFd:257 : Send command '{"execute":"qmp_capabilities","id":"libvirt-1"}' for write with FD -1 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:38.576+0000: 23349: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=13 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=27 e=25 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=-1 >2012-10-06 16:41:38.577+0000: 23349: debug : virEventPollInterruptLocked:701 : Interrupting >2012-10-06 16:41:38.577+0000: 23349: debug : qemuMonitorSend:904 : QEMU_MONITOR_SEND_MSG: mon=0x7f25cc003fb0 msg={"execute":"qmp_capabilities","id":"libvirt-1"} > fd=-1 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 12 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=12 w=13 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=27 e=25 d=0 >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=-1 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 12 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=12 w=13 >2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=13 >2012-10-06 16:41:38.672+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc003fb0 >2012-10-06 16:41:38.672+0000: 23344: error : qemuMonitorIORead:514 : Unable to read from monitor: Connection reset by peer >2012-10-06 16:41:38.672+0000: 23344: debug : qemuMonitorIO:647 : Error on monitor Unable to read from monitor: Connection reset by peer >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=12 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:38.673+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc003fb0 >2012-10-06 16:41:38.673+0000: 23344: debug : qemuMonitorIO:681 : Triggering error callback >2012-10-06 16:41:38.673+0000: 23349: debug : qemuMonitorSend:916 : Send command resulted in error Unable to read from monitor: Connection reset by peer >2012-10-06 16:41:38.673+0000: 23344: debug : qemuProcessHandleMonitorError:342 : Received error on 0x7f25cc0018a0 'guestfs-b95l0l3zykx0219j' >2012-10-06 16:41:38.673+0000: 23349: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=12 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:38.673+0000: 23349: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 -284886976 >2012-10-06 16:41:38.673+0000: 23349: debug : qemuMonitorJSONCommandWithFd:262 : Receive command reply ret=-1 rxObject=(nil) >2012-10-06 16:41:38.673+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc003fb0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:38.673+0000: 23349: debug : virFileClose:72 : Closed fd 26 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=27 e=24 d=0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=-1 >2012-10-06 16:41:38.673+0000: 23349: debug : virFileClose:72 : Closed fd 25 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:38.673+0000: 23349: debug : qemuProcessStop:3918 : Shutting down VM 'guestfs-b95l0l3zykx0219j' pid=23513 flags=0 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 12 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=12 w=13 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=8 >2012-10-06 16:41:38.673+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc003fb0 >2012-10-06 16:41:38.673+0000: 23344: debug : qemuMonitorIO:647 : Error on monitor Unable to read from monitor: Connection reset by peer >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=12 >2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:38.673+0000: 23349: debug : virFileClose:72 : Closed fd 25 >2012-10-06 16:41:38.673+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc003fb0 >2012-10-06 16:41:38.674+0000: 23344: debug : qemuMonitorIO:670 : Triggering EOF callback >2012-10-06 16:41:38.674+0000: 23344: debug : qemuProcessHandleMonitorEOF:283 : Received EOF on 0x7f25cc0018a0 'guestfs-b95l0l3zykx0219j' >2012-10-06 16:41:38.673+0000: 23349: debug : qemuMonitorClose:838 : QEMU_MONITOR_CLOSE: mon=0x7f25cc003fb0 refs=2 >2012-10-06 16:41:38.674+0000: 23349: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=13 >2012-10-06 16:41:38.674+0000: 23349: debug : virEventPollRemoveHandle:188 : mark delete 12 27 >2012-10-06 16:41:38.674+0000: 23349: debug : virEventPollInterruptLocked:701 : Interrupting >2012-10-06 16:41:38.674+0000: 23349: debug : virFileClose:72 : Closed fd 27 >2012-10-06 16:41:38.674+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc003fb0 >2012-10-06 16:41:38.674+0000: 23349: debug : qemuProcessKill:3867 : vm=guestfs-b95l0l3zykx0219j pid=23513 flags=5 >2012-10-06 16:41:38.674+0000: 23349: debug : virProcessKillPainfully:253 : vpid=23513 force=1 >2012-10-06 16:41:38.874+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc0018a0 >2012-10-06 16:41:38.874+0000: 23344: debug : qemuProcessHandleMonitorEOF:296 : Domain 0x7f25cc0018a0 is not active, ignoring EOF >2012-10-06 16:41:38.874+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:38.874+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13 >2012-10-06 16:41:38.874+0000: 23344: debug : virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=13 >2012-10-06 16:41:38.874+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc0018a0 >2012-10-06 16:41:38.874+0000: 23349: debug : qemuDomainCleanupRun:2006 : driver=0x7f25d808f590, vm=guestfs-b95l0l3zykx0219j >2012-10-06 16:41:38.874+0000: 23349: debug : qemuProcessAutoDestroyRemove:4389 : vm=guestfs-b95l0l3zykx0219j >2012-10-06 16:41:38.874+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc003fb0 >2012-10-06 16:41:38.875+0000: 23344: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f25cc003fb0 >2012-10-06 16:41:38.874+0000: 23349: debug : qemuDriverCloseCallbackUnset:661 : vm=guestfs-b95l0l3zykx0219j, uuid=b1d5e6b8-94d7-67df-b5b8-2a67e0c39d13, cb=0x7f25df53d5b0 >2012-10-06 16:41:38.875+0000: 23344: debug : qemuMonitorDispose:247 : mon=0x7f25cc003fb0 >2012-10-06 16:41:38.875+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc0018a0 >2012-10-06 16:41:38.875+0000: 23349: debug : virSecuritySELinuxRestoreSecurityAllLabel:1408 : Restoring security label on guestfs-b95l0l3zykx0219j >2012-10-06 16:41:38.875+0000: 23349: info : virSecuritySELinuxRestoreSecurityFileLabel:884 : Restoring SELinux context on '/export/vmimgs2/f17-base.qcow2' >2012-10-06 16:41:38.875+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:38.894+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/export/vmimgs2/f17-base.qcow2' to 'system_u:object_r:usr_t:s0' >2012-10-06 16:41:38.895+0000: 23349: info : virSecuritySELinuxRestoreSecurityFileLabel:884 : Restoring SELinux context on '/tmp/libguestfsBKM1WI/console.sock' >2012-10-06 16:41:38.911+0000: 23349: warning : virSecuritySELinuxRestoreSecurityFileLabel:899 : cannot lookup default selinux label for /tmp/libguestfsBKM1WI/console.sock >2012-10-06 16:41:38.911+0000: 23349: info : virSecuritySELinuxRestoreSecurityFileLabel:884 : Restoring SELinux context on '/tmp/libguestfsBKM1WI/guestfsd.sock' >2012-10-06 16:41:38.926+0000: 23349: warning : virSecuritySELinuxRestoreSecurityFileLabel:899 : cannot lookup default selinux label for /tmp/libguestfsBKM1WI/guestfsd.sock >2012-10-06 16:41:38.926+0000: 23349: info : virSecuritySELinuxRestoreSecurityFileLabel:884 : Restoring SELinux context on '/var/tmp/.guestfs-0/kernel.23504' >2012-10-06 16:41:38.943+0000: 23349: warning : virSecuritySELinuxRestoreSecurityFileLabel:899 : cannot lookup default selinux label for /var/tmp/.guestfs-0/kernel.23504 >2012-10-06 16:41:38.943+0000: 23349: info : virSecuritySELinuxRestoreSecurityFileLabel:884 : Restoring SELinux context on '/var/tmp/.guestfs-0/initrd.23504' >2012-10-06 16:41:38.959+0000: 23349: warning : virSecuritySELinuxRestoreSecurityFileLabel:899 : cannot lookup default selinux label for /var/tmp/.guestfs-0/initrd.23504 >2012-10-06 16:41:38.959+0000: 23349: debug : virSecurityDACRestoreSecurityAllLabel:754 : Restoring security label on guestfs-b95l0l3zykx0219j migrated=0 >2012-10-06 16:41:38.959+0000: 23349: info : virSecurityDACRestoreSecurityFileLabel:344 : Restoring DAC user and group on '/export/vmimgs2/f17-base.qcow2' >2012-10-06 16:41:38.959+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/export/vmimgs2/f17-base.qcow2' to '0:0' >2012-10-06 16:41:38.959+0000: 23349: info : virSecurityDACRestoreSecurityFileLabel:344 : Restoring DAC user and group on '/var/tmp/.guestfs-0/kernel.23504' >2012-10-06 16:41:38.959+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/var/tmp/.guestfs-0/kernel.23504' to '0:0' >2012-10-06 16:41:38.959+0000: 23349: info : virSecurityDACRestoreSecurityFileLabel:344 : Restoring DAC user and group on '/var/tmp/.guestfs-0/initrd.23504' >2012-10-06 16:41:38.959+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/var/tmp/.guestfs-0/initrd.23504' to '0:0' >2012-10-06 16:41:38.959+0000: 23349: debug : virCgroupNew:617 : New group /libvirt/qemu/guestfs-b95l0l3zykx0219j >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /system/libvirtd.service >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /system/libvirtd.service >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:535 : Make group /libvirt/qemu/guestfs-b95l0l3zykx0219j >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpuset/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/freezer/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/blkio/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups >2012-10-06 16:41:38.962+0000: 23349: debug : virCgroupRemoveRecursively:727 : Removing cgroup /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.971+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups >2012-10-06 16:41:38.971+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/cpuset/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups >2012-10-06 16:41:38.972+0000: 23349: debug : virCgroupRemoveRecursively:727 : Removing cgroup /sys/fs/cgroup/cpuset/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.979+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups >2012-10-06 16:41:38.980+0000: 23349: debug : virCgroupRemoveRecursively:727 : Removing cgroup /sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:38.995+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups >2012-10-06 16:41:38.995+0000: 23349: debug : virCgroupRemoveRecursively:727 : Removing cgroup /sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:39.003+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/freezer/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups >2012-10-06 16:41:39.003+0000: 23349: debug : virCgroupRemoveRecursively:727 : Removing cgroup /sys/fs/cgroup/freezer/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:39.011+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/blkio/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups >2012-10-06 16:41:39.011+0000: 23349: debug : virCgroupRemoveRecursively:727 : Removing cgroup /sys/fs/cgroup/blkio/libvirt/qemu/guestfs-b95l0l3zykx0219j/ >2012-10-06 16:41:39.020+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc0042c0 >2012-10-06 16:41:39.020+0000: 23349: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f25cc0042c0 >2012-10-06 16:41:39.020+0000: 23349: debug : qemuDomainObjEndJob:936 : Stopping job: modify (async=none) >2012-10-06 16:41:39.020+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc0018a0 >2012-10-06 16:41:39.020+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc0018a0 >2012-10-06 16:41:39.020+0000: 23349: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f25cc0018a0 >2012-10-06 16:41:39.020+0000: 23349: debug : virDomainObjDispose:1736 : obj=0x7f25cc0018a0 >2012-10-06 16:41:39.020+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8107160 >2012-10-06 16:41:39.020+0000: 23349: debug : virNetServerProgramSendError:150 : prog=536903814 ver=1 proc=10 type=1 serial=4 msg=0x1a325c0 rerr=0x7f25e6a4cc70 >2012-10-06 16:41:39.020+0000: 23349: debug : virNetMessageEncodePayload:358 : Encode length as 200 >2012-10-06 16:41:39.020+0000: 23349: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1a325c0 proc=10 len=200 offset=0 >2012-10-06 16:41:39.020+0000: 23349: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1a31ca0 len=200 prog=536903814 vers=1 proc=10 type=1 status=1 serial=4 >2012-10-06 16:41:39.021+0000: 23349: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32450 tx=0x1a325c0 >2012-10-06 16:41:39.021+0000: 23349: debug : virNetServerClientCalculateHandleMode:183 : mode=3 >2012-10-06 16:41:39.021+0000: 23349: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3 >2012-10-06 16:41:39.021+0000: 23349: debug : virEventPollInterruptLocked:701 : Interrupting >2012-10-06 16:41:39.021+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a23da0 >2012-10-06 16:41:39.021+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=5 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2 >2012-10-06 16:41:39.021+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a325c0 nfds=0 cb=(nil) >2012-10-06 16:41:39.021+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32450 tx=(nil) >2012-10-06 16:41:39.021+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1 >2012-10-06 16:41:39.022+0000: 23344: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more) >2012-10-06 16:41:39.022+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32450 tx=(nil) >2012-10-06 16:41:39.022+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:39.022+0000: 23344: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x1a31ca0 len=28 prog=536903814 vers=1 proc=2 type=0 status=0 serial=5 >2012-10-06 16:41:39.022+0000: 23344: debug : virKeepAliveCheckMessage:384 : ka=0x1a324c0, client=0x1a31ca0, msg=0x1a32450 >2012-10-06 16:41:39.022+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0 >2012-10-06 16:41:39.022+0000: 23344: debug : virNetServerDispatchNewMessage:218 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a32450 >2012-10-06 16:41:39.022+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a23da0 >2012-10-06 16:41:39.023+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a31930 tracked=1 >2012-10-06 16:41:39.023+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a31930 tx=(nil) >2012-10-06 16:41:39.023+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:39.023+0000: 23345: debug : virNetServerHandleJob:189 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a32450 prog=0x1a23da0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:39.023+0000: 23345: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=2 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:39.023+0000: 23345: debug : remoteDispatchCloseHelper:124 : server=0x1a0ff10 client=0x1a31ca0 msg=0x1a32450 rerr=0x7f25e8a50c70 args=0x7f25c0000950 ret=0x7f25c00008c0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:39.023+0000: 23345: debug : virNetMessageEncodePayload:358 : Encode length as 28 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:39.023+0000: 23345: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1a32450 proc=2 len=28 offset=0 >2012-10-06 16:41:39.023+0000: 23345: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1a31ca0 len=28 prog=536903814 vers=1 proc=2 type=1 status=0 serial=5 >2012-10-06 16:41:39.023+0000: 23345: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a31930 tx=0x1a32450 >2012-10-06 16:41:39.023+0000: 23345: debug : virNetServerClientCalculateHandleMode:183 : mode=2 >2012-10-06 16:41:39.023+0000: 23345: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=2 >2012-10-06 16:41:39.023+0000: 23345: debug : virEventPollInterruptLocked:701 : Interrupting >2012-10-06 16:41:39.023+0000: 23345: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a23da0 >2012-10-06 16:41:39.023+0000: 23345: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=4 d=0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s) >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2 >2012-10-06 16:41:39.024+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a32450 nfds=0 cb=(nil) >2012-10-06 16:41:39.024+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a31930 tx=(nil) >2012-10-06 16:41:39.024+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:39.024+0000: 23344: debug : virNetServerClientClose:620 : client=0x1a31ca0 >2012-10-06 16:41:39.024+0000: 23344: debug : virKeepAliveStop:320 : RPC_KEEPALIVE_STOP: ka=0x1a324c0 client=0x1a31ca0 >2012-10-06 16:41:39.024+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0 >2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a324c0 >2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x1a324c0 >2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0 >2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0 >2012-10-06 16:41:39.024+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0 >2012-10-06 16:41:39.024+0000: 23344: debug : daemonRemoveAllClientStreams:480 : stream=(nil) >2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=12 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollRemoveHandle:188 : mark delete 11 21 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 -284886976 >2012-10-06 16:41:39.024+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a31930 nfds=0 cb=(nil) >2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31a50 >2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12 >2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=12 >2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0 >2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x1a31ca0 >2012-10-06 16:41:39.024+0000: 23344: debug : virConnectClose:1449 : conn=0x7f25d00025f0 >2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d00025f0 >2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f25d00025f0 >2012-10-06 16:41:39.026+0000: 23344: debug : qemuDriverCloseCallbackRunAll:744 : conn=0x7f25d00025f0 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollRemoveTimeout:288 : EVENT_POLL_REMOVE_TIMEOUT: timer=1 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976 >2012-10-06 16:41:39.026+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31a50 >2012-10-06 16:41:39.026+0000: 23344: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x1a31a50 >2012-10-06 16:41:39.026+0000: 23344: debug : virNetSocketDispose:889 : sock=0x1a31a50 fd=21 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=12 >2012-10-06 16:41:39.026+0000: 23344: debug : virFileClose:72 : Closed fd 21 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0 >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms >2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=-1
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 863696
: 622766