2012-04-29 19:25:01.061+0000: 19753: info : libvirt version: 0.9.11
2012-04-29 19:25:01.061+0000: 19753: info : virNetlinkEventServiceStart:336 : starting netlink event service
2012-04-29 19:25:01.067+0000: 19764: info : networkReloadIptablesRules:1512 : Reloading iptables rules
2012-04-29 19:25:01.337+0000: 19764: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'cpu'
2012-04-29 19:25:01.337+0000: 19764: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'cpuacct'
2012-04-29 19:25:01.337+0000: 19764: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'cpuset'
2012-04-29 19:25:01.337+0000: 19764: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'memory'
2012-04-29 19:25:01.337+0000: 19764: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'devices'
2012-04-29 19:25:01.337+0000: 19764: info : qemudLoadDriverConfig:332 : Configured cgroup controller 'blkio'
2012-04-29 19:25:01.676+0000: 19764: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/var/run/libvirt/qemu
2012-04-29 19:25:01.682+0000: 19764: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/etc/libvirt/qemu
2012-04-29 19:25:01.777+0000: 19764: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/var/run/libvirt/lxc
2012-04-29 19:25:01.777+0000: 19764: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/etc/libvirt/lxc
2012-04-29 19:25:01.796+0000: 19764: info : umlStartup:479 : Adding inotify watch on /usr/local/var/run/libvirt/uml-guest
2012-04-29 19:25:01.797+0000: 19764: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/etc/libvirt/uml
2012-04-29 19:25:04.698+0000: 19754: info : remoteDispatchAuthList:2139 : Bypass polkit auth for privileged client pid:19814,uid:0
Caught Segmentation violation dumping internal log buffer:


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

 2012-04-29 19:25:01.728+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:25:01.728+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:01.728+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:25:01.728+000019753: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:25:01.728+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:25:01.728+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:01.728+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:01.728+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=0 d=0
