thr3ads.net - Xen users - XEN 4.3 and libvirt 1.1 [Jul 2013]

If this information is useful, please help other people find it:
Share via:

Thomas Wagner

2013-Jul-13 12:17 UTC

XEN 4.3 and libvirt 1.1

Hello

I wanted to upgrade to the latest version of xen. But now I cannot manage my
guests with virsh anymore. I get this error:

osiris:/opt # virsh edit test
error: failed to connect to the hypervisor
error: no valid connection
error: Unable to issue hypervisor ioctl 3166208: Function not implemented


syslog:
Jul 13 14:04:09 osiris libvirtd[16137]: libvirt version: 1.1.0
Jul 13 14:04:09 osiris libvirtd[16137]: starting netlink event service with
protocol 0
Jul 13 14:04:09 osiris libvirtd[16137]: starting netlink event service with
protocol 15
Jul 13 14:04:09 osiris libvirtd[16137]: Unable to lookup SELinux process
context: No such file or directory
Jul 13 14:04:09 osiris libvirtd[16137]: dnsmasq version is 2.65,
--bind-dynamic is present, SO_BINDTODEVICE is in use
Jul 13 14:04:09 osiris libvirtd[16137]: Reloading iptables rules
Jul 13 14:04:09 osiris libvirtd[16137]: Refreshing network daemons
Jul 13 14:04:09 osiris libvirtd[16137]: Skipping special dir
''..''
Jul 13 14:04:09 osiris libvirtd[16137]: Skipping special dir
''.''
Jul 13 14:04:09 osiris libvirtd[16137]: internal error Child process
(/sbin/iscsiadm --mode session) unexpected exit status 21
Jul 13 14:04:09 osiris libvirtd[16137]: internal error Child process
(/sbin/iscsiadm --mode session) unexpected exit status 21
Jul 13 14:04:09 osiris libvirtd[16137]:
open("/var/run/libvirt/network/nwfilter.ltmp"): No such file or
directory
Jul 13 14:04:10 osiris libvirtd[16137]: Legacy xen tool stack seems to be in
use, disabling libxenlight driver.
Jul 13 14:04:10 osiris libvirtd[16137]: Configured security driver
"none"
disables default policy to create confined guests
Jul 13 14:04:15 osiris libvirtd[16137]: Scanning for configs in
/var/run/libvirt/qemu
Jul 13 14:04:15 osiris libvirtd[16137]: Command ''systemctl --no-pager
-p Id
show network.service'' returned with exit code: 0
Jul 13 14:04:15 osiris libvirtd[16137]: Scanning for configs in
/etc/libvirt/qemu
Jul 13 14:04:15 osiris libvirtd[16137]: Command ''systemctl --no-pager
-p Id
show network.service'' returned with exit code: 0
Jul 13 14:04:15 osiris libvirtd[16137]: lxcSecurityInit (null)
Jul 13 14:04:15 osiris libvirtd[16137]: Scanning for configs in
/var/run/libvirt/lxc
Jul 13 14:04:15 osiris libvirtd[16137]: Scanning for configs in
/etc/libvirt/lxc
Jul 13 14:04:15 osiris libvirtd[16137]: Command ''systemctl --no-pager
-p Id
show network.service'' returned with exit code: 0
Jul 13 14:04:15 osiris libvirtd[16137]: Adding inotify watch on
/var/run/libvirt/uml-guest
Jul 13 14:04:15 osiris libvirtd[16137]: Scanning for configs in
/etc/libvirt/uml
Jul 13 14:04:15 osiris libvirtd[16137]: Command ''systemctl --no-pager
-p Id
show network.service'' returned with exit code: 0
Jul 13 14:04:38 osiris libvirtd[16137]: Bypass polkit auth for privileged
client pid:16316,uid:0
Jul 13 14:04:39 osiris libvirtd[16137]: Unable to issue hypervisor ioctl
3166208: Permission denied
Jul 13 14:04:39 osiris libvirtd[16137]: last message repeated 6 times
Jul 13 14:04:39 osiris libvirtd[16137]: Unable to issue hypervisor ioctl
3166208: Function not implemented
Jul 13 14:04:39 osiris libvirtd[16137]: Unable to issue hypervisor ioctl
3166208: Function not implemented
Jul 13 14:04:39 osiris libvirtd[16137]: End of file while reading data:
Input/output error


Following packages are installed from the openSUSE 12.3 build service.
osiris:/etc/libvirt # rpm -q -a |grep xen
libvirt-daemon-xen-1.1.0-290.2.x86_64
xen-4.3.0_08-259.2.x86_64
xen-tools-4.3.0_08-259.2.x86_64
libvirt-daemon-driver-xen-1.1.0-290.2.x86_64
xen-libs-4.3.0_08-259.2.x86_64
xen-xend-tools-4.3.0_08-259.2.x86_64

