2012-04-29 19:26:07.981+0000: 19858: info : libvirt version: 0.9.11
2012-04-29 19:26:07.981+0000: 19858: info : virNetlinkEventServiceStart:336 : starting netlink event service
2012-04-29 19:26:07.991+0000: 19869: info : networkReloadIptablesRules:1512 : Reloading iptables rules
2012-04-29 19:26:08.271+0000: 19869: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'cpu'
2012-04-29 19:26:08.271+0000: 19869: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'cpuacct'
2012-04-29 19:26:08.271+0000: 19869: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'cpuset'
2012-04-29 19:26:08.271+0000: 19869: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'memory'
2012-04-29 19:26:08.271+0000: 19869: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'devices'
2012-04-29 19:26:08.271+0000: 19869: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'blkio'
I/O warning : failed to load external entity "/home/cloud/usr/share/libvirt/cpu_map.xml"
2012-04-29 19:26:08.272+0000: 19869: error : cpuMapLoad:103 : internal error cannot parse CPU map file: /home/cloud/usr/share/libvirt/cpu_map.xml
2012-04-29 19:26:08.272+0000: 19869: warning : qemuCapsInit:938 : Failed to get host CPU
2012-04-29 19:26:08.601+0000: 19869: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/var/run/libvirt/qemu
2012-04-29 19:26:08.608+0000: 19869: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/etc/libvirt/qemu
2012-04-29 19:26:08.717+0000: 19869: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/var/run/libvirt/lxc
2012-04-29 19:26:08.717+0000: 19869: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/etc/libvirt/lxc
2012-04-29 19:26:08.733+0000: 19869: info : umlStartup:479 : Adding inotify watch on /usr/local/var/run/libvirt/uml-guest
2012-04-29 19:26:08.733+0000: 19869: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/etc/libvirt/uml
2012-04-29 19:26:12.053+0000: 19859: info : remoteDispatchAuthList:2139 : Bypass polkit auth for privileged client pid:19915,uid:0
Caught Segmentation violation dumping internal log buffer:


    ====== start of log =====

 19869: debug : do_open:1301 : node driver 2 udevDeviceMonitor returned SUCCESS