2012-04-29 19:25:01.728+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=0 d=0
2012-04-29 19:25:01.728+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:01.728+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:01.728+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 0 timers
2012-04-29 19:25:01.728+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:25:01.728+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=4 timeout=-1
2012-04-29 19:25:01.728+000019753: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:25:01.728+000019753: debug : virEventPollDispatchTimeouts:415 : Dispatch 0
2012-04-29 19:25:01.728+000019753: debug : virEventPollDispatchHandles:460 : Dispatch 4
2012-04-29 19:25:01.728+000019753: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:25:01.728+000019753: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:25:01.728+000019753: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:25:01.728+000019753: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:25:01.728+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
2012-04-29 19:25:01.728+000019753: debug : udevEventHandleCallback:1467 : udev action: 'add'
2012-04-29 19:25:01.728+000019753: debug : udevGetDeviceProperty:120 : udev reports device 'tx-0' does not have property 'DRIVER'
2012-04-29 19:25:01.728+000019753: debug : udevGetDeviceType:1095 : Found device type '(null)' for device 'tx-0'
2012-04-29 19:25:01.728+000019753: debug : udevGetDeviceProperty:120 : udev reports device 'tx-0' does not have property 'PCI_CLASS'
2012-04-29 19:25:01.728+000019753: debug : udevGetDeviceProperty:120 : udev reports device 'tx-0' does not have property 'INTERFACE'
2012-04-29 19:25:01.728+000019753: debug : udevGetDeviceType:1152 : Could not determine device type for device with sysfs path 'tx-0'
2012-04-29 19:25:01.728+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:25:01.728+000019753: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:25:01.728+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:25:01.728+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:01.728+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:25:01.728+000019753: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:25:01.728+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:25:01.728+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:01.728+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:01.728+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=0 d=0
2012-04-29 19:25:01.728+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=0 d=0
2012-04-29 19:25:01.728+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:01.728+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:01.728+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 0 timers
2012-04-29 19:25:01.728+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:25:01.728+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=4 timeout=-1
2012-04-29 19:25:01.769+000019753: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchTimeouts:415 : Dispatch 0
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchHandles:460 : Dispatch 4
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
2012-04-29 19:25:01.769+000019753: debug : udevEventHandleCallback:1467 : udev action: 'remove'
2012-04-29 19:25:01.769+000019753: 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:25:01.769+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:25:01.769+000019753: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:25:01.769+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:25:01.769+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:01.769+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:25:01.769+000019753: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:25:01.769+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:25:01.769+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:01.769+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:01.769+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=0 d=0
2012-04-29 19:25:01.769+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=0 d=0
2012-04-29 19:25:01.769+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:01.769+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:01.769+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 0 timers
2012-04-29 19:25:01.769+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:25:01.769+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=4 timeout=-1
2012-04-29 19:25:01.769+000019753: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchTimeouts:415 : Dispatch 0
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchHandles:460 : Dispatch 4
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:25:01.769+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
2012-04-29 19:25:01.769+000019753: debug : udevEventHandleCallback:1467 : udev action: 'remove'
2012-04-29 19:25:01.769+000019753: 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:25:01.769+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:25:01.769+000019753: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:25:01.769+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:25:01.769+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:01.769+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:25:01.769+000019753: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:25:01.769+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 6
2012-04-29 19:25:01.769+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:01.769+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:01.769+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=0 d=0
2012-04-29 19:25:01.769+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=0 d=0
2012-04-29 19:25:01.769+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:01.769+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:01.769+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 0 timers
2012-04-29 19:25:01.769+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:25:01.769+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=4 timeout=-1
2012-04-29 19:25:01.775+000019764: debug : lxcContainerAvailable:1526 : Mounted all filesystems
2012-04-29 19:25:01.775+000019764: debug : virCgroupNew:603 : New group /
2012-04-29 19:25:01.775+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu in 
2012-04-29 19:25:01.775+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpuacct in 
2012-04-29 19:25:01.775+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in 
2012-04-29 19:25:01.775+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in 
2012-04-29 19:25:01.775+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in 
2012-04-29 19:25:01.775+000019764: debug : virCgroupNew:603 : New group /libvirt
2012-04-29 19:25:01.776+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu in 
2012-04-29 19:25:01.776+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpuacct in 
2012-04-29 19:25:01.776+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in 
2012-04-29 19:25:01.776+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in 
2012-04-29 19:25:01.776+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in 
2012-04-29 19:25:01.776+000019764: debug : virCgroupMakeGroup:524 : Make group /libvirt
2012-04-29 19:25:01.776+000019764: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/cpu/libvirt/
2012-04-29 19:25:01.776+000019764: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/cpuacct/libvirt/
2012-04-29 19:25:01.776+000019764: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/memory/libvirt/
2012-04-29 19:25:01.776+000019764: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/devices/libvirt/
2012-04-29 19:25:01.776+000019764: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/freezer/libvirt/
2012-04-29 19:25:01.776+000019764: debug : virCgroupNew:603 : New group /libvirt/lxc
2012-04-29 19:25:01.776+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu in 
2012-04-29 19:25:01.776+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpuacct in 
2012-04-29 19:25:01.776+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in 
2012-04-29 19:25:01.776+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in 
2012-04-29 19:25:01.776+000019764: debug : virCgroupDetect:262 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in 
2012-04-29 19:25:01.776+000019764: debug : virCgroupMakeGroup:524 : Make group /libvirt/lxc
2012-04-29 19:25:01.776+000019764: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/cpu/libvirt/lxc/
2012-04-29 19:25:01.776+000019764: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/cpuacct/libvirt/lxc/
2012-04-29 19:25:01.776+000019764: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/memory/libvirt/lxc/
2012-04-29 19:25:01.776+000019764: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/devices/libvirt/lxc/
2012-04-29 19:25:01.776+000019764: debug : virCgroupMakeGroup:546 : Make controller /sys/fs/cgroup/freezer/libvirt/lxc/
2012-04-29 19:25:01.776+000019764: debug : virSecurityDriverLookup:45 : name=(null)
2012-04-29 19:25:01.777+000019764: debug : virSecurityDriverLookup:61 : Not enabled name=selinux
2012-04-29 19:25:01.777+000019764: debug : virSecurityDriverLookup:61 : Not enabled name=apparmor
2012-04-29 19:25:01.777+000019764: debug : virSecurityDriverLookup:56 : Probed name=none
2012-04-29 19:25:01.777+000019764: debug : lxcCapsInit:144 : Initialized caps for security driver "none" with DOI "0"
2012-04-29 19:25:01.777+000019764: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/var/run/libvirt/lxc
2012-04-29 19:25:01.777+000019764: info : virDomainLoadAllConfigs:13005 : Scanning for configs in /usr/local/etc/libvirt/lxc
2012-04-29 19:25:01.777+000019764: debug : virConnectOpen:1383 : name=lxc:///
2012-04-29 19:25:01.777+000019764: debug : do_open:1182 : name "lxc:///" to URI components:
  scheme lxc
  server (null)
  user (null)
  port 0
  path /

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