osiris:/etc/libvirt # rpm -q -a |grep libvirt
libvirt-daemon-driver-network-1.1.0-290.2.x86_64
libvirt-1.1.0-290.2.x86_64
libvirt-daemon-driver-vbox-1.1.0-290.2.x86_64
libvirt-daemon-driver-lxc-1.1.0-290.2.x86_64
libvirt-client-1.1.0-290.2.x86_64
libvirt-daemon-xen-1.1.0-290.2.x86_64
libvirt-daemon-driver-libxl-1.1.0-290.2.x86_64
libvirt-daemon-driver-secret-1.1.0-290.2.x86_64
libvirt-daemon-driver-interface-1.1.0-290.2.x86_64
libvirt-daemon-driver-xen-1.1.0-290.2.x86_64
libvirt-daemon-config-nwfilter-1.1.0-290.2.x86_64
libvirt-daemon-driver-nwfilter-1.1.0-290.2.x86_64
libvirt-python-1.1.0-290.2.x86_64
libvirt-daemon-driver-uml-1.1.0-290.2.x86_64
libvirt-daemon-1.1.0-290.2.x86_64
libvirt-daemon-driver-qemu-1.1.0-290.2.x86_64
libvirt-daemon-driver-storage-1.1.0-290.2.x86_64
libvirt-daemon-driver-nodedev-1.1.0-290.2.x86_64



osiris:~ # xm info
host                   : osiris
release                : 3.9.6-4-xen
version                : #1 SMP Sun Jun 16 13:11:50 CEST 2013
machine                : x86_64
nr_cpus                : 4
nr_nodes               : 1
cores_per_socket       : 1
threads_per_core       : 2
cpu_mhz                : 3400
hw_caps                :
bfebfbff:20000800:00000000:00000100:0000649d:00000000:00000000:00000000
virt_caps              :
total_memory           : 7103
free_memory            : 6252
free_cpus              : 0
max_free_memory        : 6508
max_para_memory        : 6504
max_hvm_memory         : 6480
xen_major              : 4
xen_minor              : 3
xen_extra              : .0_08-259.2
xen_caps               : xen-3.0-x86_64 xen-3.0-x86_32p
xen_scheduler          : credit
xen_pagesize           : 4096
platform_params        : virt_start=0xffff800000000000
xen_changeset          : 27204
xen_commandline        : vga=mode-0x318 dom0_mem=max:768M tmem
cc_compiler            : gcc (SUSE Linux) 4.7.2 20130108 [gcc-4_7-branch
revision 195012
cc_compile_by          : abuild
cc_compile_domain      :
cc_compile_date        : Fri Jul 12 15:04:58 UTC 2013
xend_config_format     : 4


Do I do something wrong or are these versions incompatible at the moment?

regards
Thomas


_______________________________________________
Xen-users mailing list
Xen-users@lists.xen.org
http://lists.xen.org/xen-users

Ian Campbell

2013-Jul-16 09:06 UTC

head link

Re: XEN 4.3 and libvirt 1.1

On Sat, 2013-07-13 at 14:17 +0200, Thomas Wagner wrote:> Hello
> 
> I wanted to upgrade to the latest version of xen. But now I cannot
> manage my guests with virsh anymore. I get this error:
[...]> Do I do something wrong or are these versions incompatible at the
> moment?
Did you rebuild libvirt against the newer hypervisor libraries?

It looks like you are using the libvirt xend backend, I''m not sure how
that works but in the libxl case you will need to rebuild and relink the
libvirt libxl plugin against the libxl which matches your hypervisor.

Ian.

Thomas Wagner

2013-Jul-16 18:01 UTC

head link

Re: XEN 4.3 and libvirt 1.1

No, I only use the rpm packages from the OBS "Virtualisation". Maybe
this information is helpful for James Fehlig, who seems to be the maintainer of
this binary build.

Regards Thomas

-----Ursprüngliche Nachricht-----
Von: Ian Campbell [mailto:Ian.Campbell@citrix.com] 
Gesendet: Dienstag, 16. Juli 2013 11:07
An: Thomas Wagner
Cc: xen-users@lists.xen.org
Betreff: Re: [Xen-users] XEN 4.3 and libvirt 1.1

On Sat, 2013-07-13 at 14:17 +0200, Thomas Wagner wrote:> Hello
> 
> I wanted to upgrade to the latest version of xen. But now I cannot 
> manage my guests with virsh anymore. I get this error:
[...]> Do I do something wrong or are these versions incompatible at the 
> moment?
Did you rebuild libvirt against the newer hypervisor libraries?

It looks like you are using the libvirt xend backend, I'm not sure how that
works but in the libxl case you will need to rebuild and relink the libvirt
libxl plugin against the libxl which matches your hypervisor.

Ian.

_______________________________________________
Xen-users mailing list
Xen-users@lists.xen.org
http://lists.xen.org/xen-users

Jim Fehlig

2013-Jul-16 20:14 UTC

head link

Re: XEN 4.3 and libvirt 1.1

Thomas Wagner wrote:> No, I only use the rpm packages from the OBS "Virtualisation".
Maybe this information is helpful for James Fehlig, who seems to be the
maintainer of this binary build.
>   
Well, there is not much information since the error was snipped :). 
What was the error?

