[Xen-devel] Windows Vista HVM Bugs

[Sven Oehme <oehmes@xxxxxxxxxx>]

Hi,

i managed to Install a Vista Ultimate
today with a very recent xen-unstable Hypervisor, but I did run in 2 issues
and wanted to know if this is are known bugs ?

1. during installation Vista reboots
a few times and during each reboot the XEN Host rebooted (looks like a
System panic). After successful Installation it still happens when i do
a reboot instead of a shutdown and new create. 

2. When i have the Vista System up ,
i don't get a ip address from my DHCP Server, looks like something with
the network transport is broken, my DHCP Server gets the request, and sends
back an offer, but the Windows Client never receives the response. necessary
log files :

ifconfig -a :

eth0      Link encap:Ethernet  HWaddr 00:16:41:3A:03:2C  
         inet addr:192.168.178.60  Bcast:192.168.178.255  Mask:255.255.255.0
         inet6 addr: fe80::216:41ff:fe3a:32c/64 Scope:Link
         UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
         RX packets:1100383 errors:0 dropped:0 overruns:0 frame:0
         TX packets:2011553 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:100
         RX bytes:103892345 (99.0 MiB)  TX bytes:2961281669 (2.7 GiB)
         Base address:0x3000 Memory:d0100000-d0120000

lo        Link encap:Local Loopback  
         inet addr:127.0.0.1  Mask:255.0.0.0
         inet6 addr: ::1/128 Scope:Host
         UP LOOPBACK RUNNING  MTU:16436  Metric:1
         RX packets:263008 errors:0 dropped:0 overruns:0 frame:0
         TX packets:263008 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:0
         RX bytes:1486290949 (1.3 GiB)  TX bytes:1486290949 (1.3 GiB)

sit0      Link encap:IPv6-in-IPv4  
         NOARP  MTU:1480  Metric:1
         RX packets:0 errors:0 dropped:0 overruns:0 frame:0
         TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:0
         RX bytes:0 (0.0 b)  TX bytes:0 (0.0 b)

tap0      Link encap:Ethernet  HWaddr 4A:F4:A8:99:5F:69  
         inet6 addr: fe80::48f4:a8ff:fe99:5f69/64 Scope:Link
         UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
         RX packets:106 errors:0 dropped:0 overruns:0 frame:0
         TX packets:25 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:500
         RX bytes:19112 (18.6 KiB)  TX bytes:2466 (2.4 KiB)

veth0     Link encap:Ethernet  HWaddr 00:16:3E:1E:4A:D7  
         inet addr:192.168.0.1  Bcast:0.0.0.0  Mask:255.255.255.0
         UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
         RX packets:1696201 errors:0 dropped:0 overruns:0 frame:0
         TX packets:30960673 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:0
         RX bytes:579570939 (552.7 MiB)  TX bytes:1998297651 (1.8 GiB)

veth1     Link encap:Ethernet  HWaddr 00:00:00:00:00:00  
         BROADCAST MULTICAST  MTU:1500  Metric:1
         RX packets:0 errors:0 dropped:0 overruns:0 frame:0
         TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:0
         RX bytes:0 (0.0 b)  TX bytes:0 (0.0 b)

veth2     Link encap:Ethernet  HWaddr 00:00:00:00:00:00  
         BROADCAST MULTICAST  MTU:1500  Metric:1
         RX packets:0 errors:0 dropped:0 overruns:0 frame:0
         TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:0
         RX bytes:0 (0.0 b)  TX bytes:0 (0.0 b)

veth3     Link encap:Ethernet  HWaddr 00:00:00:00:00:00  
         BROADCAST MULTICAST  MTU:1500  Metric:1
         RX packets:0 errors:0 dropped:0 overruns:0 frame:0
         TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:0
         RX bytes:0 (0.0 b)  TX bytes:0 (0.0 b)