2012-04-29 19:26:08.613+000019869: debug : do_open:1317 : secret driver 0 Test returned DECLINED
2012-04-29 19:26:08.613+000019869: debug : do_open:1317 : secret driver 1 remote returned DECLINED
2012-04-29 19:26:08.613+000019869: debug : do_open:1317 : secret driver 2 secret returned SUCCESS
2012-04-29 19:26:08.613+000019869: debug : do_open:1333 : nwfilter driver 0 Test returned DECLINED
2012-04-29 19:26:08.613+000019869: debug : do_open:1333 : nwfilter driver 1 remote returned DECLINED
2012-04-29 19:26:08.613+000019869: debug : do_open:1333 : nwfilter driver 2 nwfilter returned SUCCESS
2012-04-29 19:26:08.613+000019869: debug : virConnectClose:1496 : conn=0x7fc9a80c2d10
2012-04-29 19:26:08.613+000019869: debug : virUnrefConnect:145 : unref connection 0x7fc9a80c2d10 1
2012-04-29 19:26:08.613+000019869: debug : virReleaseConnect:94 : release connection 0x7fc9a80c2d10
2012-04-29 19:26:08.613+000019869: debug : qemuDriverCloseCallbackRunAll:661 : conn=0x7fc9a80c2d10
2012-04-29 19:26:08.613+000019869: debug : virConnectClose:1496 : conn=0x7fc9a808bd40
2012-04-29 19:26:08.613+000019869: debug : virUnrefConnect:145 : unref connection 0x7fc9a808bd40 1
2012-04-29 19:26:08.613+000019869: debug : virReleaseConnect:94 : release connection 0x7fc9a808bd40
2012-04-29 19:26:08.613+000019869: debug : qemuDriverCloseCallbackRunAll:661 : conn=0x7fc9a808bd40
2012-04-29 19:26:08.647+000019869: debug : lxcContainerAvailable:1526 : Mounted all filesystems
2012-04-29 19:26:08.647+000019869: debug : virCommandRunAsync:2174 : About to run ip link set lo netns -1
2012-04-29 19:26:08.649+000019869: debug : virCommandRunAsync:2192 : Command result 0, with PID 19912
2012-04-29 19:26:08.653+000019869: debug : virCommandRun:1994 : Result exit status 2, stdout: '' stderr: 'RTNETLINK answers: No such process
'
2012-04-29 19:26:08.658+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 0
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 4
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
2012-04-29 19:26:08.658+000019858: debug : udevEventHandleCallback:1467 : udev action: 'add'
2012-04-29 19:26:08.658+000019858: debug : udevGetDeviceProperty:120 : udev reports device 'rx-0' does not have property 'DRIVER'
2012-04-29 19:26:08.658+000019858: debug : udevGetDeviceType:1095 : Found device type '(null)' for device 'rx-0'
2012-04-29 19:26:08.658+000019858: debug : udevGetDeviceProperty:120 : udev reports device 'rx-0' does not have property 'PCI_CLASS'
2012-04-29 19:26:08.658+000019858: debug : udevGetDeviceProperty:120 : udev reports device 'rx-0' does not have property 'INTERFACE'
2012-04-29 19:26:08.658+000019858: debug : udevGetDeviceType:1152 : Could not determine device type for device with sysfs path 'rx-0'
2012-04-29 19:26:08.658+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:26:08.658+000019858: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:26:08.658+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:26:08.658+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:08.658+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:26:08.658+000019858: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:26:08.658+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:26:08.658+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:08.658+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:08.658+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=0 d=0
2012-04-29 19:26:08.658+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=0 d=0
2012-04-29 19:26:08.658+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:26:08.658+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:08.658+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 0 timers
2012-04-29 19:26:08.658+000019858: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:26:08.658+000019858: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=4 timeout=-1
2012-04-29 19:26:08.658+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 0
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 4
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:08.658+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
2012-04-29 19:26:08.658+000019858: debug : udevEventHandleCallback:1467 : udev action: 'add'
2012-04-29 19:26:08.659+000019858: debug : udevGetDeviceProperty:120 : udev reports device 'tx-0' does not have property 'DRIVER'
2012-04-29 19:26:08.659+000019858: debug : udevGetDeviceType:1095 : Found device type '(null)' for device 'tx-0'
2012-04-29 19:26:08.659+000019858: debug : udevGetDeviceProperty:120 : udev reports device 'tx-0' does not have property 'PCI_CLASS'
2012-04-29 19:26:08.659+000019858: debug : udevGetDeviceProperty:120 : udev reports device 'tx-0' does not have property 'INTERFACE'
2012-04-29 19:26:08.659+000019858: debug : udevGetDeviceType:1152 : Could not determine device type for device with sysfs path 'tx-0'
2012-04-29 19:26:08.659+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:26:08.659+000019858: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:26:08.659+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:26:08.659+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:08.659+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:26:08.659+000019858: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:26:08.659+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:26:08.659+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:08.659+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:08.659+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=0 d=0
2012-04-29 19:26:08.659+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=0 d=0
2012-04-29 19:26:08.659+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:26:08.659+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:08.659+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 0 timers
2012-04-29 19:26:08.659+000019858: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:26:08.659+000019858: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=4 timeout=-1
2012-04-29 19:26:08.711+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 0
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 4
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
2012-04-29 19:26:08.712+000019858: debug : udevEventHandleCallback:1467 : udev action: 'remove'
2012-04-29 19:26:08.712+000019858: debug : udevRemoveOneDevice:1218 : Failed to find device to remove that has udev name '/sys/devices/virtual/net/lo/queues/rx-0'
2012-04-29 19:26:08.712+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:26:08.712+000019858: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:26:08.712+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:26:08.712+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:08.712+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:26:08.712+000019858: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:26:08.712+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:26:08.712+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:08.712+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:08.712+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=0 d=0
2012-04-29 19:26:08.712+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=0 d=0
2012-04-29 19:26:08.712+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:26:08.712+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:08.712+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 0 timers
2012-04-29 19:26:08.712+000019858: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:26:08.712+000019858: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=4 timeout=-1
2012-04-29 19:26:08.712+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 0
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 4
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:08.712+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
2012-04-29 19:26:08.712+000019858: debug : udevEventHandleCallback:1467 : udev action: 'remove'
2012-04-29 19:26:08.712+000019858: debug : udevRemoveOneDevice:1218 : Failed to find device to remove that has udev name '/sys/devices/virtual/net/lo/queues/tx-0'
2012-04-29 19:26:08.712+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:26:08.712+000019858: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:26:08.712+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:26:08.712+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:08.712+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:26:08.712+000019858: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:26:08.712+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:26:08.712+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:08.712+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:08.712+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=0 d=0
2012-04-29 19:26:08.712+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=0 d=0
2012-04-29 19:26:08.712+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:26:08.712+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:08.712+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 0 timers
2012-04-29 19:26:08.712+000019858: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:26:08.712+000019858: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=4 timeout=-1
2012-04-29 19:26:08.715+000019869: debug : lxcContainerAvailable:1526 : Mounted all filesystems
2012-04-29 19:26:08.715+000019869: debug : virCgroupNew:603 : New group /
2012-04-29 19:26:08.715+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu in 
2012-04-29 19:26:08.715+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpuacct in 
2012-04-29 19:26:08.715+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in 
2012-04-29 19:26:08.715+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in 
2012-04-29 19:26:08.715+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in 
2012-04-29 19:26:08.715+000019869: debug : virCgroupNew:603 : New group /libvirt
2012-04-29 19:26:08.716+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu in 
2012-04-29 19:26:08.716+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpuacct in 
2012-04-29 19:26:08.716+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in 
2012-04-29 19:26:08.716+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in 
2012-04-29 19:26:08.716+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in 
2012-04-29 19:26:08.716+000019869: debug : virCgroupMakeGroup:524 : Make group /libvirt
2012-04-29 19:26:08.716+000019869: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/cpu/libvirt/
2012-04-29 19:26:08.716+000019869: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/cpuacct/libvirt/
2012-04-29 19:26:08.716+000019869: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/memory/libvirt/
2012-04-29 19:26:08.716+000019869: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/devices/libvirt/
2012-04-29 19:26:08.716+000019869: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/freezer/libvirt/
2012-04-29 19:26:08.716+000019869: debug : virCgroupNew:603 : New group /libvirt/lxc
2012-04-29 19:26:08.716+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu in 
2012-04-29 19:26:08.716+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpuacct in 
2012-04-29 19:26:08.716+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in 
2012-04-29 19:26:08.716+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in 
2012-04-29 19:26:08.716+000019869: debug : virCgroupDetect:262 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in 
2012-04-29 19:26:08.716+000019869: debug : virCgroupMakeGroup:524 : Make group /libvirt/lxc
2012-04-29 19:26:08.716+000019869: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/cpu/libvirt/lxc/
2012-04-29 19:26:08.716+000019869: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/cpuacct/libvirt/lxc/
2012-04-29 19:26:08.716+000019869: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/memory/libvirt/lxc/
2012-04-29 19:26:08.716+000019869: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/devices/libvirt/lxc/
2012-04-29 19:26:08.716+000019869: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/freezer/libvirt/lxc/
2012-04-29 19:26:08.716+000019869: debug : virSecurityDriverLookup:45 : name=(null)
2012-04-29 19:26:08.716+000019869: debug : virSecurityDriverLookup:61 : Not enabled name=selinux
2012-04-29 19:26:08.717+000019869: debug : virSecurityDriverLookup:61 : Not enabled name=apparmor
2012-04-29 19:26:08.717+000019869: debug : virSecurityDriverLookup:56 : Probed name=none
2012-04-29 19:26:08.717+000019869: debug : lxcCapsInit:144 : Initialized caps for security driver "none" with DOI "0"
2012-04-29 19:26:08.717+000019869: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/var/run/libvirt/lxc
2012-04-29 19:26:08.717+000019869: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/etc/libvirt/lxc
2012-04-29 19:26:08.717+000019869: debug : virConnectOpen:1383 : name=lxc:///
2012-04-29 19:26:08.717+000019869: debug : do_open:1182 : name "lxc:///" to URI components:
  scheme lxc
  server (null)
  user (null)
  port 0
  path /