Regards,
Jim
>
> Regards Thomas
>
> -----Ursprüngliche Nachricht-----
> Von: Ian Campbell [mailto:Ian.Campbell@citrix.com] 
> Gesendet: Dienstag, 16. Juli 2013 11:07
> An: Thomas Wagner
> Cc: xen-users@lists.xen.org
> Betreff: Re: [Xen-users] XEN 4.3 and libvirt 1.1
>
> On Sat, 2013-07-13 at 14:17 +0200, Thomas Wagner wrote:
>   
>> Hello
>>
>> I wanted to upgrade to the latest version of xen. But now I cannot 
>> manage my guests with virsh anymore. I get this error:
>>     
> [...]
>   
>> Do I do something wrong or are these versions incompatible at the 
>> moment?
>>     
>
> Did you rebuild libvirt against the newer hypervisor libraries?
>
> It looks like you are using the libvirt xend backend, I'm not sure how
that works but in the libxl case you will need to rebuild and relink the libvirt
libxl plugin against the libxl which matches your hypervisor.
>
> Ian.
>
> .
>   
_______________________________________________
Xen-users mailing list
Xen-users@lists.xen.org
http://lists.xen.org/xen-users

Andrew J Younge

2013-Jul-21 21:29 UTC

head link

Re: XEN 4.3 and libvirt 1.1

Xen community,

I too am getting an error with using the libvirt 1.1.0.3 build to use
the libxl driver via src rpm. When I try to create a VM it will give me
the an error and libvirt will crash. Oddly enough, libvirt seems to
proceed far enough to build a full VM profile that I can see is there in
a paused state with xl, and using xl I can unpause the VM and have it
boot properly. I also see the same SELinux error when libvirt starts,
however SELinux is disabled.

Any help or insight in understanding why this seg fault is occurring
when using the libxl driver within libvirt 1.1.0.3 would be very helpful.

Thanks,

Andrew


# cat Centos_63_n-qcow.xml
<domain type='xen' id='4'>
  <name>centos-6.3i-libvirtxen-2</name>
  <uuid>8af5bfd3-51ae-9b22-c67e-3a120187177e</uuid>
  <description></description>
  <memory>14576000</memory>
  <currentMemory>14576000</currentMemory>
  <vcpu cpuset='0-15'>16</vcpu>
  <os>
    <type>hvm</type>
    <loader>/usr/lib/xen/boot/hvmloader</loader>
    <kernel></kernel>
  </os>
  <features>
    <acpi/>
    <apic/>
    <pae/>
  </features>
  <clock offset='utc'>
    <timer name='hpet' present='no'/>
  </clock>
  <on_poweroff>destroy</on_poweroff>
  <on_reboot>restart</on_reboot>
  <on_crash>restart</on_crash>
  <devices>
    <emulator>/usr/lib64/xen/bin/qemu-dm</emulator>
    <disk type='file' device='disk'>
      <driver name='tap2' type='qcow2' />
      <source file='/home/Centos_63_n.qcow2'/>
      <target dev='xvda' bus='xen'/>
    </disk>
    <interface type='bridge'>
      <mac address='00:16:3e:01:01:01'/>
      <source bridge='br0'/>
      <script path='/etc/xen/scripts/vif-bridge'/>
      <target dev='vif4.0'/>
    </interface>
    <serial type='pty'>
      <source path='/dev/pts/4'/>
      <target port='0'/>
    </serial>
    <console type='pty' tty='/dev/pts/4'>
      <source path='/dev/pts/4'/>
      <target type='serial' port='0'/>
    </console>
    <input type='tablet' bus='usb'/>
    <input type='mouse' bus='ps2'/>
    <graphics type='vnc' port='5900' autoport='yes'
keymap='en-us'/>
    <sound model='es1370'/>
  </devices>
</domain>

# xl info
host                   : hp6
release                : 3.4.50-8.el6.centos.alt.x86_64
version                : #1 SMP Fri Jun 21 03:14:22 UTC 2013
machine                : x86_64
nr_cpus                : 32
max_cpu_id             : 63
nr_nodes               : 2
cores_per_socket       : 8
threads_per_core       : 2
cpu_mhz                : 2593
hw_caps                :
bfebfbff:2c000800:00000000:00003f40:17bee3ff:00000000:00000001:00000000
virt_caps              : hvm hvm_directio
total_memory           : 49117
free_memory            : 15112
sharing_freed_memory   : 0
sharing_used_memory    : 0
free_cpus              : 0
xen_major              : 4
xen_minor              : 2
xen_extra              : .2-22.el6
xen_caps               : xen-3.0-x86_64 xen-3.0-x86_32p hvm-3.0-x86_32
hvm-3.0-x86_32p hvm-3.0-x86_64
xen_scheduler          : credit
xen_pagesize           : 4096
platform_params        : virt_start=0xffff800000000000
xen_changeset          : unavailable
xen_commandline        :
cc_compiler            : gcc (GCC) 4.4.7 20120313 (Red Hat 4.4.7-3)
cc_compile_by          : mockbuild
cc_compile_domain      : centos.org
cc_compile_date        : Fri Jun 14 23:54:52 UTC 2013
xend_config_format     : 4

