First attempt at HVM: vbd problems

To: port-xen%netbsd.org@localhost
Subject: First attempt at HVM: vbd problems
From: "Brian A. Seklecki" <lavalamp%spiritual-machines.org@localhost>
Date: Thu, 10 Apr 2008 23:12:05 -0400 (EDT)


All:

I'm evaluating the overall functionality of Xen NetBSD. My test platformis NetBSD/amd64 -current on a PowerEdge 2950 r3

I can get a HVM with a VNC console to start, but only if I disable disk=and vif= devices. With disk= devices, the hvm initializes, then times-outafter about 180 seconds.

Likely this is some sort of Linux-specific code bug, like the previouslyidentified netstat(1) one.


~BAS

=== Crash ====


# xm create /usr/pkg/etc/xen/winxp
Using config file "/usr/pkg/etc/xen/winxp".
VNC= 1

[2008-04-10 18:00:58 21694] DEBUG (XendDomainInfo:84)XendDomainInfo.create(['vm', ['name', 'winxp'], ['memory', '256'],['vcpus', 2], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'],['image', ['hvm', ['kernel', '/usr/pkg/lib/xen/boot/hvmloader'], ['args','VNC_VIEWER=192.168.3.93:5501 '], ['device_model','/usr/pkg/libexec/qemu-dm'], ['pae', 1], ['vcpus', 2], ['boot', 'd'],['fda', ''], ['fdb', ''], ['timer_mode', 0], ['localtime', 0], ['serial',''], ['stdvga', 0], ['isa', 0], ['nographic', 0], ['soundhw', ''], ['vnc',1], ['vncunused', 1], ['xauthority', '/root/.Xauthority'],['rtc_timeoffset', '0'], ['monitor', 0], ['acpi', 1], ['apic', 0], ['usb',0], ['usbdevice', ''], ['keymap', '']]], ['device', ['vbd', ['uname','file:/home/seklecki/6.3-RELEASE-i386-bootonly.iso'], ['dev','ioemu:hdc:cdrom'], ['mode', 'r']]], ['device', ['vkbd']], ['device',['vfb', ['vncunused', '0'], ['type', 'vnc'], ['xauthority','/root/.Xauthority']]]])[2008-04-10 18:00:58 21694] DEBUG (XendDomainInfo:1512)XendDomainInfo.constructDomain[2008-04-10 18:00:58 21694] DEBUG (balloon:115) Balloon: 3843788 KiB free;need 2048; done.

[2008-04-10 18:00:59 21694] DEBUG (XendDomain:434) Adding Domain: 87

[2008-04-10 18:00:59 21694] DEBUG (XendDomainInfo:1572)XendDomainInfo.initDomain: 87 256

[2008-04-10 18:00:59 21694] DEBUG (image:337) args: boot, val: d
[2008-04-10 18:00:59 21694] DEBUG (image:337) args: fda, val: None
[2008-04-10 18:00:59 21694] DEBUG (image:337) args: fdb, val: None
[2008-04-10 18:00:59 21694] DEBUG (image:337) args: soundhw, val: None
[2008-04-10 18:00:59 21694] DEBUG (image:337) args: localtime, val: 0
[2008-04-10 18:00:59 21694] DEBUG (image:337) args: serial, val: None
[2008-04-10 18:00:59 21694] DEBUG (image:337) args: std-vga, val: 0
[2008-04-10 18:00:59 21694] DEBUG (image:337) args: isa, val: 0
[2008-04-10 18:00:59 21694] DEBUG (image:337) args: acpi, val: 1
[2008-04-10 18:00:59 21694] DEBUG (image:337) args: usb, val: 0
[2008-04-10 18:00:59 21694] DEBUG (image:337) args: usbdevice, val: None
[2008-04-10 18:00:59 21694] DEBUG (image:337) args: k, val: None

[2008-04-10 18:00:59 21694] DEBUG (XendDomainInfo:1607)_initDomain:shadow_memory=0x0, memory_static_max=0x10000000,memory_static_min=0x0.[2008-04-10 18:00:59 21694] DEBUG (balloon:115) Balloon: 3842748 KiB free;need 274432; done.[2008-04-10 18:00:59 21694] INFO (image:128) buildDomain os=hvm dom=87vcpus=2

[2008-04-10 18:00:59 21694] DEBUG (image:281) domid          = 87

[2008-04-10 18:00:59 21694] DEBUG (image:282) image =/usr/pkg/lib/xen/boot/hvmloader

[2008-04-10 18:00:59 21694] DEBUG (image:283) store_evtchn   = 3
[2008-04-10 18:00:59 21694] DEBUG (image:284) memsize        = 256
[2008-04-10 18:00:59 21694] DEBUG (image:285) vcpus          = 2
[2008-04-10 18:00:59 21694] DEBUG (image:286) acpi           = 1
[2008-04-10 18:00:59 21694] DEBUG (image:287) apic           = 0

[2008-04-10 18:00:59 21694] INFO (XendDomainInfo:1414) createDevice: vkbd: {'uuid': '390fcc7e-dc18-4745-cf1e-31bf12415229'}[2008-04-10 18:00:59 21694] DEBUG (DevController:117) DevController:writing {'state': '1', 'backend-id': '0', 'backend':'/local/domain/0/backend/vkbd/87/0'} to /local/domain/87/device/vkbd/0.[2008-04-10 18:00:59 21694] DEBUG (DevController:119) DevController:writing {'frontend-id': '87', 'domain': 'winxp', 'frontend':'/local/domain/87/device/vkbd/0', 'state': '1', 'online': '1'} to/local/domain/0/backend/vkbd/87/0.[2008-04-10 18:00:59 21694] INFO (XendDomainInfo:1414) createDevice: vfb :{'vncunused': '0', 'other_config': {'vncunused': '0', 'type': 'vnc','xauthority': '/root/.Xauthority'}, 'type': 'vnc', 'xauthority':'/root/.Xauthority', 'uuid': '4366e928-af8d-45f9-289b-211f8fddab90'}[2008-04-10 18:00:59 21694] DEBUG (DevController:117) DevController:writing {'state': '1', 'backend-id': '0', 'backend':'/local/domain/0/backend/vfb/87/0'} to /local/domain/87/device/vfb/0.[2008-04-10 18:00:59 21694] DEBUG (DevController:119) DevController:writing {'vncunused': '0', 'domain': 'winxp', 'frontend':'/local/domain/87/device/vfb/0', 'xauthority': '/root/.Xauthority','state': '1', 'online': '1', 'frontend-id': '87', 'type': 'vnc', 'uuid':'4366e928-af8d-45f9-289b-211f8fddab90'} to/local/domain/0/backend/vfb/87/0.[2008-04-10 18:00:59 21694] INFO (XendDomainInfo:1414) createDevice: vbd :{'uuid': '0e6857ff-8609-6c23-1b92-fedd2f3c37f6', 'bootable': 1, 'driver':'ioemu', 'dev': 'ioemu:hdc:cdrom', 'uname':'file:/home/seklecki/6.3-RELEASE-i386-bootonly.iso', 'mode': 'r'}[2008-04-10 18:00:59 21694] DEBUG (DevController:117) DevController:writing {'backend-id': '0', 'virtual-device': '5632', 'device-type':'cdrom', 'state': '1', 'backend': '/local/domain/0/backend/vbd/87/5632'}to /local/domain/87/device/vbd/5632.[2008-04-10 18:00:59 21694] DEBUG (DevController:119) DevController:writing {'domain': 'winxp', 'frontend':'/local/domain/87/device/vbd/5632', 'uuid':'0e6857ff-8609-6c23-1b92-fedd2f3c37f6', 'dev': 'hdc', 'state': '1','params': '/home/seklecki/6.3-RELEASE-i386-bootonly.iso', 'mode': 'r','online': '1', 'frontend-id': '87', 'type': 'file'} to/local/domain/0/backend/vbd/87/5632.[2008-04-10 18:00:59 21694] INFO (image:452) spawning device models:/usr/pkg/libexec/qemu-dm ['/usr/pkg/libexec/qemu-dm', '-d', '87','-vcpus', '2', '-boot', 'd', '-acpi', '-domain-name', 'winxp','-vncunused', '-vnclisten', '192.168.3.93']

[2008-04-10 18:00:59 21694] INFO (image:456) device model pid: 26095

[2008-04-10 18:00:59 21694] DEBUG (XendDomainInfo:2086) Storing VMdetails: {'on_xend_stop': 'ignore', 'shadow_memory': '4', 'uuid':'fe036add-6ab1-c0db-a56c-c3369495093e', 'on_reboot': 'restart','start_time': '1207864859.25', 'on_poweroff': 'destroy', 'on_xend_start':'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '2','vcpu_avail': '3', 'image': "(hvm (kernel /usr/pkg/lib/xen/boot/hvmloader)(args 'VNC_VIEWER=192.168.3.93:5501 ') (acpi 1) (apic 0) (boot d)(device_model /usr/pkg/libexec/qemu-dm) (isa 0) (localtime 0) (monitor 0)(nographic 0) (pae 1) (rtc_timeoffset 0) (stdvga 0) (usb 0) (vnc 1)(timer_mode 0) (vncunused 1) (xauthority /root/.Xauthority) (notes(SUSPEND_CANCEL 1)))", 'name': 'winxp'}[2008-04-10 18:00:59 21694] DEBUG (XendDomainInfo:912) Storing domaindetails: {'console/port': '4', 'name': 'winxp', 'console/limit':'1048576', 'vm': '/vm/fe036add-6ab1-c0db-a56c-c3369495093e', 'domid':'87', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online','memory/target': '262144','control/platform-feature-multiprocessor-suspend': '1', 'store/ring-ref':'65534', 'cpu/1/availability': 'online', 'store/port': '3'}[2008-04-10 18:00:59 21694] DEBUG (DevController:117) DevController:writing {'state': '1', 'backend-id': '0', 'backend':'/local/domain/0/backend/console/87/0'} to/local/domain/87/device/console/0.[2008-04-10 18:00:59 21694] DEBUG (DevController:119) DevController:writing {'domain': 'winxp', 'protocol': 'vt100', 'uuid':'5b1088f1-0fb7-076c-b9ec-be078d5b965b', 'frontend':'/local/domain/87/device/console/0', 'state': '1', 'location': '4','online': '1', 'frontend-id': '87'} to/local/domain/0/backend/console/87/0.[2008-04-10 18:00:59 21694] DEBUG (XendDomainInfo:996)XendDomainInfo.handleShutdownWatchxenstore-read: couldn't read path/local/domain/0/backend/console/87/0/type[2008-04-10 18:00:59 21694] DEBUG (DevController:150) Waiting for devicesvif.[2008-04-10 18:00:59 21694] DEBUG (DevController:150) Waiting for devicesusb.[2008-04-10 18:00:59 21694] DEBUG (DevController:150) Waiting for devicesvbd.

[2008-04-10 18:00:59 21694] DEBUG (DevController:155) Waiting for 5632.

[2008-04-10 18:00:59 21694] DEBUG (DevController:589)hotplugStatusCallback /local/domain/0/backend/vbd/87/5632/hotplug-status.


           [2 minutes -- 30 seconds here]

[2008-04-10 18:02:39 21694] DEBUG (XendDomainInfo:1772)XendDomainInfo.destroy: domid=87[2008-04-10 18:02:39 21694] DEBUG (XendDomainInfo:1791)XendDomainInfo.destroyDomain(87)

[2008-04-10 18:02:39 21694] DEBUG (XendDomainInfo:1436) Removing vbd/5632

[2008-04-10 18:02:39 21694] DEBUG (XendDomainInfo:574)XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/5632

[2008-04-10 18:02:39 21694] DEBUG (XendDomainInfo:1436) Removing vkbd/0

[2008-04-10 18:02:39 21694] DEBUG (XendDomainInfo:574)XendDomainInfo.destroyDevice: deviceClass = vkbd, device = vkbd/0

xenstore-read: couldn't read path /local/domain/0/backend/vkbd/87/0/type
[2008-04-10 18:02:39 21694] DEBUG (XendDomainInfo:1436) Removing vfb/0

[2008-04-10 18:02:39 21694] DEBUG (XendDomainInfo:574)XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0

xenstore-read: couldn't read path /local/domain/0/backend/vfb/87/0/params
[2008-04-10 18:02:39 21694] DEBUG (XendDomainInfo:1436) Removing console/0

[2008-04-10 18:02:39 21694] DEBUG (XendDomainInfo:574)XendDomainInfo.destroyDevice: deviceClass = console, device = console/0xenstore-read: couldn't read path/local/domain/0/backend/console/87/0/typeError: Device 5632 (vbd) could not be connected. Hotplug scripts notworking.




=== Startup ===

# /etc/rc.d/xend start && /etc/rc.d/xenbackendd start
Starting xend.
Apr 10 17:59:24 hv01 xenstored: Checking store ...
Apr 10 17:59:24 hv01 xenstored: Checking store complete.
Apr 10 17:59:24 hv01 xenstored: Checking store ...
Apr 10 17:59:24 hv01 xenstored: Checking store complete.
Xend started at Thu Apr 10 17:59:24 2008.
[2008-04-10 17:59:24 21694] INFO (SrvDaemon:331) Xend Daemon started

[2008-04-10 17:59:24 21694] INFO (SrvDaemon:335) Xend changeset:unavailable.

[2008-04-10 17:59:24 21694] INFO (SrvDaemon:342) Xend version: Unknown.
brctl: not found
brctl: not found

[2008-04-10 17:59:24 21694] DEBUG (XendDomainInfo:132)XendDomainInfo.recreate({'max_vcpu_id': 3, 'cpu_time': 464078074839L,'ssidref': 0, 'hvm': 0, 'shutdown_reason': 0, 'dying': 0, 'online_vcpus':1, 'domid': 0, 'paused': 0, 'crashed': 0, 'running': 1, 'maxmem_kb':17179869180L, 'shutdown': 0, 'mem_kb': 262144L, 'handle': [0, 0, 0, 0, 0,0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], 'blocked': 0, 'name': 'Domain-0'})[2008-04-10 17:59:24 21694] INFO (XendDomainInfo:148) Recreating domain 0,UUID 00000000-0000-0000-0000-000000000000. at /local/domain/0[2008-04-10 17:59:24 21694] DEBUG (XendDomainInfo:2086) Storing VMdetails: {'on_xend_stop': 'ignore', 'shadow_memory': '0', 'uuid':'00000000-0000-0000-0000-000000000000', 'on_reboot': 'restart', 'image':'(linux (kernel ))', 'on_poweroff': 'destroy', 'on_xend_start': 'ignore','on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '1','vcpu_avail': '1', 'name': 'Domain-0'}[2008-04-10 17:59:24 21694] DEBUG (XendDomainInfo:912) Storing domaindetails: {'name': 'Domain-0', 'console/limit': '1048576', 'vm':'/vm/00000000-0000-0000-0000-000000000000', 'domid': '0','cpu/0/availability': 'online', 'memory/target': '262144','control/platform-feature-multiprocessor-suspend': '1'}

[2008-04-10 17:59:24 21694] DEBUG (XendDomain:434) Adding Domain: 0

[2008-04-10 17:59:24 21694] DEBUG (XendDomain:379) number of vcpus to useis 0[2008-04-10 17:59:24 21694] WARNING (XendDomain:169) Ignoring dying domain55 from now on[2008-04-10 17:59:24 21694] DEBUG (XendDomainInfo:996)XendDomainInfo.handleShutdownWatch[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call: VBD.set_devicenot found[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call: VBD.set_typenot found[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call:VM.get_auto_power_on not found[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call:VM.set_auto_power_on not found[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call: debug.get_allnot found[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call:console.get_other_config not found[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call:console.set_other_config not found[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call:VIF.get_network not found[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call: VIF.set_devicenot found[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call: VIF.set_MACnot found[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call: VIF.set_MTUnot found[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call:session.get_all_records not found[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call:event.get_record not found[2008-04-10 17:59:24 21694] WARNING (XendAPI:672) API call: event.get_allnot found[2008-04-10 17:59:24 21694] INFO (XMLRPCServer:149) Opening Unix domainsocket XML-RPC server on /var/run/xend/xen-api.sock; authentication hasbeen disabled for this server.[2008-04-10 17:59:24 21694] INFO (XMLRPCServer:149) Opening Unix domainsocket XML-RPC server on /var/run/xend/xmlrpc.sock.

Starting xenbackendd.


=== Config ===


# egrep -vi "^$|^#" xen/xend-config.sxp
(logfile /var/log/xen/xend.log)
(loglevel DEBUG)
(xend-relocation-port 8002)
(xend-relocation-hosts-allow '^localhost$ ^localhost\\.localdomain$')
(dom0-min-mem 196)
(dom0-cpus 0)
(vnc-listen '192.168.3.93')
(vncpasswd '')




# more xen/winxp
kernel = '/usr/pkg/lib/xen/boot/hvmloader'
builder = 'hvm'
memory = '256'
device_model='/usr/pkg/libexec/qemu-dm'
#vifs=1
# Any variety
#disk = [ 'file:/home/seklecki/xp2.iso,hdc:cdrom,r' ]

disk = ['file:/home/seklecki/6.3-RELEASE-i386-bootonly.iso,ioemu:hdc:cdrom,r' ]

#disk = [ 'phy:/dev/cd1a,hdc:cdrom,r' ]

#disk = [ 'file:/opt/domains/winxp/disk.img,hda,w','file:/home/seklecki/xp2.iso,ioemu:hdc:cdrom,r' ]

#name = 'winxp'
#vif = [ 'mac=00:00:a0:c0:c0:c0, bridge=bridge0' ]
boot='d'
vnc = 1
#vncviewer = 1
vcpus = 2
#extra = 'xencons=tty'
#acpi=1
#apic=0
#vfb = ["type=vnc,vncunused=0"]

Follow-Ups:
- Re: First attempt at HVM: vbd problems
  - From: Anzi

Prev by Date: [Fwd: [Xen-users] choose_vnc_display():]
Next by Date: netbsd/amd64 dom0 && opensolaris amd64 domU
Previous by Thread: [Fwd: [Xen-users] choose_vnc_display():]
Next by Thread: Re: First attempt at HVM: vbd problems
Indexes:

Home | Main Index | Thread Index | Old Index