2012-04-29 19:26:08.717+000019869: debug : do_open:1226 : trying driver 0 (Test) ...
2012-04-29 19:26:08.717+000019869: debug : do_open:1232 : driver 0 Test returned DECLINED
2012-04-29 19:26:08.717+000019869: debug : do_open:1226 : trying driver 1 (Xen) ...
2012-04-29 19:26:08.717+000019869: debug : do_open:1232 : driver 1 Xen returned DECLINED
2012-04-29 19:26:08.717+000019869: debug : do_open:1226 : trying driver 2 (OPENVZ) ...
2012-04-29 19:26:08.717+000019869: debug : do_open:1232 : driver 2 OPENVZ returned DECLINED
2012-04-29 19:26:08.717+000019869: debug : do_open:1226 : trying driver 3 (VMWARE) ...
2012-04-29 19:26:08.717+000019869: debug : do_open:1232 : driver 3 VMWARE returned DECLINED
2012-04-29 19:26:08.717+000019869: debug : do_open:1226 : trying driver 4 (VBOX) ...
2012-04-29 19:26:08.717+000019869: debug : do_open:1232 : driver 4 VBOX returned DECLINED
2012-04-29 19:26:08.717+000019869: debug : do_open:1226 : trying driver 5 (remote) ...
2012-04-29 19:26:08.717+000019869: debug : do_open:1232 : driver 5 remote returned DECLINED
2012-04-29 19:26:08.717+000019869: debug : do_open:1226 : trying driver 6 (QEMU) ...
2012-04-29 19:26:08.717+000019869: debug : do_open:1232 : driver 6 QEMU returned DECLINED
2012-04-29 19:26:08.717+000019869: debug : do_open:1226 : trying driver 7 (LXC) ...
2012-04-29 19:26:08.717+000019869: debug : do_open:1232 : driver 7 LXC returned SUCCESS
2012-04-29 19:26:08.717+000019869: debug : do_open:1254 : network driver 0 Test returned DECLINED
2012-04-29 19:26:08.717+000019869: debug : do_open:1254 : network driver 1 VBOX returned DECLINED
2012-04-29 19:26:08.717+000019869: debug : do_open:1254 : network driver 2 remote returned DECLINED
2012-04-29 19:26:08.717+000019869: debug : do_open:1254 : network driver 3 Network returned SUCCESS
2012-04-29 19:26:08.717+000019869: debug : do_open:1269 : interface driver 0 Test returned DECLINED
2012-04-29 19:26:08.717+000019869: debug : do_open:1269 : interface driver 1 remote returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1269 : interface driver 2 Interface returned ERROR
2012-04-29 19:26:08.733+000019869: debug : do_open:1285 : storage driver 0 Test returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1285 : storage driver 1 VBOX returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1285 : storage driver 2 remote returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1285 : storage driver 3 storage returned SUCCESS
2012-04-29 19:26:08.733+000019869: debug : do_open:1301 : node driver 0 Test returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1301 : node driver 1 remote returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1301 : node driver 2 udevDeviceMonitor returned SUCCESS
2012-04-29 19:26:08.733+000019869: debug : do_open:1317 : secret driver 0 Test returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1317 : secret driver 1 remote returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1317 : secret driver 2 secret returned SUCCESS
2012-04-29 19:26:08.733+000019869: debug : do_open:1333 : nwfilter driver 0 Test returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1333 : nwfilter driver 1 remote returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1333 : nwfilter driver 2 nwfilter returned SUCCESS
2012-04-29 19:26:08.733+000019869: debug : virConnectClose:1496 : conn=0x7fc9a80c2f10
2012-04-29 19:26:08.733+000019869: debug : virUnrefConnect:145 : unref connection 0x7fc9a80c2f10 1
2012-04-29 19:26:08.733+000019869: debug : virReleaseConnect:94 : release connection 0x7fc9a80c2f10
2012-04-29 19:26:08.733+000019869: debug : lxcProcessAutoDestroyRun:1060 : conn=0x7fc9a80c2f10
2012-04-29 19:26:08.733+000019869: info : umlStartup:479 : Adding inotify watch on /usr/local/var/run/libvirt/uml-guest
2012-04-29 19:26:08.733+000019869: debug : virEventPollInterruptLocked:706 : Interrupting
2012-04-29 19:26:08.733+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:08.733+000019869: debug : virEventPollAddHandle:141 : EVENT_POLL_ADD_HANDLE: watch=7 fd=14 events=1 cb=0x4d1b10 opaque=0x7fc9a80c9810 ff=(nil)
2012-04-29 19:26:08.733+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 0
2012-04-29 19:26:08.733+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 4
2012-04-29 19:26:08.733+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:08.733+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2012-04-29 19:26:08.733+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:08.733+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:08.733+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:08.733+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:26:08.733+000019858: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:26:08.733+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 7
2012-04-29 19:26:08.733+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:08.733+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:26:08.733+000019858: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:26:08.733+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 7
2012-04-29 19:26:08.733+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:08.733+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:08.733+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=0 d=0
2012-04-29 19:26:08.733+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=0 d=0
2012-04-29 19:26:08.733+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:26:08.733+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:08.733+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:26:08.733+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 0 timers
2012-04-29 19:26:08.733+000019858: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:26:08.733+000019858: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=5 timeout=-1
2012-04-29 19:26:08.733+000019869: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/etc/libvirt/uml
2012-04-29 19:26:08.733+000019869: debug : virConnectOpen:1383 : name=uml:///system
2012-04-29 19:26:08.733+000019869: debug : do_open:1182 : name "uml:///system" to URI components:
  scheme uml
  server (null)
  user (null)
  port 0
  path /system