# virsh create Centos_63_n-qcow.xml
error: Failed to create domain from Centos_63_n-qcow.xml
error: End of file while reading data: Input/output error
error: One or more references were leaked after disconnect from the
hypervisor
error: Failed to reconnect to the hypervisor



libxl.log:

xc: debug: hypercall buffer: cache hits:7 misses:1 toobig:0
libxl: debug: libxl_create.c:1192:do_domain_create: ao 0x7fdd84198620:
create: how=(nil) callback=(nil) poller=0x7fdd84198d80
libxl: debug: libxl_device.c:229:libxl__device_disk_set_backend: Disk
vdev=xvda spec.backend=qdisk
libxl: debug: libxl_create.c:694:initiate_domain_create: running bootloader
libxl: debug: libxl_bootloader.c:321:libxl__bootloader_run: not a PV
domain, skipping bootloader
libxl: debug: libxl_event.c:607:libxl__ev_xswatch_deregister: watch
w=0x7fdd84159050: deregister unregistered
libxl: debug: libxl_numa.c:435:libxl__get_numa_candidate: New best NUMA
placement candidate found: nr_nodes=2, nr_cpus=32, nr_vcpus=48,
free_memkb=15110
libxl: detail: libxl_dom.c:193:numa_place_domain: NUMA placement
candidate with 2 nodes, 32 cpus and 15110 KB free selected
xc: detail: elf_parse_binary: phdr: paddr=0x100000 memsz=0x9bb24
xc: detail: elf_parse_binary: memory: 0x100000 -> 0x19bb24
xc: info: VIRTUAL MEMORY ARRANGEMENT:
  Loader:        0000000000100000->000000000019bb24
  TOTAL:         0000000000000000->0000000379200000
  ENTRY ADDRESS: 0000000000100000
xc: info: PHYSICAL MEMORY ALLOCATION:
  4KB PAGES: 0x0000000000000200
  2MB PAGES: 0x00000000000007c8
  1GB PAGES: 0x000000000000000a
xc: detail: elf_load_binary: phdr 0 at 0x140589896593408 ->
0x140589897193884
libxl: debug: libxl_device.c:229:libxl__device_disk_set_backend: Disk
vdev=xvda spec.backend=qdisk
libxl: debug: libxl_dm.c:1148:libxl__spawn_local_dm: Spawning
device-model /usr/lib/xen/bin/qemu-dm with arguments:
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:
/usr/lib/xen/bin/qemu-dm
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   -d
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   1
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   -domain-name
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:
centos-6.3-pci-libvirtxen-2
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   -vnc
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   127.0.0.1:0
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   -vncunused
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   -videoram
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   8
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   -boot
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   c
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   -acpi
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   -vcpus
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   16
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   -vcpu_avail
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   0x01
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   -net
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   none
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   -M
libxl: debug: libxl_dm.c:1150:libxl__spawn_local_dm:   xenfv
libxl: debug: libxl_event.c:558:libxl__ev_xswatch_register: watch
w=0x7fdd84159288 wpath=/local/domain/0/device-model/1/state token=3/0:
register slotnum=3
libxl: debug: libxl_create.c:1205:do_domain_create: ao 0x7fdd84198620:
inprogress: poller=0x7fdd84198d80, flags=i
libxl: debug: libxl_event.c:503:watchfd_callback: watch w=0x7fdd84159288
wpath=/local/domain/0/device-model/1/state token=3/0: event
epath=/local/domain/0/device-model/1/state
libxl: debug: libxl_event.c:503:watchfd_callback: watch w=0x7fdd84159288
wpath=/local/domain/0/device-model/1/state token=3/0: event
epath=/local/domain/0/device-model/1/state
libxl: debug: libxl_event.c:595:libxl__ev_xswatch_deregister: watch
w=0x7fdd84159288 wpath=/local/domain/0/device-model/1/state token=3/0:
deregister slotnum=3
libxl: debug: libxl_event.c:607:libxl__ev_xswatch_deregister: watch
w=0x7fdd84159288: deregister unregistered
libxl: debug: libxl_event.c:558:libxl__ev_xswatch_register: watch
w=0x7fdd84210f78 wpath=/local/domain/0/backend/vif/1/0/state token=3/1:
register slotnum=3
libxl: debug: libxl_event.c:503:watchfd_callback: watch w=0x7fdd84210f78
wpath=/local/domain/0/backend/vif/1/0/state token=3/1: event
epath=/local/domain/0/backend/vif/1/0/state
libxl: debug: libxl_event.c:642:devstate_watch_callback: backend
/local/domain/0/backend/vif/1/0/state wanted state 2 ok
libxl: debug: libxl_event.c:595:libxl__ev_xswatch_deregister: watch
w=0x7fdd84210f78 wpath=/local/domain/0/backend/vif/1/0/state token=3/1:
deregister slotnum=3
libxl: debug: libxl_event.c:607:libxl__ev_xswatch_deregister: watch
w=0x7fdd84210f78: deregister unregistered
libxl: debug: libxl_device.c:917:device_hotplug: calling hotplug script:
/etc/xen/scripts/vif-bridge online
libxl: debug: libxl_event.c:472:watchfd_callback: watch
epath=/local/domain/0/backend/vif/1/0/state token=3/1: empty slot
libxl: debug: libxl_event.c:1736:libxl__ao_progress_report: ao
0x7fdd84198620: progress report: ignored
libxl: debug: libxl_event.c:1568:libxl__ao_complete: ao 0x7fdd84198620:
complete, rc=0
libxl: debug: libxl_event.c:1540:libxl__ao__destroy: ao 0x7fdd84198620:
destroy
libxl: debug: libxl_event.c:558:libxl__ev_xswatch_register: watch
w=0x7fdd84219af0 wpath=@releaseDomain token=3/2: register slotnum=3