vif0.0    Link encap:Ethernet  HWaddr FE:FF:FF:FF:FF:FF  
         inet6 addr: fe80::fcff:ffff:feff:ffff/64 Scope:Link
         UP BROADCAST RUNNING NOARP  MTU:1500  Metric:1
         RX packets:31 errors:0 dropped:0 overruns:0 frame:0
         TX packets:106 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:0
         RX bytes:6392 (6.2 KiB)  TX bytes:19070 (18.6 KiB)

vif0.1    Link encap:Ethernet  HWaddr FE:FF:FF:FF:FF:FF  
         BROADCAST MULTICAST  MTU:1500  Metric:1
         RX packets:0 errors:0 dropped:0 overruns:0 frame:0
         TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:0
         RX bytes:0 (0.0 b)  TX bytes:0 (0.0 b)

vif0.2    Link encap:Ethernet  HWaddr FE:FF:FF:FF:FF:FF  
         BROADCAST MULTICAST  MTU:1500  Metric:1
         RX packets:0 errors:0 dropped:0 overruns:0 frame:0
         TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:0
         RX bytes:0 (0.0 b)  TX bytes:0 (0.0 b)

vif0.3    Link encap:Ethernet  HWaddr FE:FF:FF:FF:FF:FF  
         BROADCAST MULTICAST  MTU:1500  Metric:1
         RX packets:0 errors:0 dropped:0 overruns:0 frame:0
         TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:0
         RX bytes:0 (0.0 b)  TX bytes:0 (0.0 b)

vif6.0    Link encap:Ethernet  HWaddr FE:FF:FF:FF:FF:FF  
         UP BROADCAST NOARP  MTU:1500  Metric:1
         RX packets:0 errors:0 dropped:0 overruns:0 frame:0
         TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:0
         RX bytes:0 (0.0 b)  TX bytes:0 (0.0 b)

xenbr0    Link encap:Ethernet  HWaddr 4A:F4:A8:99:5F:69  
         inet6 addr: fe80::200:ff:fe00:0/64 Scope:Link
         UP BROADCAST RUNNING NOARP  MTU:1500  Metric:1
         RX packets:32661727 errors:0 dropped:0 overruns:0 frame:0
         TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:0
         RX bytes:2122624638 (1.9 GiB)  TX bytes:0 (0.0 b)


brctl show :

bridge name        bridge id                STP enabled        interfaces
xenbr0                8000.4af4a8995f69        no                tap0
                                                       vif6.0
                                                       vif0.0

xm info :

host                   : xen2.localdomain
release                : 2.6.18-1.2869.fc6xen
version                : #1 SMP Wed Dec 20 15:28:06 EST 2006
machine                : i686
nr_cpus                : 2
nr_nodes               : 1
sockets_per_node       : 1
cores_per_socket       : 2
threads_per_core       : 1
cpu_mhz                : 1860
hw_caps                : bfebfbff:20100000:00000000:00000140:0000e3bd:00000000:00000001
total_memory           : 2021
free_memory            : 1
xen_major              : 3
xen_minor              : 0
xen_extra              : -unstable
xen_caps               : xen-3.0-x86_32p hvm-3.0-x86_32 hvm-3.0-x86_32p
xen_pagesize           : 4096
platform_params        : virt_start=0xf5800000
xen_changeset          : Sat Dec 30 12:49:31 2006 +0000 13195:a578c9703416
cc_compiler            : gcc version 4.1.1 20061220 (Red Hat 4.1.1-47)
cc_compile_by          : root
cc_compile_domain      : (none)
cc_compile_date        : Sat Dec 30 17:26:26 CET 2006
xend_config_format     : 3

xm list -l :