2012-04-29 19:26:08.733+000019869: debug : do_open:1226 : trying driver 0 (Test) ...
2012-04-29 19:26:08.733+000019869: debug : do_open:1232 : driver 0 Test returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1226 : trying driver 1 (Xen) ...
2012-04-29 19:26:08.733+000019869: debug : do_open:1232 : driver 1 Xen returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1226 : trying driver 2 (OPENVZ) ...
2012-04-29 19:26:08.733+000019869: debug : do_open:1232 : driver 2 OPENVZ returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1226 : trying driver 3 (VMWARE) ...
2012-04-29 19:26:08.733+000019869: debug : do_open:1232 : driver 3 VMWARE returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1226 : trying driver 4 (VBOX) ...
2012-04-29 19:26:08.733+000019869: debug : do_open:1232 : driver 4 VBOX returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1226 : trying driver 5 (remote) ...
2012-04-29 19:26:08.733+000019869: debug : do_open:1232 : driver 5 remote returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1226 : trying driver 6 (QEMU) ...
2012-04-29 19:26:08.733+000019869: debug : do_open:1232 : driver 6 QEMU returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1226 : trying driver 7 (LXC) ...
2012-04-29 19:26:08.733+000019869: debug : do_open:1232 : driver 7 LXC returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1226 : trying driver 8 (UML) ...
2012-04-29 19:26:08.733+000019869: debug : do_open:1232 : driver 8 UML returned SUCCESS
2012-04-29 19:26:08.733+000019869: debug : do_open:1254 : network driver 0 Test returned DECLINED
2012-04-29 19:26:08.733+000019869: debug : do_open:1254 : network driver 1 VBOX 2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 3 timers
2012-04-29 19:26:12.054+000019858: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335727577054
 6:08.738+000019869: debug : do_open:1285 : storage driver 0 Test returned DECLINED