libvirt.log:

2013-07-21 20:25:31.597+0000: 7429: info : libvirt version: 1.1.0,
package: 3.el6 (Unknown, 2013-07-19-17:35:26, hp6)
2013-07-21 20:25:31.597+0000: 7429: error : virIdentityGetSystem:158 :
Unable to lookup SELinux process context: Ialid argument
Caught Segmentation violation dumping internal log buffer:


    ====== start of log ====
393 : Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.695+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.695+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.695+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.695+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.695+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
2013-07-21 21:12:32.695+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.695+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152695
2013-07-21 21:12:32.695+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2415 ms
2013-07-21 21:12:32.695+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2415
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 2
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=6
events=1
2013-07-21 21:12:32.708+0000: 7419: debug : virObjectRef:297 :
OBJECT_REF: obj=0x7fdd842155c0
2013-07-21 21:12:32.708+0000: 7418: debug : virNetlinkEventCallback:346
: dispatching to max 0 clients, called from event watch 6
2013-07-21 21:12:32.708+0000: 7418: debug : virNetlinkEventCallback:359
: event not handled.
2013-07-21 21:12:32.708+0000: 7419: debug :
virEventPollInterruptLocked:716 : Interrupting
2013-07-21 21:12:32.708+0000: 7419: debug : virEventPollAddTimeout:248 :
EVENT_POLL_ADD_TIMEOUT: timer=6 frequency=10000 cb=0x7fdd8e277260
opaque=0x7fdd84211710 ff=0x7fdd8e277230
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.708+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162708
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152708
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2402 ms
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2402
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=1
events=1
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.708+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162708
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152708
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2402 ms
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2402
2013-07-21 21:12:32.708+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.708+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=7
events=1
2013-07-21 21:12:32.709+0000: 7418: debug : udevEventHandleCallback:1526
: udev action: 'add'
2013-07-21 21:12:32.709+0000: 7418: debug : udevGetDeviceProperty:121 :
udev reports device 'vif-1-0' does not have property 'DRIVER'
2013-07-21 21:12:32.709+0000: 7418: debug : udevGetDeviceProperty:139 :
Found property key 'SUBSYSTEM' value 'xen-backend' for device
with
sysname 'vif-1-0'
2013-07-21 21:12:32.709+0000: 7418: debug : udevGetDeviceType:1216 :
Could not determine device type for device with sysfs name 'vif-1-0'
2013-07-21 21:12:32.709+0000: 7418: debug : udevAddOneDevice:1395 :
Discarding device -1 0x7fdda4153a00 /sys/devices/vif-1-0
2013-07-21 21:12:32.709+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.709+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.709+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.709+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.709+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.709+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.709+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.709+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.709+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.709+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.709+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.709+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.709+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.709+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.709+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.709+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.709+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.709+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.709+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162708
2013-07-21 21:12:32.709+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152709
2013-07-21 21:12:32.709+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2401 ms
2013-07-21 21:12:32.709+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2401
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=6
events=1
2013-07-21 21:12:32.711+0000: 7418: debug : virNetlinkEventCallback:346
: dispatching to max 0 clients, called from event watch 6
2013-07-21 21:12:32.711+0000: 7418: debug : virNetlinkEventCallback:359
: event not handled.
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.711+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162708
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152711
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2399 ms
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2399
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=6
events=1
2013-07-21 21:12:32.711+0000: 7418: debug : virNetlinkEventCallback:346
: dispatching to max 0 clients, called from event watch 6
2013-07-21 21:12:32.711+0000: 7418: debug : virNetlinkEventCallback:359
: event not handled.
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.711+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162708
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152711
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2399 ms
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2399
2013-07-21 21:12:32.711+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=6
events=1
2013-07-21 21:12:32.711+0000: 7418: debug : virNetlinkEventCallback:346
: dispatching to max 0 clients, called from event watch 6
2013-07-21 21:12:32.711+0000: 7418: debug : virNetlinkEventCallback:359
: event not handled.
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.711+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.712+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162708
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152712
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2398 ms
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2398
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=6
events=1
2013-07-21 21:12:32.712+0000: 7418: debug : virNetlinkEventCallback:346
: dispatching to max 0 clients, called from event watch 6
2013-07-21 21:12:32.712+0000: 7418: debug : virNetlinkEventCallback:359
: event not handled.
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.712+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162708
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152712
2013-07-21 21:12:32.712+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2398 ms
2013-07-21 21:12:32.712+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2398
2013-07-21 21:12:32.713+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.713+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.713+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.713+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.713+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.713+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.713+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.713+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.713+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.713+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.713+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.713+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.713+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=10
events=1
2013-07-21 21:12:32.714+0000: 7419: debug :
virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=6
frequency=0
2013-07-21 21:12:32.714+0000: 7419: debug :
virEventPollUpdateTimeout:278 : Set timer freq=0 expires=1374441152714
2013-07-21 21:12:32.714+0000: 7419: debug :
virEventPollInterruptLocked:716 : Interrupting
2013-07-21 21:12:32.718+0000: 7419: debug : virObjectRef:297 :
OBJECT_REF: obj=0x7fdd842155c0
2013-07-21 21:12:32.718+0000: 7419: debug :
virEventPollInterruptLocked:716 : Interrupting
2013-07-21 21:12:32.718+0000: 7419: debug : virEventPollAddTimeout:248 :
EVENT_POLL_ADD_TIMEOUT: timer=7 frequency=10000 cb=0x7fdd8e277260
opaque=0x7fdd842119a0 ff=0x7fdd8e277230
2013-07-21 21:12:32.724+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 4
2013-07-21 21:12:32.724+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.724+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.724+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 4
2013-07-21 21:12:32.724+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.724+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.724+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.724+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.724+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.724+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 4 timers
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441152714
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162718
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441152714
now=1374441152725
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441152714 due in 0 ms
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=0
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 4
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchTimeouts:448 : EVENT_POLL_DISPATCH_TIMEOUT: timer=6
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=6
frequency=-1
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollUpdateTimeout:278 : Set timer freq=-1 expires=0
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollInterruptLocked:712 : Skip interrupt, 1 140589770872832
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollRemoveTimeout:300 : EVENT_POLL_REMOVE_TIMEOUT: timer=6
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollInterruptLocked:712 : Skip interrupt, 1 140589770872832
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=1
events=1
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 4
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCleanupTimeouts:529 : EVENT_POLL_PURGE_TIMEOUT: timer=6
2013-07-21 21:12:32.725+0000: 7418: debug : virObjectUnref:260 :
OBJECT_UNREF: obj=0x7fdd842155c0
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.725+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162718
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152725
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2385 ms
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2385
2013-07-21 21:12:32.725+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=1
events=1
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.725+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.726+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.726+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.726+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.726+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.726+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.726+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.726+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.726+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.726+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.726+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.726+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.726+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.726+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.726+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.726+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.726+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.726+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.726+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162718
2013-07-21 21:12:32.726+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152726
2013-07-21 21:12:32.726+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2384 ms
2013-07-21 21:12:32.726+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2384
2013-07-21 21:12:32.832+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.832+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.832+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.832+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.832+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.832+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.832+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.832+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.832+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.832+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.832+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=7
events=1
2013-07-21 21:12:32.832+0000: 7418: debug : udevEventHandleCallback:1526
: udev action: 'add'
2013-07-21 21:12:32.832+0000: 7418: debug : udevGetDeviceProperty:121 :
udev reports device 'vif1.0' does not have property 'DRIVER'
2013-07-21 21:12:32.832+0000: 7418: debug : udevGetDeviceProperty:139 :
Found property key 'SUBSYSTEM' value 'net' for device with
sysname 'vif1.0'
2013-07-21 21:12:32.832+0000: 7418: debug : udevGetDeviceProperty:139 :
Found property key 'INTERFACE' value 'vif1.0' for device with
sysname
'vif1.0'
2013-07-21 21:12:32.832+0000: 7418: debug : udevGetDeviceSysfsAttr:226 :
Found sysfs attribute 'address' value 'fe:ff:ff:ff:ff:ff' for
device
with sysname 'vif1.0'
2013-07-21 21:12:32.833+0000: 7418: debug : udevGetDeviceSysfsAttr:226 :
Found sysfs attribute 'addr_len' value '6' for device with
sysname 'vif1.0'
2013-07-21 21:12:32.833+0000: 7418: debug : udevStrToLong_ui:86 :
Converted '6' to unsigned int 6
2013-07-21 21:12:32.833+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.833+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.833+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.833+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.833+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.833+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.833+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.833+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.833+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.833+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.833+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.833+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.833+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162718
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152834
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2276 ms
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2276
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=7
events=1
2013-07-21 21:12:32.834+0000: 7418: debug : udevEventHandleCallback:1526
: udev action: 'add'
2013-07-21 21:12:32.834+0000: 7418: debug : udevGetDeviceProperty:121 :
udev reports device 'rx-0' does not have property 'DRIVER'
2013-07-21 21:12:32.834+0000: 7418: debug : udevGetDeviceProperty:139 :
Found property key 'SUBSYSTEM' value 'queues' for device with
sysname 'rx-0'
2013-07-21 21:12:32.834+0000: 7418: debug : udevGetDeviceType:1216 :
Could not determine device type for device with sysfs name 'rx-0'
2013-07-21 21:12:32.834+0000: 7418: debug : udevAddOneDevice:1395 :
Discarding device -1 0x7fdda4153b20
/sys/devices/vif-1-0/net/vif1.0/queues/rx-0
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.834+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162718
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152834
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2276 ms
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2276
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=7
events=1
2013-07-21 21:12:32.834+0000: 7418: debug : udevEventHandleCallback:1526
: udev action: 'add'
2013-07-21 21:12:32.834+0000: 7418: debug : udevGetDeviceProperty:121 :
udev reports device 'tx-0' does not have property 'DRIVER'
2013-07-21 21:12:32.834+0000: 7418: debug : udevGetDeviceProperty:139 :
Found property key 'SUBSYSTEM' value 'queues' for device with
sysname 'tx-0'
2013-07-21 21:12:32.834+0000: 7418: debug : udevGetDeviceType:1216 :
Could not determine device type for device with sysfs name 'tx-0'
2013-07-21 21:12:32.834+0000: 7418: debug : udevAddOneDevice:1395 :
Discarding device -1 0x7fdda4153fa0
/sys/devices/vif-1-0/net/vif1.0/queues/tx-0
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.834+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162718
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152834
2013-07-21 21:12:32.834+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2276 ms
2013-07-21 21:12:32.834+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2276
2013-07-21 21:12:32.843+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=6
events=1
2013-07-21 21:12:32.843+0000: 7418: debug : virNetlinkEventCallback:346
: dispatching to max 0 clients, called from event watch 6
2013-07-21 21:12:32.843+0000: 7418: debug : virNetlinkEventCallback:359
: event not handled.
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.843+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.843+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.843+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.843+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.843+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.843+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.843+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.843+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.843+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.843+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162718
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152843
2013-07-21 21:12:32.843+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2267 ms
2013-07-21 21:12:32.843+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2267
2013-07-21 21:12:32.862+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.862+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.862+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.862+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.862+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.862+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.862+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.862+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.862+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.862+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.862+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=7
events=1
2013-07-21 21:12:32.862+0000: 7418: debug : udevEventHandleCallback:1526
: udev action: 'online'
2013-07-21 21:12:32.862+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.862+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.862+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.863+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.863+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.863+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.863+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.863+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.863+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.863+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.863+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.863+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.863+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.863+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.863+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.863+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.863+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.863+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.863+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162718
2013-07-21 21:12:32.863+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152863
2013-07-21 21:12:32.863+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2247 ms
2013-07-21 21:12:32.863+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2247
2013-07-21 21:12:32.893+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=7
events=1
2013-07-21 21:12:32.893+0000: 7418: debug : udevEventHandleCallback:1526
: udev action: 'move'
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.893+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.893+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.893+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.893+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.893+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.893+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.893+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.893+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.893+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.893+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 3 timers
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441162718
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152893
2013-07-21 21:12:32.893+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2217 ms
2013-07-21 21:12:32.893+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2217
2013-07-21 21:12:32.935+0000: 7419: debug :
virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=7
frequency=0
2013-07-21 21:12:32.935+0000: 7419: debug :
virEventPollUpdateTimeout:278 : Set timer freq=0 expires=1374441152935
2013-07-21 21:12:32.935+0000: 7419: debug :
virEventPollInterruptLocked:716 : Interrupting
2013-07-21 21:12:32.935+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.935+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 3
2013-07-21 21:12:32.935+0000: 7418: debug :
virEventPollDispatchTimeouts:448 : EVENT_POLL_DISPATCH_TIMEOUT: timer=7
2013-07-21 21:12:32.935+0000: 7418: debug :
virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=7
frequency=-1
2013-07-21 21:12:32.935+0000: 7418: debug :
virEventPollUpdateTimeout:278 : Set timer freq=-1 expires=0
2013-07-21 21:12:32.935+0000: 7418: debug :
virEventPollInterruptLocked:712 : Skip interrupt, 1 140589770872832
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollRemoveTimeout:300 : EVENT_POLL_REMOVE_TIMEOUT: timer=7
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollInterruptLocked:712 : Skip interrupt, 1 140589770872832
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=1
events=1
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 3
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollCleanupTimeouts:529 : EVENT_POLL_PURGE_TIMEOUT: timer=7
2013-07-21 21:12:32.937+0000: 7418: debug : virObjectUnref:260 :
OBJECT_UNREF: obj=0x7fdd842155c0
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.937+0000: 7418: debug : virEventRunDefaultImpl:270 :
running default event implementation
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollCleanupTimeouts:516 : Cleanup 2
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollCleanupHandles:564 : Cleanup 9
2013-07-21 21:12:32.937+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=0 w=1, f=7 e=1 d=0
2013-07-21 21:12:32.937+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=1 w=2, f=9 e=1 d=0
2013-07-21 21:12:32.937+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=2 w=3, f=12 e=1 d=0
2013-07-21 21:12:32.937+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=3 w=4, f=13 e=1 d=0
2013-07-21 21:12:32.937+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=4 w=5, f=6 e=1 d=0
2013-07-21 21:12:32.937+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=5 w=6, f=14 e=1 d=0
2013-07-21 21:12:32.937+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=6 w=7, f=15 e=1 d=0
2013-07-21 21:12:32.937+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=7 w=9, f=22 e=1 d=0
2013-07-21 21:12:32.937+0000: 7418: debug : virEventPollMakePollFDs:393
: Prepare n=8 w=10, f=49 e=9 d=0
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1374441155110
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollCalculateTimeout:353 : Schedule timeout then=1374441155110
now=1374441152937
2013-07-21 21:12:32.937+0000: 7418: debug :
virEventPollCalculateTimeout:361 : Timeout at 1374441155110 due in 2173 ms
2013-07-21 21:12:32.937+0000: 7418: debug : virEventPollRunOnce:629 :
EVENT_POLL_RUN: nhandles=9 timeout=2173
2013-07-21 21:12:32.938+0000: 7419: debug : virArchFromHost:174 : Mapped
x86_64 to 28 (x86_64)
2013-07-21 21:12:32.938+0000: 7418: debug : virEventPollRunOnce:640 :
Poll got 1 event(s)
2013-07-21 21:12:32.938+0000: 7418: debug :
virEventPollDispatchTimeouts:425 : Dispatch 2
2013-07-21 21:12:32.938+0000: 7418: debug :
virEventPollDispatchHandles:470 : Dispatch 9
2013-07-21 21:12:32.938+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=0 w=1
2013-07-21 21:12:32.938+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=1 w=2
2013-07-21 21:12:32.938+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=2 w=3
2013-07-21 21:12:32.938+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=3 w=4
2013-07-21 21:12:32.938+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=4 w=5
2013-07-21 21:12:32.938+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=5 w=6
2013-07-21 21:12:32.938+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=6 w=7
2013-07-21 21:12:32.938+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=7 w=9
2013-07-21 21:12:32.938+0000: 7418: debug :
virEventPollDispatchHandles:484 : i=8 w=10
2013-07-21 21:12:32.938+0000: 7418: debug :
virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=10
events=1


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