(domain
   (domid 0)
   (on_crash restart)
   (memory 1470)
   (uuid 00000000-0000-0000-0000-000000000000)
   (bootloader_args )
   (name Domain-0)
   (maxmem 1470)
   (on_reboot restart)
   (on_poweroff destroy)
   (vcpus 2)
   (bootloader )
   (shadow_memory 0)
   (cpu_weight 256)
   (cpu_cap 0)
   (features )
   (on_xend_start ignore)
   (on_xend_stop ignore)
   (cpu_time 2345.49705665)
   (online_vcpus 2)
   (status 2)
   (memory_dynamic_min 1470)
   (memory_dynamic_max 0)
   (state r-----)
)
(domain
   (domid 6)
   (on_crash restart)
   (memory 512)
   (uuid fa2383cf-c57f-8f03-1237-aced09c92585)
   (bootloader_args )
   (name vista-ws)
   (maxmem 528)
   (on_reboot restart)
   (on_poweroff destroy)
   (localtime 0)
   (vcpus 1)
   (bootloader )
   (shadow_memory 5)
   (cpu_weight 256)
   (cpu_cap 0)
   (features )
   (on_xend_start ignore)
   (on_xend_stop ignore)
   (start_time 1167506548.5)
   (cpu_time 49.46941824)
   (online_vcpus 1)
   (image
       (hvm
            (kernel /usr/lib/xen/boot/hvmloader)
           (nographic 0)
           (vnc 1)
           (sdl 0)
           (vncunused 1)
           (device_model /usr/lib/xen/bin/qemu-dm)
           (display :0.0)
           (xauthority /root/.xauthQ1hEIK)
           (pae 1)
           (apic 1)
           (acpi 1)
           (boot c)
           (isa 0)
           (localtime 0)
           (serial pty)
           (usb 0)
           (usbdevice tablet)
           (vcpus 1)
       )
   )
   (status 2)
   (memory_dynamic_min 520)
   (memory_dynamic_max 528)
   (state -b----)
   (store_mfn 131070)
   (device
       (vif
           (bridge xenbr0)
           (uuid 85a1ce8e-8523-757e-b262-cad300ce1ad1)
           (script vif-bridge)
           (mac 00:b7:aa:48:09:5d)
           (type ioemu)
           (backend 0)
       )
   )
   (device
       (vbd
           (uname file:/temp/en_windows_vista_x86_dvd_X12-34293.iso)
           (uuid 0673099b-2398-8240-0ad9-def9af0d9590)
           (mode r)
            (dev hdb:cdrom)
           (backend 0)
       )
   )
   (device
       (vbd
           (uname file:/xenimages/vista-ws.img)
           (uuid 747b97e4-f27f-89d2-260f-706aca8bdb0a)
           (mode w)
           (dev hda:disk)
           (backend 0)
       )
   )
   (device (console (vnc-port 5901)))
)

some lines from /var/log/messages :

Dec 30 20:22:00 xen2 avahi-daemon[2650]: Interface veth0.IPv4 no longer relevant for mDNS.
Dec 30 20:22:00 xen2 avahi-daemon[2650]: New relevant interface veth0.IPv4 for mDNS.
Dec 30 20:22:00 xen2 avahi-daemon[2650]: Joining mDNS multicast group on interface veth0.IPv4 with address 192.168.0.1.
Dec 30 20:22:00 xen2 avahi-daemon[2650]: Registering new address record for 192.168.0.1 on veth0.
Dec 30 20:22:28 xen2 kernel: device vif6.0 entered promiscuous mode
Dec 30 20:22:28 xen2 kernel: audit(1167506548.581:43): dev=vif6.0 prom=256 old_prom=0 auid=4294967295
Dec 30 20:22:28 xen2 kernel: ADDRCONF(NETDEV_UP): vif6.0: link is not ready
Dec 30 20:22:28 xen2 kernel: device tap0 entered promiscuous mode
Dec 30 20:22:28 xen2 kernel: audit(1167506548.777:44): dev=tap0 prom=256 old_prom=0 auid=4294967295
Dec 30 20:22:28 xen2 kernel: xenbr0: port 3(tap0) entering learning state
Dec 30 20:22:28 xen2 kernel: xenbr0: topology change detected, propagating
Dec 30 20:22:28 xen2 kernel: xenbr0: port 3(tap0) entering forwarding state
Dec 30 20:23:11 xen2 dhcpd: DHCPDISCOVER from 00:b7:aa:48:09:5d via veth0
Dec 30 20:23:11 xen2 dhcpd: DHCPOFFER on 192.168.0.212 to 00:b7:aa:48:09:5d via veth0
Dec 30 20:23:15 xen2 dhcpd: DHCPDISCOVER from 00:b7:aa:48:09:5d via veth0
Dec 30 20:23:15 xen2 dhcpd: DHCPOFFER on 192.168.0.212 to 00:b7:aa:48:09:5d via veth0
Dec 30 20:23:23 xen2 dhcpd: DHCPDISCOVER from 00:b7:aa:48:09:5d via veth0
Dec 30 20:23:23 xen2 dhcpd: DHCPOFFER on 192.168.0.212 to 00:b7:aa:48:09:5d via veth0
Dec 30 20:23:39 xen2 dhcpd: DHCPDISCOVER from 00:b7:aa:48:09:5d via veth0
Dec 30 20:23:39 xen2 dhcpd: DHCPOFFER on 192.168.0.212 to 00:b7:aa:48:09:5d via veth0