2012-04-29 19:26:08.738+000019869: debug : do_open:1285 : storage driver 1 VBOX returned DECLINED
2012-04-29 19:26:08.738+000019869: debug : do_open:1285 : storage driver 2 remote returned DECLINED
2012-04-29 19:26:08.738+000019869: debug : do_open:1285 : storage driver 3 storage returned SUCCESS
2012-04-29 19:26:08.738+000019869: debug : do_open:1301 : node driver 0 Test returned DECLINED
2012-04-29 19:26:08.738+000019869: debug : do_open:1301 : node driver 1 remote returned DECLINED
2012-04-29 19:26:08.738+000019869: debug : do_open:1301 : node driver 2 udevDeviceMonitor returned SUCCESS
2012-04-29 19:26:08.738+000019869: debug : do_open:1317 : secret driver 0 Test returned DECLINED
2012-04-29 19:26:08.738+000019869: debug : do_open:1317 : secret driver 1 remote returned DECLINED
2012-04-29 19:26:08.738+000019869: debug : do_open:1317 : secret driver 2 secret returned SUCCESS
2012-04-29 19:26:08.738+000019869: debug : do_open:1333 : nwfilter driver 0 Test returned DECLINED
2012-04-29 19:26:08.738+000019869: debug : do_open:1333 : nwfilter driver 1 remote returned DECLINED
2012-04-29 19:26:08.738+000019869: debug : do_open:1333 : nwfilter driver 2 nwfilter returned SUCCESS
2012-04-29 19:26:08.738+000019869: debug : virConnectClose:1496 : conn=0x7fc9a80c28b0
2012-04-29 19:26:08.738+000019869: debug : virUnrefConnect:145 : unref connection 0x7fc9a80c28b0 1
2012-04-29 19:26:08.738+000019869: debug : virReleaseConnect:94 : release connection 0x7fc9a80c28b0
2012-04-29 19:26:08.738+000019869: debug : umlProcessAutoDestroyRun:701 : conn=0x7fc9a80c28b0
2012-04-29 19:26:08.738+000019869: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=3 events=1
2012-04-29 19:26:08.738+000019869: debug : virEventPollInterruptLocked:706 : Interrupting
2012-04-29 19:26:08.738+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:08.738+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 0
2012-04-29 19:26:08.738+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 5
2012-04-29 19:26:08.738+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:08.738+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2012-04-29 19:26:08.738+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:08.738+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:08.738+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:08.738+000019858: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:26:08.738+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:26:08.738+000019858: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:26:08.738+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 7
2012-04-29 19:26:08.739+000019869: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=4 events=1
2012-04-29 19:26:08.739+000019869: debug : virEventPollInterruptLocked:702 : Skip interrupt, 0 -999585728
2012-04-29 19:26:08.739+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:08.739+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:26:08.739+000019858: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:26:08.739+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 7
2012-04-29 19:26:08.739+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:08.739+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:08.739+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:26:08.739+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:26:08.739+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:26:08.739+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:08.739+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:26:08.739+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 0 timers
2012-04-29 19:26:08.739+000019858: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:26:08.739+000019858: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=7 timeout=-1
2012-04-29 19:26:08.739+000019869: debug : virNetServerFree:790 : srv=0x2732040 refs=2
2012-04-29 19:26:12.052+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 0
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 7
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=3 events=1
2012-04-29 19:26:12.052+000019858: debug : virNetSocketNew:119 : localAddr=0x7fff77fd1780 remoteAddr=0x7fff77fd1810 fd=15 errfd=-1 pid=0
2012-04-29 19:26:12.052+000019858: debug : virNetSocketNew:179 : RPC_SOCKET_NEW: sock=0x273b490 refs=1 fd=15 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
2012-04-29 19:26:12.052+000019858: debug : virNetServerClientNew:346 : sock=0x273b490 auth=2 tls=(nil)
2012-04-29 19:26:12.052+000019858: debug : virEventPollAddTimeout:225 : Used 0 timeout slots, adding at least 10 more
2012-04-29 19:26:12.052+000019858: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -999585728
2012-04-29 19:26:12.052+000019858: debug : virEventPollAddTimeout:248 : EVENT_POLL_ADD_TIMEOUT: timer=1 frequency=-1 cb=0x7fc9c2013ad0 opaque=0x273b650 ff=(nil)
2012-04-29 19:26:12.052+000019858: debug : virNetMessageNew:48 : msg=0x7fc9c4634010 tracked=1
2012-04-29 19:26:12.052+000019858: debug : virNetServerClientNew:380 : RPC_SERVER_CLIENT_NEW: client=0x273b650 refs=1 sock=0x273b490
2012-04-29 19:26:12.052+000019858: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fc9c4634010 tx=(nil)
2012-04-29 19:26:12.052+000019858: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:26:12.052+000019858: debug : virNetServerClientRegisterEvent:187 : Registering client event callback 1
2012-04-29 19:26:12.052+000019858: debug : virNetSocketRef:709 : RPC_SOCKET_REF: sock=0x273b490 refs=2
2012-04-29 19:26:12.052+000019858: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -999585728
2012-04-29 19:26:12.052+000019858: debug : virEventPollAddHandle:141 : EVENT_POLL_ADD_HANDLE: watch=8 fd=15 events=1 cb=0x7fc9c201c2e0 opaque=0x273b490 ff=0x7fc9c201ca10
2012-04-29 19:26:12.052+000019858: debug : virNetServerClientRef:397 : RPC_SERVER_CLIENT_REF: client=0x273b650 refs=3
2012-04-29 19:26:12.052+000019858: debug : virKeepAliveNew:244 : client=0x273b650, interval=5, count=5
2012-04-29 19:26:12.052+000019858: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -999585728
2012-04-29 19:26:12.052+000019858: debug : virEventPollAddTimeout:248 : EVENT_POLL_ADD_TIMEOUT: timer=2 frequency=-1 cb=0x7fc9c2018370 opaque=0x2738490 ff=0x7fc9c2018590
2012-04-29 19:26:12.052+000019858: debug : virKeepAliveNew:277 : RPC_KEEPALIVE_NEW: ka=0x2738490 client=0x273b650 refs=2
2012-04-29 19:26:12.052+000019858: debug : virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x273b650 refs=4
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:26:12.052+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:26:12.052+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.052+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:12.052+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:26:12.052+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 2 timers
2012-04-29 19:26:12.052+000019858: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:26:12.052+000019858: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=-1
2012-04-29 19:26:12.052+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 2
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:26:12.052+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
2012-04-29 19:26:12.052+000019858: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
2012-04-29 19:26:12.052+000019858: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fc9c4634010 tx=(nil)
2012-04-29 19:26:12.052+000019858: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:26:12.052+000019858: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:26:12.052+000019858: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -999585728
2012-04-29 19:26:12.052+000019858: debug : virNetServerClientDispatchRead:886 : RPC_SERVER_CLIENT_MSG_RX: client=0x273b650 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
2012-04-29 19:26:12.052+000019858: debug : virKeepAliveCheckMessage:408 : ka=0x2738490, client=0x273b650, msg=0x7fc9c4634010
2012-04-29 19:26:12.052+000019858: debug : virNetServerDispatchNewMessage:199 : server=0x2732040 client=0x273b650 message=0x7fc9c4634010
2012-04-29 19:26:12.052+000019858: debug : virNetServerProgramRef:87 : prog=0x273de90 refs=3
2012-04-29 19:26:12.052+000019858: debug : virNetMessageNew:48 : msg=0x7fc9c45f3010 tracked=1
2012-04-29 19:26:12.052+000019858: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fc9c45f3010 tx=(nil)
2012-04-29 19:26:12.052+000019858: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:26:12.052+000019858: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:26:12.052+000019858: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -999585728
2012-04-29 19:26:12.052+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:26:12.052+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.052+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:12.052+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:26:12.052+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=1 d=0
2012-04-29 19:26:12.052+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 2 timers
2012-04-29 19:26:12.052+000019858: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:26:12.052+000019858: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=-1
2012-04-29 19:26:12.053+000019859: debug : virNetServerHandleJob:138 : server=0x2732040 client=0x273b650 message=0x7fc9c4634010 prog=0x273de90
2012-04-29 19:26:12.053+000019859: debug : virNetServerProgramDispatch:269 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
2012-04-29 19:26:12.053+000019859: debug : remoteDispatchAuthListHelper:18 : server=0x2732040 client=0x273b650 msg=0x7fc9c4634010 rerr=0x7fc9b47cac50 args=0x7fc9a80c2850 ret=0x7fc9a80c26f0
2012-04-29 19:26:12.053+000019859: info : remoteDispatchAuthList:2139 : Bypass polkit auth for privileged client pid:19915,uid:0
2012-04-29 19:26:12.053+000019859: debug : virNetMessageEncodePayload:351 : Encode length as 36
2012-04-29 19:26:12.053+000019859: debug : virNetServerClientSendMessage:1106 : msg=0x7fc9c4634010 proc=66 len=36 offset=0
2012-04-29 19:26:12.053+000019859: debug : virNetServerClientSendMessage:1116 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x273b650 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
2012-04-29 19:26:12.053+000019859: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fc9c45f3010 tx=0x7fc9c4634010
2012-04-29 19:26:12.053+000019859: debug : virNetServerClientCalculateHandleMode:167 : mode=3
2012-04-29 19:26:12.053+000019859: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=3
2012-04-29 19:26:12.053+000019859: debug : virEventPollInterruptLocked:706 : Interrupting
2012-04-29 19:26:12.053+000019859: debug : virNetServerProgramFree:527 : prog=0x273de90 refs=3
2012-04-29 19:26:12.053+000019859: debug : virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x273b650 refs=4
2012-04-29 19:26:12.053+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 2
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:26:12.053+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:26:12.053+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.053+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:12.053+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:26:12.053+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=5 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 2 timers
2012-04-29 19:26:12.053+000019858: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:26:12.053+000019858: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=-1
2012-04-29 19:26:12.053+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 2
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
2012-04-29 19:26:12.053+000019858: debug : virNetMessageFree:75 : msg=0x7fc9c4634010 nfds=0 cb=(nil)
2012-04-29 19:26:12.053+000019858: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fc9c45f3010 tx=(nil)
2012-04-29 19:26:12.053+000019858: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:26:12.053+000019858: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:26:12.053+000019858: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -999585728
2012-04-29 19:26:12.053+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:26:12.053+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.053+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:12.053+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:26:12.053+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 2 timers
2012-04-29 19:26:12.053+000019858: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:26:12.053+000019858: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=-1
2012-04-29 19:26:12.053+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 2
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:26:12.053+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
2012-04-29 19:26:12.053+000019858: debug : virNetMessageDecodeLength:149 : Got length, now need 32 total (28 more)
2012-04-29 19:26:12.053+000019858: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7fc9c45f3010 tx=(nil)
2012-04-29 19:26:12.053+000019858: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:26:12.053+000019858: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:26:12.053+000019858: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -999585728
2012-04-29 19:26:12.053+000019858: debug : virNetServerClientDispatchRead:886 : RPC_SERVER_CLIENT_MSG_RX: client=0x273b650 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
2012-04-29 19:26:12.053+000019858: debug : virKeepAliveCheckMessage:408 : ka=0x2738490, client=0x273b650, msg=0x7fc9c45f3010
2012-04-29 19:26:12.053+000019858: debug : virNetServerDispatchNewMessage:199 : server=0x2732040 client=0x273b650 message=0x7fc9c45f3010
2012-04-29 19:26:12.053+000019858: debug : virNetServerProgramRef:87 : prog=0x273de90 refs=3
2012-04-29 19:26:12.053+000019860: debug : virNetServerHandleJob:138 : server=0x2732040 client=0x273b650 message=0x7fc9c45f3010 prog=0x273de90
2012-04-29 19:26:12.053+000019858: debug : virNetMessageNew:48 : msg=0x273e0a0 tracked=1
2012-04-29 19:26:12.053+000019860: debug : virNetServerProgramDispatch:269 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=60
2012-04-29 19:26:12.053+000019858: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x273e0a0 tx=(nil)
2012-04-29 19:26:12.053+000019858: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:26:12.053+000019858: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:26:12.053+000019858: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -999585728
2012-04-29 19:26:12.053+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:26:12.053+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.053+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:12.053+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:26:12.053+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:12.053+000019860: debug : remoteDispatchSupportsFeatureHelper:12695 : server=0x2732040 client=0x273b650 msg=0x7fc9c45f3010 rerr=0x7fc9b3fc9c50 args=0x7fc9a0000970 ret=0x7fc9a00008c0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:26:12.053+000019860: debug : virKeepAliveStart:353 : RPC_KEEPALIVE_START: ka=0x2738490 client=0x273b650 interval=0 count=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=1 d=0
2012-04-29 19:26:12.053+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 2 timers
2012-04-29 19:26:12.053+000019858: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:26:12.053+000019858: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=-1
2012-04-29 19:26:12.053+000019860: debug : virEventPollInterruptLocked:706 : Interrupting
2012-04-29 19:26:12.053+000019860: debug : virEventPollAddTimeout:248 : EVENT_POLL_ADD_TIMEOUT: timer=3 frequency=5000 cb=0x7fc9c2018190 opaque=0x2738490 ff=0x7fc9c2018590
2012-04-29 19:26:12.054+000019860: debug : virNetMessageEncodePayload:351 : Encode length as 32
2012-04-29 19:26:12.054+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:12.054+000019860: debug : virNetServerClientSendMessage:1106 : msg=0x7fc9c45f3010 proc=60 len=32 offset=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 3
2012-04-29 19:26:12.054+000019860: debug : virNetServerClientSendMessage:1116 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x273b650 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:12.054+000019860: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x273e0a0 tx=0x7fc9c45f3010
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2012-04-29 19:26:12.054+000019860: debug : virNetServerClientCalculateHandleMode:167 : mode=3
2012-04-29 19:26:12.054+000019860: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=3
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:26:12.054+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 3
2012-04-29 19:26:12.054+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.054+000019860: debug : virEventPollInterruptLocked:702 : Skip interrupt, 0 -999585728
2012-04-29 19:26:12.054+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:12.054+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 3
2012-04-29 19:26:12.054+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.054+000019860: debug : virNetServerProgramFree:527 : prog=0x273de90 refs=3
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:12.054+000019860: debug : virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x273b650 refs=4
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=5 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 3 timers
2012-04-29 19:26:12.054+000019858: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335727577053
2012-04-29 19:26:12.054+000019858: debug : virEventPollCalculateTimeout:351 : Timeout at 1335727577053 due in 4999 ms
2012-04-29 19:26:12.054+000019858: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=4999
2012-04-29 19:26:12.054+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 3
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
2012-04-29 19:26:12.054+000019858: debug : virNetMessageFree:75 : msg=0x7fc9c45f3010 nfds=0 cb=(nil)
2012-04-29 19:26:12.054+000019858: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x273e0a0 tx=(nil)
2012-04-29 19:26:12.054+000019858: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:26:12.054+000019858: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:26:12.054+000019858: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -999585728
2012-04-29 19:26:12.054+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 3
2012-04-29 19:26:12.054+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.054+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:12.054+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 3
2012-04-29 19:26:12.054+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 3 timers
2012-04-29 19:26:12.054+000019858: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335727577053
2012-04-29 19:26:12.054+000019858: debug : virEventPollCalculateTimeout:351 : Timeout at 1335727577053 due in 4999 ms
2012-04-29 19:26:12.054+000019858: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=4999
2012-04-29 19:26:12.054+000019858: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchTimeouts:415 : Dispatch 3
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:26:12.054+000019858: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
2012-04-29 19:26:12.054+000019858: debug : virNetMessageDecodeLength:149 : Got length, now need 56 total (52 more)
2012-04-29 19:26:12.054+000019858: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x273e0a0 tx=(nil)
2012-04-29 19:26:12.054+000019858: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:26:12.054+000019858: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:26:12.054+000019858: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -999585728
2012-04-29 19:26:12.054+000019858: debug : virNetServerClientDispatchRead:886 : RPC_SERVER_CLIENT_MSG_RX: client=0x273b650 len=56 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
2012-04-29 19:26:12.054+000019858: debug : virKeepAliveCheckMessage:408 : ka=0x2738490, client=0x273b650, msg=0x273e0a0
2012-04-29 19:26:12.054+000019858: debug : virEventPollUpdateTimeout:259 : EVENT_POLL_UPDATE_TIMEOUT: timer=3 frequency=5000
2012-04-29 19:26:12.054+000019858: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -999585728
2012-04-29 19:26:12.054+000019858: debug : virNetServerDispatchNewMessage:199 : server=0x2732040 client=0x273b650 message=0x273e0a0
2012-04-29 19:26:12.054+000019858: debug : virNetServerProgramRef:87 : prog=0x273de90 refs=3
2012-04-29 19:26:12.054+000019866: debug : virNetServerHandleJob:138 : server=0x2732040 client=0x273b650 message=0x273e0a0 prog=0x273de90
2012-04-29 19:26:12.054+000019866: debug : virNetServerProgramDispatch:269 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=1
2012-04-29 19:26:12.054+000019858: debug : virNetMessageNew:48 : msg=0x277e110 tracked=1
2012-04-29 19:26:12.054+000019858: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x277e110 tx=(nil)
2012-04-29 19:26:12.054+000019858: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:26:12.054+000019858: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:26:12.054+000019858: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -999585728
2012-04-29 19:26:12.054+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 3
2012-04-29 19:26:12.054+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.054+000019858: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:26:12.054+000019858: debug : virEventPollCleanupTimeouts:506 : Cleanup 3
2012-04-29 19:26:12.054+000019858: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:26:12.054+000019866: debug : remoteDispatchOpenHelper:10494 : server=0x2732040 client=0x273b650 msg=0x273e0a0 rerr=0x7fc9b0fc3c50 args=0x7fc9a4000970 ret=0x7fc9a40008c0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:26:12.054+000019858: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0


     ====== end of log =====