On 7/16/13 4:14 PM, Jim Fehlig wrote:> Thomas Wagner wrote:
>> No, I only use the rpm packages from the OBS
"Virtualisation". Maybe this information is helpful for James Fehlig,
who seems to be the maintainer of this binary build.
>>   
> 
> Well, there is not much information since the error was snipped :). 
> What was the error?
> 
> Regards,
> Jim
> 
>>
>> Regards Thomas
>>
>> -----Ursprüngliche Nachricht-----
>> Von: Ian Campbell [mailto:Ian.Campbell@citrix.com] 
>> Gesendet: Dienstag, 16. Juli 2013 11:07
>> An: Thomas Wagner
>> Cc: xen-users@lists.xen.org
>> Betreff: Re: [Xen-users] XEN 4.3 and libvirt 1.1
>>
>> On Sat, 2013-07-13 at 14:17 +0200, Thomas Wagner wrote:
>>   
>>> Hello
>>>
>>> I wanted to upgrade to the latest version of xen. But now I cannot 
>>> manage my guests with virsh anymore. I get this error:
>>>     
>> [...]
>>   
>>> Do I do something wrong or are these versions incompatible at the 
>>> moment?
>>>     
>>
>> Did you rebuild libvirt against the newer hypervisor libraries?
>>
>> It looks like you are using the libvirt xend backend, I'm not sure
how that works but in the libxl case you will need to rebuild and relink the
libvirt libxl plugin against the libxl which matches your hypervisor.
>>
>> Ian.
>>
>> .
>>   
> 
> _______________________________________________
> Xen-users mailing list
> Xen-users@lists.xen.org
> http://lists.xen.org/xen-users
> 
-- 
Andrew J. Younge
Information Sciences Institute
University of Southern California

_______________________________________________
Xen-users mailing list
Xen-users@lists.xen.org
http://lists.xen.org/xen-users

Xen users - Jul 2013 - XEN 4.3 and libvirt 1.1

XEN 4.3 and libvirt 1.1

Re: XEN 4.3 and libvirt 1.1

Re: XEN 4.3 and libvirt 1.1

Re: XEN 4.3 and libvirt 1.1

Re: XEN 4.3 and libvirt 1.1