qemu-dm log file :

domid: 6
qemu: the number of cpus is 1
qemu_map_cache_init nr_buckets = 2000
shared page at pfn:1ffff
buffered io page at pfn:1fffd
xs_read(): vncpasswd get error. /vm/fa2383cf-c57f-8f03-1237-aced09c92585/vncpasswd.
char device redirected to /dev/pts/2
I/O request not ready: 0, ptr: 0, port: 0, data: 0, count: 0, size: 0


xend.log :

[2006-12-30 20:21:58 11175] WARNING (XendAuthSessions:27) python-pam is required for XenAPI support.
[2006-12-30 20:21:58 11175] WARNING (XendAPI:1599) API call: VIF.get_io_read_kbs not found
[2006-12-30 20:21:58 11175] WARNING (XendAPI:1599) API call: VIF.get_io_write_kbs not found
[2006-12-30 20:21:58 11175] WARNING (XendAPI:1599) API call: VIF.get_name not found
[2006-12-30 20:21:58 11175] WARNING (XendAPI:1599) API call: VIF.get_type not found
[2006-12-30 20:21:58 11175] WARNING (XendAPI:1599) API call: VIF.get_device not found
[2006-12-30 20:21:58 11175] WARNING (XendAPI:1599) API call: VIF.get_network not found
[2006-12-30 20:21:58 11175] WARNING (XendAPI:1599) API call: VIF.get_VM not found
[2006-12-30 20:21:58 11175] WARNING (XendAPI:1599) API call: VIF.get_MAC not found
[2006-12-30 20:21:58 11175] WARNING (XendAPI:1599) API call: VIF.get_MTU not found
[2006-12-30 20:21:58 11175] WARNING (XendAPI:1599) API call: VIF.set_name not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VIF.set_type not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VIF.set_device not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VIF.set_network not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VIF.set_VM not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VIF.set_MAC not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VIF.set_MTU not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VIF.destroy not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VIF.get_all not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VBD.get_image not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VBD.get_io_read_kbs not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VBD.get_io_write_kbs not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VBD.set_VM not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VBD.set_VDI not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VBD.set_device not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VBD.set_mode not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VBD.set_driver not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VBD.destroy not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VBD.get_all not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VM.get_consoles not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VM.get_auto_power_on not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VM.set_auto_power_on not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VM.set_platform_keymap not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: PIF.set_network not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: PIF.set_host not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: PIF.set_VLAN not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: host.get_by_name_label not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: session.create not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: session.get_all not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VTPM.destroy not found
[2006-12-30 20:21:59 11175] WARNING (XendAPI:1599) API call: VTPM.get_all not found
[2006-12-30 20:21:59 11189] INFO (SrvDaemon:322) Xend Daemon started
[2006-12-30 20:21:59 11189] INFO (SrvDaemon:326) Xend changeset: Sat Dec 30 12:49:31 2006 +0000 13195:a578c9703416.
[2006-12-30 20:21:59 11189] INFO (SrvDaemon:333) Xend version: Unknown.
[2006-12-30 20:22:00 11189] DEBUG (XendDomainInfo:142) XendDomainInfo.recreate({'max_vcpu_id': 1, 'cpu_time': 2300517969444L, 'ssidref': 0, 'hvm': 0, 'shutdown_reason': 0, 'dying': 0, 'online_vcpus': 2, 'domid': 0, 'paused': 0, 'crashed': 0, 'running': 1, 'maxmem_kb': -4, 'shutdown': 0, 'mem_kb': 1505280, 'handle': [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], 'blocked': 0, 'name': 'Domain-0'})
[2006-12-30 20:22:00 11189] INFO (XendDomainInfo:156) Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000. at /local/domain/0
[2006-12-30 20:22:00 11189] DEBUG (XendDomain:429) Adding Domain: 0
[2006-12-30 20:22:00 11189] DEBUG (XendDomain:373) number of vcpus to use is 0
[2006-12-30 20:22:00 11189] DEBUG (XendDomainInfo:819) XendDomainInfo.handleShutdownWatch
[2006-12-30 20:22:00 11189] INFO (XMLRPCServer:122) Opening Unix domain socket XML-RPC server on /var/run/xend/xmlrpc.sock.
[2006-12-30 20:22:00 11189] INFO (XMLRPCServer:122) Opening Unix domain socket XML-RPC server on /var/run/xend/xen-api.sock.
[2006-12-30 20:22:01 11215] WARNING (XendAuthSessions:27) python-pam is required for XenAPI support.
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.get_io_read_kbs not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.get_io_write_kbs not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.get_name not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.get_type not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.get_device not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.get_network not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.get_VM not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.get_MAC not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.get_MTU not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.set_name not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.set_type not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.set_device not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.set_network not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.set_VM not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.set_MAC not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.set_MTU not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.destroy not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VIF.get_all not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VBD.get_image not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VBD.get_io_read_kbs not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VBD.get_io_write_kbs not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VBD.set_VM not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VBD.set_VDI not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VBD.set_device not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VBD.set_mode not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VBD.set_driver not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VBD.destroy not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VBD.get_all not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VM.get_consoles not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VM.get_auto_power_on not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VM.set_auto_power_on not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VM.set_platform_keymap not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: PIF.set_network not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: PIF.set_host not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: PIF.set_VLAN not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: host.get_by_name_label not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: session.create not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: session.get_all not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VTPM.destroy not found
[2006-12-30 20:22:01 11215] WARNING (XendAPI:1599) API call: VTPM.get_all not found
[2006-12-30 20:22:28 11189] DEBUG (XendDomainInfo:93) XendDomainInfo.create(['vm', ['name', 'vista-ws'], ['memory', '512'], ['on_reboot', 'restart'], ['on_crash', 'restart'], ['vcpus', 1], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['uuid', 'fa2383cf-c57f-8f03-1237-aced09c92585'], ['image', ['hvm', ['kernel', '/usr/lib/xen/boot/hvmloader'], ['device_model', '/usr/lib/xen/bin/qemu-dm'], ['pae', 1], ['vcpus', 1], ['boot', 'c'], ['fda', ''], ['fdb', ''], ['localtime', 0], ['serial', 'pty'], ['stdvga', 0], ['isa', 0], ['nographic', 0], ['soundhw', ''], ['vnc', 1], ['vncunused', 1], ['sdl', 0], ['display', ':0.0'], ['xauthority', '/root/.xauthQ1hEIK'], ['acpi', 1], ['apic', 1], ['usb', 0], ['usbdevice', 'tablet'], ['keymap', ''], ['vncpasswd', 'XXXXXXXX']]], ['device', ['vbd', ['uname', 'file:/xenimages/vista-ws.img'], ['dev', 'hda'], ['mode', 'w']]], ['device', ['vbd', ['uname', 'file:/temp/en_windows_vista_x86_dvd_X12-34293.iso'], ['dev', 'hdb:cdrom'], ['mode', 'r']]], ['device', ['vif', ['bridge', 'xenbr0'], ['mac', '00:b7:aa:48:09:5d'], ['type', 'ioemu']]]])
[2006-12-30 20:22:28 11189] WARNING (XendConfig:606) Unconverted key: cpus
[2006-12-30 20:22:28 11189] DEBUG (XendDomainInfo:1300) XendDomainInfo.constructDomain
[2006-12-30 20:22:28 11189] DEBUG (XendDomainInfo:1346) XendDomainInfo.initDomain: 6 256
[2006-12-30 20:22:28 11189] DEBUG (image:405) args: boot, val: c
[2006-12-30 20:22:28 11189] DEBUG (image:405) args: fda, val:
[2006-12-30 20:22:28 11189] DEBUG (image:405) args: fdb, val:
[2006-12-30 20:22:28 11189] DEBUG (image:405) args: soundhw, val:
[2006-12-30 20:22:28 11189] DEBUG (image:405) args: localtime, val: 0
[2006-12-30 20:22:28 11189] DEBUG (image:405) args: serial, val: pty
[2006-12-30 20:22:28 11189] DEBUG (image:405) args: std-vga, val: 0
[2006-12-30 20:22:28 11189] DEBUG (image:405) args: isa, val: 0
[2006-12-30 20:22:28 11189] DEBUG (image:405) args: vcpus, val: 1
[2006-12-30 20:22:28 11189] DEBUG (image:405) args: acpi, val: 1
[2006-12-30 20:22:28 11189] DEBUG (image:405) args: usb, val: 0
[2006-12-30 20:22:28 11189] DEBUG (image:405) args: usbdevice, val: tablet
[2006-12-30 20:22:28 11189] DEBUG (image:405) args: k, val:
[2006-12-30 20:22:28 11189] DEBUG (balloon:127) Balloon: 538128 KiB free; need 537600; done.
[2006-12-30 20:22:28 11189] INFO (image:130) buildDomain os=hvm dom=6 vcpus=1
[2006-12-30 20:22:28 11189] DEBUG (image:355) domid          = 6
[2006-12-30 20:22:28 11189] DEBUG (image:356) image          = /usr/lib/xen/boot/hvmloader
[2006-12-30 20:22:28 11189] DEBUG (image:357) store_evtchn   = 2
[2006-12-30 20:22:28 11189] DEBUG (image:358) memsize        = 512
[2006-12-30 20:22:28 11189] DEBUG (image:359) vcpus          = 1
[2006-12-30 20:22:28 11189] DEBUG (image:360) pae            = 1
[2006-12-30 20:22:28 11189] DEBUG (image:361) acpi           = 1
[2006-12-30 20:22:28 11189] DEBUG (image:362) apic           = 1
[2006-12-30 20:22:28 11189] DEBUG (image:529) hvm shutdown watch registered
[2006-12-30 20:22:28 11189] DEBUG (image:568) hvm reboot feature watch registered
[2006-12-30 20:22:28 11189] INFO (XendDomainInfo:1210) createDevice: vbd : {'uname': 'file:/temp/en_windows_vista_x86_dvd_X12-34293.iso', 'driver': 'paravirtualised', 'mode': 'r', 'dev': 'hdb:cdrom', 'uuid': '0673099b-2398-8240-0ad9-def9af0d9590'}
[2006-12-30 20:22:28 11189] DEBUG (DevController:115) DevController: writing {'backend-id': '0', 'virtual-device': '832', 'device-type': 'cdrom', 'state': '1', 'backend': '/local/domain/0/backend/vbd/6/832'} to /local/domain/6/device/vbd/832.
[2006-12-30 20:22:28 11189] DEBUG (DevController:117) DevController: writing {'domain': 'vista-ws', 'frontend': '/local/domain/6/device/vbd/832', 'uuid': '0673099b-2398-8240-0ad9-def9af0d9590', 'dev': 'hdb', 'state': '1', 'params': '/temp/en_windows_vista_x86_dvd_X12-34293.iso', 'mode': 'r', 'online': '1', 'frontend-id': '6', 'type': 'file'} to /local/domain/0/backend/vbd/6/832.
[2006-12-30 20:22:28 11189] INFO (XendDomainInfo:1210) createDevice: vif : {'bridge': 'xenbr0', 'mac': '00:b7:aa:48:09:5d', 'type': 'ioemu', 'uuid': '85a1ce8e-8523-757e-b262-cad300ce1ad1'}
[2006-12-30 20:22:28 11189] DEBUG (DevController:115) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vif/6/0'} to /local/domain/6/device/vif/0.
[2006-12-30 20:22:28 11189] DEBUG (DevController:117) DevController: writing {'bridge': 'xenbr0', 'domain': 'vista-ws', 'handle': '0', 'uuid': '85a1ce8e-8523-757e-b262-cad300ce1ad1', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/6/device/vif/0', 'mac': '00:b7:aa:48:09:5d', 'online': '1', 'frontend-id': '6', 'type': 'ioemu'} to /local/domain/0/backend/vif/6/0.
[2006-12-30 20:22:28 11189] INFO (XendDomainInfo:1210) createDevice: vbd : {'uname': 'file:/xenimages/vista-ws.img', 'driver': 'paravirtualised', 'mode': 'w', 'dev': 'hda', 'uuid': '747b97e4-f27f-89d2-260f-706aca8bdb0a'}
[2006-12-30 20:22:28 11189] DEBUG (DevController:115) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/6/768'} to /local/domain/6/device/vbd/768.
[2006-12-30 20:22:28 11189] DEBUG (DevController:117) DevController: writing {'domain': 'vista-ws', 'frontend': '/local/domain/6/device/vbd/768', 'uuid': '747b97e4-f27f-89d2-260f-706aca8bdb0a', 'dev': 'hda', 'state': '1', 'params': '/xenimages/vista-ws.img', 'mode': 'w', 'online': '1', 'frontend-id': '6', 'type': 'file'} to /local/domain/0/backend/vbd/6/768.
[2006-12-30 20:22:28 11189] INFO (image:497) spawning device models: /usr/lib/xen/bin/qemu-dm ['/usr/lib/xen/bin/qemu-dm', '-d', '6', '-m', '512', '-boot', 'c', '-serial', 'pty', '-vcpus', '1', '-acpi', '-usbdevice', 'tablet', '-domain-name', 'vista-ws', '-net', 'nic,vlan=1,macaddr=00:b7:aa:48:09:5d,model=rtl8139', '-net', 'tap,vlan=1,bridge=xenbr0', '-vncunused', '-vnclisten', '127.0.0.1']
[2006-12-30 20:22:28 11189] INFO (image:501) device model pid: 11264
[2006-12-30 20:22:28 11189] DEBUG (XendDomainInfo:1732) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '5', 'uuid': 'fa2383cf-c57f-8f03-1237-aced09c92585', 'name': 'vista-ws', 'on_reboot': 'restart', 'start_time': '1167506548.5', 'on_poweroff': 'destroy', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '512', 'image': '(hvm (kernel /usr/lib/xen/boot/hvmloader) (nographic 0) (vnc 1) (sdl 0) (vncunused 1) (device_model /usr/lib/xen/bin/qemu-dm) (display :0.0) (xauthority /root/.xauthQ1hEIK) (pae 1) (apic 1) (acpi 1) (boot c) (isa 0) (localtime 0) (serial pty) (usb 0) (usbdevice tablet) (vcpus 1))', 'maxmem': '512'}
[2006-12-30 20:22:28 11189] DEBUG (XendDomainInfo:752) Storing domain details: {'console/port': '3', 'name': 'vista-ws', 'console/limit': '1048576', 'vm': '/vm/fa2383cf-c57f-8f03-1237-aced09c92585', 'domid': '6', 'cpu/0/availability': 'online', 'memory/target': '524288', 'store/ring-ref': '131070', 'store/port': '2'}
[2006-12-30 20:22:28 11189] DEBUG (XendDomain:429) Adding Domain: 6
[2006-12-30 20:22:28 11189] DEBUG (image:555) hvm_shutdown fired, shutdown reason=None
[2006-12-30 20:22:28 11189] DEBUG (image:587) hvm_reboot_feature fired, module status=None
[2006-12-30 20:22:28 11189] DEBUG (XendDomainInfo:752) Storing domain details: {'console/port': '3', 'name': 'vista-ws', 'console/limit': '1048576', 'vm': '/vm/fa2383cf-c57f-8f03-1237-aced09c92585', 'domid': '6', 'cpu/0/availability': 'online', 'memory/target': '524288', 'store/ring-ref': '131070', 'store/port': '2'}
[2006-12-30 20:22:28 11189] DEBUG (XendDomainInfo:819) XendDomainInfo.handleShutdownWatch
[2006-12-30 20:22:28 11189] DEBUG (DevController:148) Waiting for devices vif.
[2006-12-30 20:22:28 11189] DEBUG (DevController:153) Waiting for 0.
[2006-12-30 20:22:28 11189] DEBUG (DevController:521) hotplugStatusCallback /local/domain/0/backend/vif/6/0/hotplug-status.
[2006-12-30 20:22:28 11189] DEBUG (DevController:521) hotplugStatusCallback /local/domain/0/backend/vif/6/0/hotplug-status.
[2006-12-30 20:22:28 11189] DEBUG (DevController:535) hotplugStatusCallback 1.
[2006-12-30 20:22:28 11189] DEBUG (DevController:148) Waiting for devices usb.
[2006-12-30 20:22:28 11189] DEBUG (DevController:148) Waiting for devices vbd.
[2006-12-30 20:22:28 11189] DEBUG (DevController:153) Waiting for 832.
[2006-12-30 20:22:28 11189] DEBUG (DevController:521) hotplugStatusCallback /local/domain/0/backend/vbd/6/832/hotplug-status.
[2006-12-30 20:22:28 11189] DEBUG (DevController:521) hotplugStatusCallback /local/domain/0/backend/vbd/6/832/hotplug-status.
[2006-12-30 20:22:28 11189] DEBUG (DevController:535) hotplugStatusCallback 1.
[2006-12-30 20:22:28 11189] DEBUG (DevController:153) Waiting for 768.
[2006-12-30 20:22:28 11189] DEBUG (DevController:521) hotplugStatusCallback /local/domain/0/backend/vbd/6/768/hotplug-status.
[2006-12-30 20:22:29 11189] DEBUG (DevController:521) hotplugStatusCallback /local/domain/0/backend/vbd/6/768/hotplug-status.
[2006-12-30 20:22:29 11189] DEBUG (DevController:535) hotplugStatusCallback 1.
[2006-12-30 20:22:29 11189] DEBUG (DevController:148) Waiting for devices irq.
[2006-12-30 20:22:29 11189] DEBUG (DevController:148) Waiting for devices vkbd.
[2006-12-30 20:22:29 11189] DEBUG (DevController:148) Waiting for devices vfb.
[2006-12-30 20:22:29 11189] DEBUG (DevController:148) Waiting for devices pci.
[2006-12-30 20:22:29 11189] DEBUG (DevController:148) Waiting for devices ioports.
[2006-12-30 20:22:29 11189] DEBUG (DevController:148) Waiting for devices tap.
[2006-12-30 20:22:29 11189] DEBUG (DevController:148) Waiting for devices vtpm.
[2006-12-30 20:22:29 11189] INFO (XendDomain:1057) Domain vista-ws (6) unpaused.

xend-debug.log :


Xend started at Sat Dec 30 20:21:59 2006.
Nothing to flush.
SIOCSIFHWADDR: Device or resource busy

is this a known bug , or should i open a bugzilla against xen-unstable or 3.0.4 ?

thanks . Sven

_______________________________________________
Xen-devel mailing list
Xen-devel@xxxxxxxxxxxxxxxxxxx
http://lists.xensource.com/xen-devel