2012-04-29 19:25:01.797+000019764: debug : do_open:1226 : trying driver 0 (Test) ...
2012-04-29 19:25:01.797+000019764: debug : do_open:1232 : driver 0 Test returned DECLINED
2012-04-29 19:25:01.797+000019764: debug : do_open:1226 : trying driver 1 (Xen) ...
2012-04-29 19:25:01.797+000019764: debug : do_open:1232 : driver 1 Xen returned DECLINED
2012-04-29 19:25:01.797+000019764: debug : do_open:1226 : trying driver 2 (OPENVZ) ...
2012-04-29 19:25:01.797+000019764: debug : do_open:1232 : driver 2 OPENVZ returned DECLINED
2012-04-29 19:25:01.797+000019764: debug : do_open:1226 : trying driver 3 (VMWARE) ...
2012-04-29 19:25:01.797+000019764: debug : do_open:1232 : driver 3 VMWARE returned DECLINED
2012-04-29 19:25:01.797+000019764: debug : do_open:1226 : trying driver 4 (VBOX) ...
2012-04-29 19:25:01.797+000019764: debug : do_open:1232 : driver 4 VBOX returned DECLINED
2012-04-29 19:25:01.797+000019764: debug : do_open:1226 : trying driver 5 (remote) ...
2012-04-29 19:25:01.797+000019764: debug : do_open:1232 : driver 5 remote returned DECLINED
2012-04-29 19:25:01.797+000019764: debug : do_open:1226 : trying driver 6 (QEMU) ...
2012-04-29 19:25:01.797+000019764: debug : do_open:1232 : driver 6 QEMU returned DECLINED
2012-04-29 19:25:01.797+000019764: debug : do_open:1226 : trying driver 7 (LXC) ...
2012-04-29 19:25:01.797+000019764: debug : do_open:1232 : driver 7 LXC returned DECLINED
2012-04-29 19:25:01.797+000019764: debug : do_open:1226 : trying driver 8 (UML) ...
2012-04-29 19:25:01.797+000019764: debug : do_open:1232 : driver 8 UML returned SUCCESS
2012-04-29 19:25:01.797+000019764: debug : do_open:1254 : network driver 0 Test returned DECLINED
2012-04-29 19:25:01.797+000019764: debug : do_open:1254 : network driver 1 VBOX returned DECLINED
2012-04-29 19:25:01.797+000019764: debug : do_open:1254 : network driver 2 remote returned DECLINED
2012-04-29 19:25:01.797+000019764: debug : do_open:1254 : network driver 3 Network returned SUCCESS
2012-04-29 19:25:01.797+000019764: debug : do_open:1269 : interface driver 0 Test returned DECLINED
2012-04-29 19:25:01.797+000019764: debug : do_open:1269 : interface driver 1 remote returned DECLINED
2012-04-29 19:25:01.804+000019764: debug : do_open:1269 : interface driver 2 Interface returned ERROR
2012-04-29 19:25:01.804+000019764: debug : do_open:1285 : storage driver 0 Test returned DECLINED
2012-04-29 19:25:01.804+000019764: debug : do_open:1285 : storage driver 1 VBOX returned DECLINED
2012-04-29 19:25:01.804+000019764: debug : do_open:1285 : storage driver 2 remote returned DECLINED
2012-04-29 19:25:01.804+000019764: debug : do_open:1285 : storage driver 3 storage returned SUCCESS
2012-04-29 19:25:01.804+000019764: debug : do_open:1301 : node driver 0 Test returned DECLINED
2012-04-29 19:25:01.804+000019764: debug : do_open:1301 : node driver 1 remote returned DECLINED
2012-04-29 19:25:01.804+000019764: debug : do_open:1301 : node driver 2 udevDeviceMonitor returned SUCCESS
2012-04-29 19:25:01.804+000019764: debug : do_open:1317 : secret driver 0 Test returned DECLINED
2012-04-29 19:25:01.804+000019764: debug : do_open:1317 : secret driver 1 remote returned DECLINED
2012-04-29 19:25:01.804+000019764: debug : do_open:1317 : secret driver 2 secret returned SUCCESS
2012-04-29 19:25:01.804+000019764: debug : do_open:1333 : nwfilter driver 0 Test returned DECLINED
2012-04-29 19:25:01.804+000019764: debug : do_open:1333 : nwfilter driver 1 remote returned DECLINED
2012-04-29 19:25:01.804+000019764: debug : do_open:1333 : nwfilter driver 2 nwfilter returned SUCCESS
2012-04-29 19:25:01.804+000019764: debug : virConnectClose:1496 : conn=0x7f7a140bfeb0
2012-04-29 19:25:01.804+000019764: debug : virUnrefConnect:145 : unref connection 0x7f7a140bfeb0 1
2012-04-29 19:25:01.804+000019764: debug : virReleaseConnect:94 : release connection 0x7f7a140bfeb0
2012-04-29 19:25:01.804+000019764: debug : umlProcessAutoDestroyRun:701 : conn=0x7f7a140bfeb0
2012-04-29 19:25:01.804+000019764: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=3 events=1
2012-04-29 19:25:01.804+000019764: debug : virEventPollInterruptLocked:706 : Interrupting
2012-04-29 19:25:01.804+000019764: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=4 events=1
2012-04-29 19:25:01.804+000019764: debug : virEventPollInterruptLocked:706 : Interrupting
2012-04-29 19:25:01.804+000019764: debug : virNetServerFree:790 : srv=0x1cc2040 refs=2
2012-04-29 19:25:01.804+000019753: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchTimeouts:415 : Dispatch 0
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:460 : Dispatch 5
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:25:01.804+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:25:01.804+000019753: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:25:01.804+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 7
2012-04-29 19:25:01.804+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:01.804+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:25:01.804+000019753: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:25:01.804+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 7
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 0 timers
2012-04-29 19:25:01.804+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:25:01.804+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=7 timeout=-1
2012-04-29 19:25:01.804+000019753: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchTimeouts:415 : Dispatch 0
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:460 : Dispatch 7
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:25:01.804+000019753: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:25:01.804+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:25:01.804+000019753: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:25:01.804+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 7
2012-04-29 19:25:01.804+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:01.804+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2012-04-29 19:25:01.804+000019753: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2012-04-29 19:25:01.804+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 7
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:25:01.804+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 0 timers
2012-04-29 19:25:01.804+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:25:01.804+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=7 timeout=-1
2012-04-29 19:25:04.697+000019753: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:25:04.697+000019753: debug : virEventPollDispatchTimeouts:415 : Dispatch 0
2012-04-29 19:25:04.697+000019753: debug : virEventPollDispatchHandles:460 : Dispatch 7
2012-04-29 19:25:04.697+000019753: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:25:04.697+000019753: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:25:04.697+000019753: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:25:04.697+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=3 events=1
2012-04-29 19:25:04.697+000019753: debug : virNetSocketNew:119 : localAddr=0x7fffecc55cd0 remoteAddr=0x7fffecc55d60 fd=15 errfd=-1 pid=0
2012-04-29 19:25:04.697+000019753: debug : virNetSocketNew:179 : RPC_SOCKET_NEW: sock=0x1ccbab0 refs=1 fd=15 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
2012-04-29 19:25:04.697+000019753: debug : virNetServerClientNew:346 : sock=0x1ccbab0 auth=2 tls=(nil)
2012-04-29 19:25:04.697+000019753: debug : virEventPollAddTimeout:225 : Used 0 timeout slots, adding at least 10 more
2012-04-29 19:25:04.697+000019753: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 814274624
2012-04-29 19:25:04.697+000019753: debug : virEventPollAddTimeout:248 : EVENT_POLL_ADD_TIMEOUT: timer=1 frequency=-1 cb=0x7f7a2e1e8ad0 opaque=0x1ccbc70 ff=(nil)
2012-04-29 19:25:04.697+000019753: debug : virNetMessageNew:48 : msg=0x7f7a307f7010 tracked=1
2012-04-29 19:25:04.697+000019753: debug : virNetServerClientNew:380 : RPC_SERVER_CLIENT_NEW: client=0x1ccbc70 refs=1 sock=0x1ccbab0
2012-04-29 19:25:04.697+000019753: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7f7a307f7010 tx=(nil)
2012-04-29 19:25:04.697+000019753: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:25:04.697+000019753: debug : virNetServerClientRegisterEvent:187 : Registering client event callback 1
2012-04-29 19:25:04.697+000019753: debug : virNetSocketRef:709 : RPC_SOCKET_REF: sock=0x1ccbab0 refs=2
2012-04-29 19:25:04.697+000019753: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 814274624
2012-04-29 19:25:04.697+000019753: debug : virEventPollAddHandle:141 : EVENT_POLL_ADD_HANDLE: watch=8 fd=15 events=1 cb=0x7f7a2e1f12e0 opaque=0x1ccbab0 ff=0x7f7a2e1f1a10
2012-04-29 19:25:04.697+000019753: debug : virNetServerClientRef:397 : RPC_SERVER_CLIENT_REF: client=0x1ccbc70 refs=3
2012-04-29 19:25:04.697+000019753: debug : virKeepAliveNew:244 : client=0x1ccbc70, interval=5, count=5
2012-04-29 19:25:04.697+000019753: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 814274624
2012-04-29 19:25:04.697+000019753: debug : virEventPollAddTimeout:248 : EVENT_POLL_ADD_TIMEOUT: timer=2 frequency=-1 cb=0x7f7a2e1ed370 opaque=0x1cc8490 ff=0x7f7a2e1ed590
2012-04-29 19:25:04.697+000019753: debug : virKeepAliveNew:277 : RPC_KEEPALIVE_NEW: ka=0x1cc8490 client=0x1ccbc70 refs=2
2012-04-29 19:25:04.697+000019753: debug : virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x1ccbc70 refs=4
2012-04-29 19:25:04.697+000019753: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:25:04.697+000019753: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:25:04.697+000019753: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:25:04.697+000019753: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:25:04.697+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:25:04.697+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.697+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:04.697+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:25:04.697+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.697+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:04.697+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:04.697+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:25:04.697+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:25:04.697+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:04.697+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:04.697+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:25:04.697+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=1 d=0
2012-04-29 19:25:04.697+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 2 timers
2012-04-29 19:25:04.697+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:25:04.697+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=-1
2012-04-29 19:25:04.698+000019753: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchTimeouts:415 : Dispatch 2
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
2012-04-29 19:25:04.698+000019753: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
2012-04-29 19:25:04.698+000019753: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7f7a307f7010 tx=(nil)
2012-04-29 19:25:04.698+000019753: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:25:04.698+000019753: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:25:04.698+000019753: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 814274624
2012-04-29 19:25:04.698+000019753: debug : virNetServerClientDispatchRead:886 : RPC_SERVER_CLIENT_MSG_RX: client=0x1ccbc70 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
2012-04-29 19:25:04.698+000019753: debug : virKeepAliveCheckMessage:408 : ka=0x1cc8490, client=0x1ccbc70, msg=0x7f7a307f7010
2012-04-29 19:25:04.698+000019753: debug : virNetServerDispatchNewMessage:199 : server=0x1cc2040 client=0x1ccbc70 message=0x7f7a307f7010
2012-04-29 19:25:04.698+000019753: debug : virNetServerProgramRef:87 : prog=0x1ccde90 refs=3
2012-04-29 19:25:04.698+000019753: debug : virNetMessageNew:48 : msg=0x7f7a307b6010 tracked=1
2012-04-29 19:25:04.698+000019753: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7f7a307b6010 tx=(nil)
2012-04-29 19:25:04.698+000019753: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:25:04.698+000019754: debug : virNetServerHandleJob:138 : server=0x1cc2040 client=0x1ccbc70 message=0x7f7a307f7010 prog=0x1ccde90
2012-04-29 19:25:04.698+000019753: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:25:04.698+000019753: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 814274624
2012-04-29 19:25:04.698+000019754: debug : virNetServerProgramDispatch:269 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
2012-04-29 19:25:04.698+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:25:04.698+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.698+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:04.698+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:25:04.698+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:04.698+000019754: debug : remoteDispatchAuthListHelper:18 : server=0x1cc2040 client=0x1ccbc70 msg=0x7f7a307f7010 rerr=0x7f7a2099fc50 args=0x7f7a140d6ad0 ret=0x7f7a140bf2c0
2012-04-29 19:25:04.698+000019754: info : remoteDispatchAuthList:2139 : Bypass polkit auth for privileged client pid:19814,uid:0
2012-04-29 19:25:04.698+000019754: debug : virNetMessageEncodePayload:351 : Encode length as 36
2012-04-29 19:25:04.698+000019754: debug : virNetServerClientSendMessage:1106 : msg=0x7f7a307f7010 proc=66 len=36 offset=0
2012-04-29 19:25:04.698+000019754: debug : virNetServerClientSendMessage:1116 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1ccbc70 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
2012-04-29 19:25:04.698+000019754: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7f7a307b6010 tx=0x7f7a307f7010
2012-04-29 19:25:04.698+000019754: debug : virNetServerClientCalculateHandleMode:167 : mode=3
2012-04-29 19:25:04.698+000019754: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=3
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 2 timers
2012-04-29 19:25:04.698+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:25:04.698+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=-1
2012-04-29 19:25:04.698+000019754: debug : virEventPollInterruptLocked:706 : Interrupting
2012-04-29 19:25:04.698+000019754: debug : virNetServerProgramFree:527 : prog=0x1ccde90 refs=3
2012-04-29 19:25:04.698+000019754: debug : virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x1ccbc70 refs=4
2012-04-29 19:25:04.698+000019753: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchTimeouts:415 : Dispatch 2
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:25:04.698+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:25:04.698+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.698+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:04.698+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:25:04.698+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=5 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 2 timers
2012-04-29 19:25:04.698+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:25:04.698+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=-1
2012-04-29 19:25:04.698+000019753: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchTimeouts:415 : Dispatch 2
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
2012-04-29 19:25:04.698+000019753: debug : virNetMessageFree:75 : msg=0x7f7a307f7010 nfds=0 cb=(nil)
2012-04-29 19:25:04.698+000019753: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7f7a307b6010 tx=(nil)
2012-04-29 19:25:04.698+000019753: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:25:04.698+000019753: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:25:04.698+000019753: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 814274624
2012-04-29 19:25:04.698+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:25:04.698+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.698+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:04.698+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:25:04.698+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=1 d=0
2012-04-29 19:25:04.698+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 2 timers
2012-04-29 19:25:04.698+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:25:04.698+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=-1
2012-04-29 19:25:04.698+000019753: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchTimeouts:415 : Dispatch 2
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:25:04.698+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
2012-04-29 19:25:04.698+000019753: debug : virNetMessageDecodeLength:149 : Got length, now need 32 total (28 more)
2012-04-29 19:25:04.698+000019753: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x7f7a307b6010 tx=(nil)
2012-04-29 19:25:04.698+000019753: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:25:04.698+000019753: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:25:04.698+000019753: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 814274624
2012-04-29 19:25:04.698+000019753: debug : virNetServerClientDispatchRead:886 : RPC_SERVER_CLIENT_MSG_RX: client=0x1ccbc70 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
2012-04-29 19:25:04.699+000019753: debug : virKeepAliveCheckMessage:408 : ka=0x1cc8490, client=0x1ccbc70, msg=0x7f7a307b6010
2012-04-29 19:25:04.699+000019753: debug : virNetServerDispatchNewMessage:199 : server=0x1cc2040 client=0x1ccbc70 message=0x7f7a307b6010
2012-04-29 19:25:04.699+000019753: debug : virNetServerProgramRef:87 : prog=0x1ccde90 refs=3
2012-04-29 19:25:04.699+000019753: debug : virNetMessageNew:48 : msg=0x1cce0a0 tracked=1
2012-04-29 19:25:04.699+000019753: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x1cce0a0 tx=(nil)
2012-04-29 19:25:04.699+000019753: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:25:04.699+000019753: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:25:04.699+000019753: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 814274624
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.699+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 2
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 2 timers
2012-04-29 19:25:04.699+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-04-29 19:25:04.699+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=-1
2012-04-29 19:25:04.699+000019755: debug : virNetServerHandleJob:138 : server=0x1cc2040 client=0x1ccbc70 message=0x7f7a307b6010 prog=0x1ccde90
2012-04-29 19:25:04.699+000019755: debug : virNetServerProgramDispatch:269 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=60
2012-04-29 19:25:04.699+000019755: debug : remoteDispatchSupportsFeatureHelper:12695 : server=0x1cc2040 client=0x1ccbc70 msg=0x7f7a307b6010 rerr=0x7f7a2019ec50 args=0x7f7a0c000970 ret=0x7f7a0c0008c0
2012-04-29 19:25:04.699+000019755: debug : virKeepAliveStart:353 : RPC_KEEPALIVE_START: ka=0x1cc8490 client=0x1ccbc70 interval=0 count=0
2012-04-29 19:25:04.699+000019755: debug : virEventPollInterruptLocked:706 : Interrupting
2012-04-29 19:25:04.699+000019755: debug : virEventPollAddTimeout:248 : EVENT_POLL_ADD_TIMEOUT: timer=3 frequency=5000 cb=0x7f7a2e1ed190 opaque=0x1cc8490 ff=0x7f7a2e1ed590
2012-04-29 19:25:04.699+000019755: debug : virNetMessageEncodePayload:351 : Encode length as 32
2012-04-29 19:25:04.699+000019755: debug : virNetServerClientSendMessage:1106 : msg=0x7f7a307b6010 proc=60 len=32 offset=0
2012-04-29 19:25:04.699+000019755: debug : virNetServerClientSendMessage:1116 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1ccbc70 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
2012-04-29 19:25:04.699+000019755: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x1cce0a0 tx=0x7f7a307b6010
2012-04-29 19:25:04.699+000019755: debug : virNetServerClientCalculateHandleMode:167 : mode=3
2012-04-29 19:25:04.699+000019755: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=3
2012-04-29 19:25:04.699+000019755: debug : virEventPollInterruptLocked:706 : Interrupting
2012-04-29 19:25:04.699+000019755: debug : virNetServerProgramFree:527 : prog=0x1ccde90 refs=3
2012-04-29 19:25:04.699+000019755: debug : virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x1ccbc70 refs=4
2012-04-29 19:25:04.699+000019753: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchTimeouts:415 : Dispatch 3
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 3
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.699+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 3
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=5 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 3 timers
2012-04-29 19:25:04.699+000019753: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335727509699
2012-04-29 19:25:04.699+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 1335727509699 due in 5000 ms
2012-04-29 19:25:04.699+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=5000
2012-04-29 19:25:04.699+000019753: debug : virEventPollRunOnce:630 : Poll got 2 event(s)
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchTimeouts:415 : Dispatch 3
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
2012-04-29 19:25:04.699+000019753: debug : virNetMessageFree:75 : msg=0x7f7a307b6010 nfds=0 cb=(nil)
2012-04-29 19:25:04.699+000019753: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x1cce0a0 tx=(nil)
2012-04-29 19:25:04.699+000019753: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:25:04.699+000019753: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:25:04.699+000019753: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 814274624
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 3
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.699+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 3
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 3 timers
2012-04-29 19:25:04.699+000019753: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335727509699
2012-04-29 19:25:04.699+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 1335727509699 due in 5000 ms
2012-04-29 19:25:04.699+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=5000
2012-04-29 19:25:04.699+000019753: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchTimeouts:415 : Dispatch 3
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:460 : Dispatch 8
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=0 w=1
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=1 w=2
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=2 w=3
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=3 w=4
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=4 w=5
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=5 w=6
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=6 w=7
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:474 : i=7 w=8
2012-04-29 19:25:04.699+000019753: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
2012-04-29 19:25:04.699+000019753: debug : virNetMessageDecodeLength:149 : Got length, now need 56 total (52 more)
2012-04-29 19:25:04.699+000019753: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x1cce0a0 tx=(nil)
2012-04-29 19:25:04.699+000019753: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:25:04.699+000019753: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:25:04.699+000019753: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 814274624
2012-04-29 19:25:04.699+000019753: debug : virNetServerClientDispatchRead:886 : RPC_SERVER_CLIENT_MSG_RX: client=0x1ccbc70 len=56 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
2012-04-29 19:25:04.699+000019753: debug : virKeepAliveCheckMessage:408 : ka=0x1cc8490, client=0x1ccbc70, msg=0x1cce0a0
2012-04-29 19:25:04.699+000019753: debug : virEventPollUpdateTimeout:259 : EVENT_POLL_UPDATE_TIMEOUT: timer=3 frequency=5000
2012-04-29 19:25:04.699+000019753: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 814274624
2012-04-29 19:25:04.699+000019753: debug : virNetServerDispatchNewMessage:199 : server=0x1cc2040 client=0x1ccbc70 message=0x1cce0a0
2012-04-29 19:25:04.699+000019753: debug : virNetServerProgramRef:87 : prog=0x1ccde90 refs=3
2012-04-29 19:25:04.699+000019753: debug : virNetMessageNew:48 : msg=0x1d0e110 tracked=1
2012-04-29 19:25:04.699+000019753: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x1d0e110 tx=(nil)
2012-04-29 19:25:04.699+000019753: debug : virNetServerClientCalculateHandleMode:167 : mode=1
2012-04-29 19:25:04.699+000019753: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
2012-04-29 19:25:04.699+000019753: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 814274624
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 3
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.699+000019753: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupTimeouts:506 : Cleanup 3
2012-04-29 19:25:04.699+000019753: debug : virEventPollCleanupHandles:554 : Cleanup 8
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=5 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=7 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=9 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=10 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=12 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=13 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=14 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=15 e=1 d=0
2012-04-29 19:25:04.699+000019753: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 3 timers
2012-04-29 19:25:04.699+000019753: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335727509699
2012-04-29 19:25:04.699+000019753: debug : virEventPollCalculateTimeout:351 : Timeout at 1335727509699 due in 5000 ms
2012-04-29 19:25:04.699+000019753: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 timeout=5000
2012-04-29 19:25:04.699+000019756: debug : virNetServerHandleJob:138 : server=0x1cc2040 client=0x1ccbc70 message=0x1cce0a0 prog=0x1ccde90
2012-04-29 19:25:04.699+000019756: debug : virNetServerProgramDispatch:269 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=1
2012-04-29 19:25:04.699+000019756: debug : remoteDispatchOpenHelper:10494 : server=0x1cc2040 client=0x1ccbc70 msg=0x1cce0a0 rerr=0x7f7a1f99dc50 args=0x7f7a10000970 ret=0x7f7a100008c0


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

