Attachment 639136 Details for Bug 873537 – libvirtd log

libvirtd log

libvirtd.log (text/plain), 65.24 KB, created by zhpeng on 2012-11-06 06:37:57 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: zhpeng

Created: 2012-11-06 06:37:57 UTC

Size: 65.24 KB

patch

obsolete

>2012-11-06 06:19:17.217+0000: 9279: warning : virCgroupMoveTask:885 : no vm cgroup in controller 3
>2012-11-06 06:19:17.217+0000: 9279: warning : virCgroupMoveTask:885 : no vm cgroup in controller 4
>2012-11-06 06:19:17.217+0000: 9279: warning : virCgroupMoveTask:885 : no vm cgroup in controller 6
>2012-11-06 06:19:17.379+0000: 9279: warning : virSecuritySELinuxRestoreSecurityFileLabel:939 : cannot lookup default selinux label for /tmp/rail.save
>2012-11-06 06:19:17.379+0000: 9279: warning : qemuDomainSaveImageStartVM:5026 : failed to restore save state label on /tmp/rail.save
>2012-11-06 06:19:22.260+0000: 9282: warning : virCgroupMoveTask:885 : no vm cgroup in controller 3
>2012-11-06 06:19:22.260+0000: 9282: warning : virCgroupMoveTask:885 : no vm cgroup in controller 4
>2012-11-06 06:19:22.260+0000: 9282: warning : virCgroupMoveTask:885 : no vm cgroup in controller 6
>2012-11-06 06:19:22.411+0000: 9282: warning : virSecuritySELinuxRestoreSecurityFileLabel:939 : cannot lookup default selinux label for /tmp/rail.save
>2012-11-06 06:19:22.411+0000: 9282: warning : qemuDomainSaveImageStartVM:5026 : failed to restore save state label on /tmp/rail.save
>Caught abort signal dumping internal log buffer:
>
>
>    ====== start of log =====
>
>obj=0x7fcbe800bab0
>2012-11-06 06:19:23.389+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:23.389+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:23.389+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:23.389+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:23.389+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:23.389+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:23.389+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:23.389+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=19, f=26 e=25 d=0
>2012-11-06 06:19:23.389+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=20, f=20 e=1 d=0
>2012-11-06 06:19:23.389+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=21, f=27 e=1 d=0
>2012-11-06 06:19:23.389+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:23.389+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:23.389+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 4043 ms
>2012-11-06 06:19:23.389+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=4043
>2012-11-06 06:19:23.412+00009278: debug : virFileClose:72 : Closed fd 23
>2012-11-06 06:19:23.412+00009278: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbfc06a120
>2012-11-06 06:19:23.462+00009278: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fcbfc06a120
>2012-11-06 06:19:23.462+00009278: debug : qemuDomainObjBeginJobInternal:807 : Starting job: async nested (async=save)
>2012-11-06 06:19:23.462+00009278: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fcbe800bab0
>2012-11-06 06:19:23.462+00009278: debug : qemuMonitorGetMigrationStatus:1851 : mon=0x7fcbe800bab0
>2012-11-06 06:19:23.462+00009278: debug : virJSONValueToString:1099 : object=0x7fcbf8000cf0
>2012-11-06 06:19:23.463+00009278: debug : virJSONValueToStringOne:1030 : object=0x7fcbf8000cf0 type=0 gen=0x7fcbf8000e70
>2012-11-06 06:19:23.463+00009278: debug : virJSONValueToStringOne:1030 : object=0x7fcbf8000d60 type=2 gen=0x7fcbf8000e70
>2012-11-06 06:19:23.463+00009278: debug : virJSONValueToStringOne:1030 : object=0x7fcbf8000e10 type=2 gen=0x7fcbf8000e70
>2012-11-06 06:19:23.463+00009278: debug : virJSONValueToString:1133 : result={"execute":"query-migrate","id":"libvirt-22"}
>2012-11-06 06:19:23.463+00009278: debug : qemuMonitorJSONCommandWithFd:263 : Send command '{"execute":"query-migrate","id":"libvirt-22"}' for write with FD -1
>2012-11-06 06:19:23.463+00009278: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=19 events=15
>2012-11-06 06:19:23.463+00009278: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-06 06:19:23.463+00009278: debug : qemuMonitorSend:903 : QEMU_MONITOR_SEND_MSG: mon=0x7fcbe800bab0 msg={"execute":"query-migrate","id":"libvirt-22"}
> fd=-1
>2012-11-06 06:19:23.463+00009277: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=9 w=19
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=10 w=20
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=11 w=21
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.463+00009277: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=19, f=26 e=29 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=20, f=20 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=21, f=27 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 3969 ms
>2012-11-06 06:19:23.463+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3969
>2012-11-06 06:19:23.463+00009277: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=9 w=19
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=19 events=2
>2012-11-06 06:19:23.463+00009277: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fcbe800bab0
>2012-11-06 06:19:23.463+00009277: debug : qemuMonitorIOWrite:461 : QEMU_MONITOR_IO_WRITE: mon=0x7fcbe800bab0 buf={"execute":"query-migrate","id":"libvirt-22"}
> len=47 ret=47 errno=11
>2012-11-06 06:19:23.463+00009277: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=19 events=13
>2012-11-06 06:19:23.463+00009277: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 223680608
>2012-11-06 06:19:23.463+00009277: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbe800bab0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=10 w=20
>2012-11-06 06:19:23.463+00009277: debug : virEventPollDispatchHandles:469 : i=11 w=21
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.463+00009277: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=19, f=26 e=25 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=20, f=20 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=21, f=27 e=1 d=0
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:23.463+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 3969 ms
>2012-11-06 06:19:23.463+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3969
>2012-11-06 06:19:23.518+00009277: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-06 06:19:23.518+00009277: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-06 06:19:23.518+00009277: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-06 06:19:23.518+00009277: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-06 06:19:23.518+00009277: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-06 06:19:23.518+00009277: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-06 06:19:23.518+00009277: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-06 06:19:23.518+00009277: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-06 06:19:23.518+00009277: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-06 06:19:23.518+00009277: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-06 06:19:23.518+00009277: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-06 06:19:23.518+00009277: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-06 06:19:23.518+00009277: debug : virEventPollDispatchHandles:469 : i=9 w=19
>2012-11-06 06:19:23.518+00009277: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=19 events=1
>2012-11-06 06:19:23.518+00009277: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fcbe800bab0
>2012-11-06 06:19:23.518+00009277: debug : qemuMonitorIOProcess:353 : QEMU_MONITOR_IO_PROCESS: mon=0x7fcbe800bab0 buf={"return": {"status": "completed"}, "id": "libvirt-22"}
> len=57
>2012-11-06 06:19:23.518+00009277: debug : qemuMonitorJSONIOProcessLine:150 : Line [{"return": {"status": "completed"}, "id": "libvirt-22"}]
>2012-11-06 06:19:23.518+00009277: debug : virJSONValueFromString:975 : string={"return": {"status": "completed"}, "id": "libvirt-22"}
>2012-11-06 06:19:23.518+00009277: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd62bf7e0
>2012-11-06 06:19:23.518+00009277: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd62bf7e0 key=0xe7f7a2
>2012-11-06 06:19:23.518+00009277: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd62bf7e0
>2012-11-06 06:19:23.518+00009277: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd62bf7e0 key=0xe7f7ad
>2012-11-06 06:19:23.518+00009277: debug : virJSONParserHandleString:813 : parser=0x7fffd62bf7e0 str=0xe7f7b7
>2012-11-06 06:19:23.518+00009277: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd62bf7e0
>2012-11-06 06:19:23.518+00009277: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd62bf7e0 key=0xe7f7c5
>2012-11-06 06:19:23.518+00009277: debug : virJSONParserHandleString:813 : parser=0x7fffd62bf7e0 str=0xe7f7cb
>2012-11-06 06:19:23.518+00009277: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd62bf7e0
>2012-11-06 06:19:23.518+00009277: debug : virJSONValueFromString:1019 : result=0xe62a10
>2012-11-06 06:19:23.518+00009277: debug : qemuMonitorJSONIOProcessLine:170 : QEMU_MONITOR_RECV_REPLY: mon=0x7fcbe800bab0 reply={"return": {"status": "completed"}, "id": "libvirt-22"}
>2012-11-06 06:19:23.518+00009277: debug : qemuMonitorJSONIOProcess:221 : Total used 57 bytes out of 57 available in buffer
>2012-11-06 06:19:23.518+00009277: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=19 events=13
>2012-11-06 06:19:23.518+00009277: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 223680608
>2012-11-06 06:19:23.519+00009277: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbe800bab0
>2012-11-06 06:19:23.519+00009277: debug : virEventPollDispatchHandles:469 : i=10 w=20
>2012-11-06 06:19:23.519+00009277: debug : virEventPollDispatchHandles:469 : i=11 w=21
>2012-11-06 06:19:23.519+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.519+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.519+00009278: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=19 events=13
>2012-11-06 06:19:23.519+00009277: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-06 06:19:23.519+00009278: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 223680608
>2012-11-06 06:19:23.519+00009278: debug : qemuMonitorJSONCommandWithFd:268 : Receive command reply ret=0 rxObject=0xe62a10
>2012-11-06 06:19:23.519+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.519+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.519+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-06 06:19:23.519+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-06 06:19:23.519+00009278: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbe800bab0
>2012-11-06 06:19:23.519+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:23.519+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:23.519+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:23.519+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:23.519+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:23.519+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:23.519+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:23.519+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=19, f=26 e=25 d=0
>2012-11-06 06:19:23.519+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=20, f=20 e=1 d=0
>2012-11-06 06:19:23.519+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=21, f=27 e=1 d=0
>2012-11-06 06:19:23.519+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:23.519+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:23.519+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 3913 ms
>2012-11-06 06:19:23.519+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3913
>2012-11-06 06:19:23.553+00009278: debug : virFileClose:72 : Closed fd 23
>2012-11-06 06:19:23.553+00009278: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbfc06a120
>2012-11-06 06:19:23.603+00009278: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fcbfc06a120
>2012-11-06 06:19:23.603+00009278: debug : qemuDomainObjBeginJobInternal:807 : Starting job: async nested (async=save)
>2012-11-06 06:19:23.603+00009278: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fcbe800bab0
>2012-11-06 06:19:23.604+00009278: debug : qemuMonitorSetMigrationSpeed:1808 : mon=0x7fcbe800bab0 bandwidth=8796093022207
>2012-11-06 06:19:23.604+00009278: debug : virJSONValueToString:1099 : object=0x7fcbf8000cf0
>2012-11-06 06:19:23.604+00009278: debug : virJSONValueToStringOne:1030 : object=0x7fcbf8000cf0 type=0 gen=0x7fcbf8000f20
>2012-11-06 06:19:23.604+00009278: debug : virJSONValueToStringOne:1030 : object=0x7fcbf8000c80 type=2 gen=0x7fcbf8000f20
>2012-11-06 06:19:23.604+00009278: debug : virJSONValueToStringOne:1030 : object=0x7fcbf8000de0 type=0 gen=0x7fcbf8000f20
>2012-11-06 06:19:23.604+00009278: debug : virJSONValueToStringOne:1030 : object=0x7fcbf8000e20 type=3 gen=0x7fcbf8000f20
>2012-11-06 06:19:23.604+00009278: debug : virJSONValueToStringOne:1030 : object=0x7fcbf8000ea0 type=2 gen=0x7fcbf8000f20
>2012-11-06 06:19:23.604+00009278: debug : virJSONValueToString:1133 : result={"execute":"migrate_set_speed","arguments":{"value":9223372036853727232},"id":"libvirt-23"}
>2012-11-06 06:19:23.604+00009278: debug : qemuMonitorJSONCommandWithFd:263 : Send command '{"execute":"migrate_set_speed","arguments":{"value":9223372036853727232},"id":"libvirt-23"}' for write with FD -1
>2012-11-06 06:19:23.604+00009278: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=19 events=15
>2012-11-06 06:19:23.604+00009278: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-06 06:19:23.604+00009278: debug : qemuMonitorSend:903 : QEMU_MONITOR_SEND_MSG: mon=0x7fcbe800bab0 msg={"execute":"migrate_set_speed","arguments":{"value":9223372036853727232},"id":"libvirt-23"}
> fd=-1
>2012-11-06 06:19:23.604+00009277: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=9 w=19
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=10 w=20
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=11 w=21
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.604+00009277: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=19, f=26 e=29 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=20, f=20 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=21, f=27 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 3828 ms
>2012-11-06 06:19:23.604+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3828
>2012-11-06 06:19:23.604+00009277: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=9 w=19
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=19 events=2
>2012-11-06 06:19:23.604+00009277: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fcbe800bab0
>2012-11-06 06:19:23.604+00009277: debug : qemuMonitorIOWrite:461 : QEMU_MONITOR_IO_WRITE: mon=0x7fcbe800bab0 buf={"execute":"migrate_set_speed","arguments":{"value":9223372036853727232},"id":"libvirt-23"}
> len=93 ret=93 errno=11
>2012-11-06 06:19:23.604+00009277: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=19 events=13
>2012-11-06 06:19:23.604+00009277: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 223680608
>2012-11-06 06:19:23.604+00009277: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbe800bab0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=10 w=20
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=11 w=21
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.604+00009277: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=19, f=26 e=25 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=20, f=20 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=21, f=27 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 3828 ms
>2012-11-06 06:19:23.604+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3828
>2012-11-06 06:19:23.604+00009277: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=9 w=19
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=19 events=1
>2012-11-06 06:19:23.604+00009277: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fcbe800bab0
>2012-11-06 06:19:23.604+00009277: debug : qemuMonitorIOProcess:353 : QEMU_MONITOR_IO_PROCESS: mon=0x7fcbe800bab0 buf={"return": {}, "id": "libvirt-23"}
> len=36
>2012-11-06 06:19:23.604+00009277: debug : qemuMonitorJSONIOProcessLine:150 : Line [{"return": {}, "id": "libvirt-23"}]
>2012-11-06 06:19:23.604+00009277: debug : virJSONValueFromString:975 : string={"return": {}, "id": "libvirt-23"}
>2012-11-06 06:19:23.604+00009277: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd62bf7e0
>2012-11-06 06:19:23.604+00009277: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd62bf7e0 key=0xe7f7a2
>2012-11-06 06:19:23.604+00009277: debug : virJSONParserHandleStartMap:852 : parser=0x7fffd62bf7e0
>2012-11-06 06:19:23.604+00009277: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd62bf7e0
>2012-11-06 06:19:23.604+00009277: debug : virJSONParserHandleMapKey:833 : parser=0x7fffd62bf7e0 key=0xe7f7b0
>2012-11-06 06:19:23.604+00009277: debug : virJSONParserHandleString:813 : parser=0x7fffd62bf7e0 str=0xe7f7b6
>2012-11-06 06:19:23.604+00009277: debug : virJSONParserHandleEndMap:881 : parser=0x7fffd62bf7e0
>2012-11-06 06:19:23.604+00009277: debug : virJSONValueFromString:1019 : result=0xe5e8f0
>2012-11-06 06:19:23.604+00009277: debug : qemuMonitorJSONIOProcessLine:170 : QEMU_MONITOR_RECV_REPLY: mon=0x7fcbe800bab0 reply={"return": {}, "id": "libvirt-23"}
>2012-11-06 06:19:23.604+00009277: debug : qemuMonitorJSONIOProcess:221 : Total used 36 bytes out of 36 available in buffer
>2012-11-06 06:19:23.604+00009277: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=19 events=13
>2012-11-06 06:19:23.604+00009277: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 223680608
>2012-11-06 06:19:23.604+00009277: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbe800bab0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=10 w=20
>2012-11-06 06:19:23.604+00009277: debug : virEventPollDispatchHandles:469 : i=11 w=21
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.604+00009277: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:23.604+00009278: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=19 events=13
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=19, f=26 e=25 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=20, f=20 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=21, f=27 e=1 d=0
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:23.604+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 3828 ms
>2012-11-06 06:19:23.604+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3828
>2012-11-06 06:19:23.605+00009278: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-06 06:19:23.605+00009278: debug : qemuMonitorJSONCommandWithFd:268 : Receive command reply ret=0 rxObject=0xe5e8f0
>2012-11-06 06:19:23.605+00009278: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbe800bab0
>2012-11-06 06:19:23.605+00009277: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:469 : i=9 w=19
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:469 : i=10 w=20
>2012-11-06 06:19:23.605+00009277: debug : virEventPollDispatchHandles:469 : i=11 w=21
>2012-11-06 06:19:23.605+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.605+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.605+00009277: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-06 06:19:23.605+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:23.605+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:23.605+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-06 06:19:23.605+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-06 06:19:23.605+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:23.605+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:23.605+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:23.605+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:23.605+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:23.605+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:23.605+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:23.605+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=19, f=26 e=25 d=0
>2012-11-06 06:19:23.605+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=20, f=20 e=1 d=0
>2012-11-06 06:19:23.605+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=21, f=27 e=1 d=0
>2012-11-06 06:19:23.605+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:23.605+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:23.605+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 3827 ms
>2012-11-06 06:19:23.605+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=3827
>2012-11-06 06:19:23.628+00009278: debug : virFileClose:72 : Closed fd 23
>2012-11-06 06:19:23.628+00009278: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbfc06a120
>2012-11-06 06:19:23.628+00009278: debug : virFileClose:72 : Closed fd 25
>2012-11-06 06:19:26.237+00009277: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:469 : i=9 w=19
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:469 : i=10 w=20
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:469 : i=11 w=21
>2012-11-06 06:19:26.237+00009277: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=21 events=8
>2012-11-06 06:19:26.237+00009277: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=21
>2012-11-06 06:19:26.237+00009277: debug : virEventPollRemoveHandle:188 : mark delete 11 27
>2012-11-06 06:19:26.237+00009277: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 223680608
>2012-11-06 06:19:26.237+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:26.237+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-06 06:19:26.237+00009277: debug : virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=21
>2012-11-06 06:19:26.237+00009277: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-06 06:19:26.237+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:26.237+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-11-06 06:19:26.237+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-06 06:19:26.237+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-06 06:19:26.237+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:26.237+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:26.237+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:26.237+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:26.237+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:26.237+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:26.237+00009278: debug : virStorageFileIsSharedFSType:1129 : Check if path /tmp/rail.save with FS magic 61267 is shared
>2012-11-06 06:19:26.237+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:26.237+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=19, f=26 e=25 d=0
>2012-11-06 06:19:26.237+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=20, f=20 e=1 d=0
>2012-11-06 06:19:26.237+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:26.237+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:26.237+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 1195 ms
>2012-11-06 06:19:26.237+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=1195
>2012-11-06 06:19:26.237+00009278: debug : virFileClose:72 : Closed fd 23
>2012-11-06 06:19:26.237+00009278: debug : virFileClose:72 : Closed fd 27
>2012-11-06 06:19:26.237+00009278: debug : qemuProcessStop:4061 : Shutting down VM 'rail' pid=9509 flags=0
>2012-11-06 06:19:26.238+00009278: debug : virFileClose:72 : Closed fd 23
>2012-11-06 06:19:26.238+00009278: debug : qemuMonitorClose:837 : QEMU_MONITOR_CLOSE: mon=0x7fcbe800bab0 refs=2
>2012-11-06 06:19:26.238+00009278: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=19
>2012-11-06 06:19:26.238+00009278: debug : virEventPollRemoveHandle:188 : mark delete 9 26
>2012-11-06 06:19:26.238+00009278: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-06 06:19:26.238+00009278: debug : virFileClose:72 : Closed fd 26
>2012-11-06 06:19:26.238+00009278: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbe800bab0
>2012-11-06 06:19:26.238+00009277: debug : virEventPollRunOnce:625 : Poll got 2 event(s)
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:469 : i=9 w=19
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:472 : Skip deleted n=9 w=19 f=26
>2012-11-06 06:19:26.238+00009277: debug : virEventPollDispatchHandles:469 : i=10 w=20
>2012-11-06 06:19:26.238+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:26.238+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-11-06 06:19:26.238+00009277: debug : virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=19
>2012-11-06 06:19:26.238+00009277: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbe800bab0
>2012-11-06 06:19:26.238+00009277: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7fcbe800bab0
>2012-11-06 06:19:26.238+00009277: debug : qemuMonitorDispose:246 : mon=0x7fcbe800bab0
>2012-11-06 06:19:26.238+00009277: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbfc06a120
>2012-11-06 06:19:26.238+00009277: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-06 06:19:26.238+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:26.238+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-11-06 06:19:26.238+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-06 06:19:26.238+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-06 06:19:26.238+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:26.238+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:26.238+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:26.238+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:26.238+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:26.238+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:26.238+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:26.238+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=20, f=20 e=1 d=0
>2012-11-06 06:19:26.238+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:26.238+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:26.238+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 1194 ms
>2012-11-06 06:19:26.238+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=1194
>2012-11-06 06:19:26.239+00009278: debug : qemuProcessKill:3959 : vm=rail pid=9509 flags=5
>2012-11-06 06:19:26.253+00009277: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2012-11-06 06:19:26.253+00009277: debug : virNetlinkEventCallback:346 : dispatching to max 0 clients, called from event watch 8
>2012-11-06 06:19:26.253+00009277: debug : virNetlinkEventCallback:359 : event not handled.
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=9 w=20
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-11-06 06:19:26.253+00009277: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=20, f=20 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 1179 ms
>2012-11-06 06:19:26.253+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=1179
>2012-11-06 06:19:26.253+00009277: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2012-11-06 06:19:26.253+00009277: debug : virNetlinkEventCallback:346 : dispatching to max 0 clients, called from event watch 8
>2012-11-06 06:19:26.253+00009277: debug : virNetlinkEventCallback:359 : event not handled.
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-06 06:19:26.253+00009277: debug : virEventPollDispatchHandles:469 : i=9 w=20
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-11-06 06:19:26.253+00009277: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=20, f=20 e=1 d=0
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:26.253+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 1179 ms
>2012-11-06 06:19:26.253+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=1179
>2012-11-06 06:19:26.255+00009277: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-06 06:19:26.255+00009277: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-06 06:19:26.255+00009277: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-11-06 06:19:26.255+00009277: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-06 06:19:26.255+00009277: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-06 06:19:26.255+00009277: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-06 06:19:26.255+00009277: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-06 06:19:26.255+00009277: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-06 06:19:26.255+00009277: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-06 06:19:26.255+00009277: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-06 06:19:26.255+00009277: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-06 06:19:26.255+00009277: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-06 06:19:26.255+00009277: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=1
>2012-11-06 06:19:26.255+00009277: debug : udevEventHandleCallback:1467 : udev action: 'remove'
>2012-11-06 06:19:26.255+00009277: debug : udevRemoveOneDevice:1218 : Failed to find device to remove that has udev name '/sys/devices/virtual/net/vnet0/queues/tx-0'
>2012-11-06 06:19:26.255+00009277: debug : virEventPollDispatchHandles:469 : i=9 w=20
>2012-11-06 06:19:26.255+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:26.255+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-11-06 06:19:26.255+00009277: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-06 06:19:26.255+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:26.255+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-11-06 06:19:26.255+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-06 06:19:26.255+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-06 06:19:26.255+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:26.255+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:26.255+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:26.255+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:26.255+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:26.255+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:26.255+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:26.255+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=20, f=20 e=1 d=0
>2012-11-06 06:19:26.255+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:26.255+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:26.255+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 1177 ms
>2012-11-06 06:19:26.255+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=1177
>2012-11-06 06:19:26.291+00009277: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-06 06:19:26.291+00009277: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-06 06:19:26.291+00009277: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-11-06 06:19:26.291+00009277: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-06 06:19:26.291+00009277: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-06 06:19:26.291+00009277: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-06 06:19:26.291+00009277: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-06 06:19:26.291+00009277: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-06 06:19:26.291+00009277: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-06 06:19:26.291+00009277: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-06 06:19:26.291+00009277: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-06 06:19:26.291+00009277: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-06 06:19:26.291+00009277: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=1
>2012-11-06 06:19:26.291+00009277: debug : udevEventHandleCallback:1467 : udev action: 'remove'
>2012-11-06 06:19:26.291+00009277: debug : udevRemoveOneDevice:1214 : Removing device 'net_vnet0_fe_54_00_1c_22_33' with sysfs path '/sys/devices/virtual/net/vnet0'
>2012-11-06 06:19:26.291+00009277: debug : virEventPollDispatchHandles:469 : i=9 w=20
>2012-11-06 06:19:26.291+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:26.291+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-11-06 06:19:26.291+00009277: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-06 06:19:26.291+00009277: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-06 06:19:26.291+00009277: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2012-11-06 06:19:26.291+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-06 06:19:26.291+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-06 06:19:26.291+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-06 06:19:26.291+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-06 06:19:26.291+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-06 06:19:26.291+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-06 06:19:26.291+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-06 06:19:26.291+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-06 06:19:26.291+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-06 06:19:26.291+00009277: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=20, f=20 e=1 d=0
>2012-11-06 06:19:26.291+00009277: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-06 06:19:26.291+00009277: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1352182767432
>2012-11-06 06:19:26.291+00009277: debug : virEventPollCalculateTimeout:346 : Timeout at 1352182767432 due in 1141 ms
>2012-11-06 06:19:26.291+00009277: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=1141
>2012-11-06 06:19:26.439+00009278: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fcbfc06a120
>2012-11-06 06:19:26.439+00009278: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbfc06a120
>2012-11-06 06:19:26.439+00009278: debug : qemuDomainCleanupRun:1983 : driver=0x7fcbfc005790, vm=rail
>2012-11-06 06:19:26.439+00009278: debug : qemuProcessAutoDestroyRemove:4532 : vm=rail
>2012-11-06 06:19:26.439+00009278: debug : qemuDriverCloseCallbackUnset:661 : vm=rail, uuid=0094a6b8-02d8-c73f-5455-4f946b3e9e04, cb=0x49c9b0
>2012-11-06 06:19:26.439+00009278: debug : virSecuritySELinuxRestoreSecurityAllLabel:1445 : Restoring security label on rail
>2012-11-06 06:19:26.439+00009278: info : virSecuritySELinuxRestoreSecurityFileLabel:924 : Restoring SELinux context on '/var/lib/libvirt/images/rhel63.img'
>2012-11-06 06:19:26.513+00009278: info : virSecuritySELinuxSetFileconHelper:794 : Setting SELinux context on '/var/lib/libvirt/images/rhel63.img' to 'system_u:object_r:virt_image_t:s0'
>2012-11-06 06:19:26.513+00009278: debug : virStorageFileIsSharedFSType:1129 : Check if path /var/lib/libvirt/images/rhel63.img with FS magic 26985 is shared
>2012-11-06 06:19:26.513+00009278: info : virSecuritySELinuxSetFileconHelper:833 : Setting security context 'system_u:object_r:virt_image_t:s0' on '/var/lib/libvirt/images/rhel63.img' not supported
>2012-11-06 06:19:26.513+00009278: debug : virSecurityDACRestoreSecurityAllLabel:726 : Restoring security label on rail migrated=0
>2012-11-06 06:19:26.513+00009278: info : virSecurityDACRestoreSecurityFileLabel:315 : Restoring DAC user and group on '/var/lib/libvirt/images/rhel63.img'
>2012-11-06 06:19:26.513+00009278: info : virSecurityDACSetOwnership:271 : Setting DAC user and group on '/var/lib/libvirt/images/rhel63.img' to '0:0'
>2012-11-06 06:19:26.551+00009278: debug : networkReleaseActualDevice:3964 : Nothing to release to network default
>2012-11-06 06:19:26.551+00009278: debug : networkReleaseActualDevice:4050 : Releasing network default, 0 connections
>2012-11-06 06:19:26.551+00009278: debug : virCgroupNew:617 : New group /libvirt/qemu/rail
>2012-11-06 06:19:26.552+00009278: debug : virCgroupDetect:273 : Detected mount/mapping 0:cpu at /cgroup/cpu in 
>2012-11-06 06:19:26.552+00009278: debug : virCgroupDetect:273 : Detected mount/mapping 1:cpuacct at /cgroup/cpuacct in 
>2012-11-06 06:19:26.552+00009278: debug : virCgroupDetect:273 : Detected mount/mapping 2:cpuset at /cgroup/cpuset in 
>2012-11-06 06:19:26.552+00009278: debug : virCgroupDetect:273 : Detected mount/mapping 3:memory at /cgroup/memory in 
>2012-11-06 06:19:26.552+00009278: debug : virCgroupDetect:273 : Detected mount/mapping 4:devices at /cgroup/devices in 
>2012-11-06 06:19:26.552+00009278: debug : virCgroupDetect:273 : Detected mount/mapping 5:freezer at /cgroup/freezer in 
>2012-11-06 06:19:26.552+00009278: debug : virCgroupDetect:273 : Detected mount/mapping 6:blkio at /cgroup/blkio in 
>2012-11-06 06:19:26.552+00009278: debug : virCgroupMakeGroup:535 : Make group /libvirt/qemu/rail
>2012-11-06 06:19:26.552+00009278: debug : virCgroupMakeGroup:560 : Make controller /cgroup/cpu/libvirt/qemu/rail/
>2012-11-06 06:19:26.552+00009278: debug : virCgroupMakeGroup:560 : Make controller /cgroup/cpuacct/libvirt/qemu/rail/
>2012-11-06 06:19:26.552+00009278: debug : virCgroupMakeGroup:560 : Make controller /cgroup/cpuset/libvirt/qemu/rail/
>2012-11-06 06:19:26.552+00009278: debug : virCgroupMakeGroup:560 : Make controller /cgroup/memory/libvirt/qemu/rail/
>2012-11-06 06:19:26.552+00009278: debug : virCgroupMakeGroup:560 : Make controller /cgroup/devices/libvirt/qemu/rail/
>2012-11-06 06:19:26.552+00009278: debug : virCgroupMakeGroup:560 : Make controller /cgroup/freezer/libvirt/qemu/rail/
>2012-11-06 06:19:26.552+00009278: debug : virCgroupMakeGroup:560 : Make controller /cgroup/blkio/libvirt/qemu/rail/
>2012-11-06 06:19:26.552+00009278: debug : virCgroupRemove:772 : Removing cgroup /cgroup/cpu/libvirt/qemu/rail/ and all child cgroups
>2012-11-06 06:19:26.552+00009278: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpu/libvirt/qemu/rail//emulator
>2012-11-06 06:19:26.559+00009278: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpu/libvirt/qemu/rail//vcpu0
>2012-11-06 06:19:26.567+00009278: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpu/libvirt/qemu/rail/
>2012-11-06 06:19:26.575+00009278: debug : virCgroupRemove:772 : Removing cgroup /cgroup/cpuacct/libvirt/qemu/rail/ and all child cgroups
>2012-11-06 06:19:26.575+00009278: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpuacct/libvirt/qemu/rail//emulator
>2012-11-06 06:19:26.583+00009278: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpuacct/libvirt/qemu/rail//vcpu0
>2012-11-06 06:19:26.595+00009278: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpuacct/libvirt/qemu/rail/
>2012-11-06 06:19:26.603+00009278: debug : virCgroupRemove:772 : Removing cgroup /cgroup/cpuset/libvirt/qemu/rail/ and all child cgroups
>2012-11-06 06:19:26.603+00009278: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpuset/libvirt/qemu/rail//emulator
>2012-11-06 06:19:26.611+00009278: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpuset/libvirt/qemu/rail//vcpu0
>2012-11-06 06:19:26.619+00009278: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/cpuset/libvirt/qemu/rail/
>2012-11-06 06:19:26.627+00009278: debug : virCgroupRemove:772 : Removing cgroup /cgroup/memory/libvirt/qemu/rail/ and all child cgroups
>2012-11-06 06:19:26.627+00009278: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/memory/libvirt/qemu/rail/
>2012-11-06 06:19:26.635+00009278: debug : virCgroupRemove:772 : Removing cgroup /cgroup/devices/libvirt/qemu/rail/ and all child cgroups
>2012-11-06 06:19:26.635+00009278: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/devices/libvirt/qemu/rail/
>2012-11-06 06:19:26.643+00009278: debug : virCgroupRemove:772 : Removing cgroup /cgroup/freezer/libvirt/qemu/rail/ and all child cgroups
>2012-11-06 06:19:26.643+00009278: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/freezer/libvirt/qemu/rail/
>2012-11-06 06:19:26.651+00009278: debug : virCgroupRemove:772 : Removing cgroup /cgroup/blkio/libvirt/qemu/rail/ and all child cgroups
>2012-11-06 06:19:26.651+00009278: debug : virCgroupRemoveRecursively:727 : Removing cgroup /cgroup/blkio/libvirt/qemu/rail/
>2012-11-06 06:19:26.659+00009278: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbe800a0c0
>2012-11-06 06:19:26.659+00009278: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7fcbe800a0c0
>2012-11-06 06:19:26.661+00009278: debug : qemuDomainObjEndAsyncJob:954 : Stopping async job: save
>2012-11-06 06:19:26.661+00009278: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fcbfc06a120
>
>
>     ====== end of log =====
>

Actions: View

Attachments on bug 873537: 639136 | 639137