WARNING - OLD ARCHIVES

This is an archived copy of the Xen.org mailing list, which we have preserved to ensure that existing links to archives are not broken. The live archive, which contains the latest emails, can be found at http://lists.xen.org/
   
 
 
Xen 
 
Home Products Support Community News
 
   
 

xen-users

[Xen-users] Re: xenstore-read: couldn't read path /local/domain/1/vm

To: xen-users@xxxxxxxxxxxxxxxxxxx
Subject: [Xen-users] Re: xenstore-read: couldn't read path /local/domain/1/vm
From: Michael Brancato <mike@xxxxxxxxxxxxxxxx>
Date: Fri, 11 Apr 2008 22:32:48 -0400
Delivery-date: Fri, 11 Apr 2008 19:33:36 -0700
Envelope-to: www-data@xxxxxxxxxxxxxxxxxx
In-reply-to: <47FFED5A.1040000@xxxxxxxxxxxxxxxx>
List-help: <mailto:xen-users-request@lists.xensource.com?subject=help>
List-id: Xen user discussion <xen-users.lists.xensource.com>
List-post: <mailto:xen-users@lists.xensource.com>
List-subscribe: <http://lists.xensource.com/cgi-bin/mailman/listinfo/xen-users>, <mailto:xen-users-request@lists.xensource.com?subject=subscribe>
List-unsubscribe: <http://lists.xensource.com/cgi-bin/mailman/listinfo/xen-users>, <mailto:xen-users-request@lists.xensource.com?subject=unsubscribe>
References: <47FEC249.5040205@xxxxxxxxxxxxxxxx> <47FFED5A.1040000@xxxxxxxxxxxxxxxx>
Sender: xen-users-bounces@xxxxxxxxxxxxxxxxxxx
User-agent: Thunderbird 2.0.0.12 (Macintosh/20080213)
I noticed that my solution of pre-mounting to /dev/loop* wasn't 100% reliable. I did some more digging and I've kept coming back to the VBD. Specifically the hda image file. I tried booting another HVM guest I hadn't used in a while and it "just worked". At first I thought maybe I had hit some name length limit or too many directory levels, but that is not the case. So I copied the working HVM's hda .img file to my 'farnsworth' domain's directory, and it too worked! So then that got me thinking, why are the sparse files I'm creating causing Xen to immediately die (poweroff). Maybe this is best illustrated with the console output below...

mike@fry:/data/xen/domains/farnsworth$ sudo xm list
Name ID Mem VCPUs State Time(s) Domain-0 0 1475 2 r----- 407.8
mike@fry:/data/xen/domains/farnsworth$ sudo xm create farnsworth.conf
Using config file "./farnsworth.conf".
Started domain farnsworth
mike@fry:/data/xen/domains/farnsworth$ sudo xm list
Name ID Mem VCPUs State Time(s) Domain-0 0 1475 2 r----- 410.6 farnsworth 19 512 1 r----- 1.7
mike@fry:/data/xen/domains/farnsworth$ sudo xm destroy farnsworth
mike@fry:/data/xen/domains/farnsworth$ mv farnsworth.img farnsworth.img.old
mike@fry:/data/xen/domains/farnsworth$ dd if=/dev/zero of=farnsworth.img bs=1M seek=15G count=0
0+0 records in
0+0 records out
0 bytes (0 B) copied, 3.0333e-05 s, 0.0 kB/s
mike@fry:/data/xen/domains/farnsworth$ sudo xm list
Name ID Mem VCPUs State Time(s) Domain-0 0 1475 2 r----- 413.7
mike@fry:/data/xen/domains/farnsworth$ sudo xm create farnsworth.conf
Using config file "./farnsworth.conf".
Started domain farnsworth
mike@fry:/data/xen/domains/farnsworth$ sudo xm list
Name ID Mem VCPUs State Time(s) Domain-0 0 1475 2 r----- 416.5
mike@fry:/data/xen/domains/farnsworth$

I've tried filling it with data, formatting it, etc and it always dies. But if I put that old image file back in place, it just works. This provides a temporary workaround for me, but why on earth are the files DD is creating causing Xen to poweroff?

Here is my config:
kernel = "/usr/lib/xen/boot/hvmloader"
builder='hvm'
memory = 512
name = "farnsworth"
uuid = "82458fe7-6f48-4736-9f3b-2cfcc28a641e"
vif = [ 'type=ioemu, mac=00:16:3E:D4:73:B2, bridge=xenbr0, model=ne2k_pci' ]
disk = [ 'tap:aio:/var/xen/domains/farnsworth/farnsworth.img,hda,w', 'tap:aio:/data/storage/images/CentOS-5.1-x86_64-netinstall.iso,hdb:cdrom,r' ]
device_model = '/usr/lib/xen/bin/qemu-dm'
boot="dc"


I still get errors in my xen-hotplug.log whether it boots or not. Here is the output from xend.log (qemu-dm is the same whether the domain succeeds or fails): [2008-04-11 22:17:09 5757] DEBUG (XendDomainInfo:84) XendDomainInfo.create(['vm', ['name', 'farnsworth'], ['memory', 512], ['vcpus', 1], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['uuid', '82458fe7-6f48-4736-9f3b-2cfcc28a641e'], ['image', ['hvm', ['kernel', '/usr/lib/xen/boot/hvmloader'], ['device_model', '/usr/lib/xen/bin/qemu-dm'], ['pae', 1], ['vcpus', 1], ['boot', 'dc'], ['fda', ''], ['fdb', ''], ['timer_mode', 0], ['localtime', 0], ['serial', ''], ['stdvga', 0], ['isa', 0], ['nographic', 0], ['soundhw', ''], ['vnc', 1], ['vncunused', 1], ['display', 'localhost:10.0'], ['xauthority', '/home/mike/.Xauthority'], ['rtc_timeoffset', '0'], ['monitor', 0], ['acpi', 1], ['apic', 1], ['usb', 0], ['usbdevice', ''], ['keymap', ''], ['pci', []], ['guest_os_type', 'default']]], ['device', ['tap', ['uname', 'tap:aio:/var/xen/domains/farnsworth/farnsworth.img'], ['dev', 'hda'], ['mode', 'w']]], ['device', ['tap', ['uname', 'tap:aio:/data/storage/images/CentOS-5.1-x86_64-netinstall.iso'], ['dev', 'hdb:cdrom'], ['mode', 'r']]], ['device', ['vif', ['bridge', 'xenbr0'], ['mac', '00:16:3E:D4:73:B2'], ['type', 'ioemu'], ['model', 'ne2k_pci']]]]) [2008-04-11 22:17:09 5757] DEBUG (XendDomainInfo:1637) XendDomainInfo.constructDomain [2008-04-11 22:17:09 5757] DEBUG (balloon:151) Balloon: 542400 KiB free; need 2048; done.
[2008-04-11 22:17:09 5757] DEBUG (XendDomain:443) Adding Domain: 19
[2008-04-11 22:17:09 5757] DEBUG (XendDomainInfo:1722) XendDomainInfo.initDomain: 19 256 [2008-04-11 22:17:09 5757] DEBUG (image:228) No VNC passwd configured for vfb access
[2008-04-11 22:17:09 5757] DEBUG (image:464) args: boot, val: dc
[2008-04-11 22:17:09 5757] DEBUG (image:464) args: fda, val: None
[2008-04-11 22:17:09 5757] DEBUG (image:464) args: fdb, val: None
[2008-04-11 22:17:09 5757] DEBUG (image:464) args: soundhw, val: None
[2008-04-11 22:17:09 5757] DEBUG (image:464) args: localtime, val: 0
[2008-04-11 22:17:09 5757] DEBUG (image:464) args: serial, val: None
[2008-04-11 22:17:09 5757] DEBUG (image:464) args: std-vga, val: 0
[2008-04-11 22:17:09 5757] DEBUG (image:464) args: isa, val: 0
[2008-04-11 22:17:09 5757] DEBUG (image:464) args: acpi, val: 1
[2008-04-11 22:17:09 5757] DEBUG (image:464) args: usb, val: 0
[2008-04-11 22:17:09 5757] DEBUG (image:464) args: usbdevice, val: None
[2008-04-11 22:17:09 5757] DEBUG (image:464) args: pci, val: []
[2008-04-11 22:17:09 5757] DEBUG (XendDomainInfo:1754) _initDomain:shadow_memory=0x0, memory_static_max=0x20000000, memory_static_min=0x0. [2008-04-11 22:17:09 5757] DEBUG (balloon:151) Balloon: 541108 KiB free; need 538112; done. [2008-04-11 22:17:09 5757] INFO (image:139) buildDomain os=hvm dom=19 vcpus=1
[2008-04-11 22:17:09 5757] DEBUG (image:514) domid          = 19
[2008-04-11 22:17:09 5757] DEBUG (image:515) image = /usr/lib/xen/boot/hvmloader
[2008-04-11 22:17:09 5757] DEBUG (image:516) store_evtchn   = 2
[2008-04-11 22:17:09 5757] DEBUG (image:517) memsize        = 512
[2008-04-11 22:17:09 5757] DEBUG (image:518) vcpus          = 1
[2008-04-11 22:17:09 5757] DEBUG (image:519) acpi           = 1
[2008-04-11 22:17:09 5757] DEBUG (image:520) apic           = 1
[2008-04-11 22:17:10 5757] INFO (XendDomainInfo:1530) createDevice: vfb : {'vncunused': 1, 'other_config': {'vncunused': 1, 'type': 'vnc'}, 'type': 'vnc', 'uuid': 'b6bc5ec2-c937-8ea1-22e5-2fd41a82a6b8'} [2008-04-11 22:17:10 5757] DEBUG (DevController:117) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/19/0'} to /local/domain/19/device/vfb/0. [2008-04-11 22:17:10 5757] DEBUG (DevController:119) DevController: writing {'vncunused': '1', 'domain': 'farnsworth', 'frontend': '/local/domain/19/device/vfb/0', 'uuid': 'b6bc5ec2-c937-8ea1-22e5-2fd41a82a6b8', 'state': '1', 'online': '1', 'frontend-id': '19', 'type': 'vnc'} to /local/domain/0/backend/vfb/19/0. [2008-04-11 22:17:10 5757] INFO (XendDomainInfo:1530) createDevice: tap : {'bootable': 1, 'uname': 'tap:aio:/var/xen/domains/farnsworth/farnsworth.img', 'mode': 'w', 'dev': 'hda', 'uuid': '001fcbff-58e5-2273-d1c3-17640f15ba94'} [2008-04-11 22:17:10 5757] DEBUG (DevController:117) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/tap/19/768'} to /local/domain/19/device/vbd/768. [2008-04-11 22:17:10 5757] DEBUG (DevController:119) DevController: writing {'domain': 'farnsworth', 'frontend': '/local/domain/19/device/vbd/768', 'uuid': '001fcbff-58e5-2273-d1c3-17640f15ba94', 'dev': 'hda', 'state': '1', 'params': 'aio:/var/xen/domains/farnsworth/farnsworth.img', 'mode': 'w', 'online': '1', 'frontend-id': '19', 'type': 'tap'} to /local/domain/0/backend/tap/19/768. [2008-04-11 22:17:10 5757] INFO (XendDomainInfo:1530) createDevice: tap : {'bootable': 0, 'uname': 'tap:aio:/data/storage/images/CentOS-5.1-x86_64-netinstall.iso', 'mode': 'r', 'dev': 'hdb:cdrom', 'uuid': '480fa743-00f0-7ca2-8f96-c33f84fb0854'} [2008-04-11 22:17:10 5757] DEBUG (DevController:117) DevController: writing {'backend-id': '0', 'virtual-device': '832', 'device-type': 'cdrom', 'state': '1', 'backend': '/local/domain/0/backend/tap/19/832'} to /local/domain/19/device/vbd/832. [2008-04-11 22:17:10 5757] DEBUG (DevController:119) DevController: writing {'domain': 'farnsworth', 'frontend': '/local/domain/19/device/vbd/832', 'uuid': '480fa743-00f0-7ca2-8f96-c33f84fb0854', 'dev': 'hdb', 'state': '1', 'params': 'aio:/data/storage/images/CentOS-5.1-x86_64-netinstall.iso', 'mode': 'r', 'online': '1', 'frontend-id': '19', 'type': 'tap'} to /local/domain/0/backend/tap/19/832. [2008-04-11 22:17:10 5757] INFO (XendDomainInfo:1530) createDevice: vif : {'bridge': 'xenbr0', 'mac': '00:16:3E:D4:73:B2', 'type': 'ioemu', 'model': 'ne2k_pci', 'uuid': 'feecc00b-913b-5d0a-365c-ed49756ad484'} [2008-04-11 22:17:10 5757] DEBUG (DevController:117) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vif/19/0'} to /local/domain/19/device/vif/0. [2008-04-11 22:17:10 5757] DEBUG (DevController:119) DevController: writing {'bridge': 'xenbr0', 'domain': 'farnsworth', 'handle': '0', 'uuid': 'feecc00b-913b-5d0a-365c-ed49756ad484', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/19/device/vif/0', 'mac': '00:16:3E:D4:73:B2', 'online': '1', 'frontend-id': '19', 'model': 'ne2k_pci', 'type': 'ioemu'} to /local/domain/0/backend/vif/19/0. [2008-04-11 22:17:10 5757] INFO (image:283) spawning device models: /usr/lib/xen/bin/qemu-dm ['/usr/lib/xen/bin/qemu-dm', '-d', '19', '-domain-name', 'farnsworth', '-vnc', '127.0.0.1:0', '-vncunused', '-vcpus', '1', '-boot', 'dc', '-acpi', '-net', 'nic,vlan=1,macaddr=00:16:3E:D4:73:B2,model=ne2k_pci', '-net', 'tap,vlan=1,bridge=xenbr0', '-M', 'xenfv']
[2008-04-11 22:17:10 5757] INFO (image:287) device model pid: 17152
[2008-04-11 22:17:10 5757] DEBUG (XendDomainInfo:2211) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '5', 'uuid': '82458fe7-6f48-4736-9f3b-2cfcc28a641e', 'on_reboot': 'restart', 'start_time': '1207966630.24', 'on_poweroff': 'destroy', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'image': '(hvm (kernel /usr/lib/xen/boot/hvmloader) (acpi 1) (apic 1) (boot dc) (device_model /usr/lib/xen/bin/qemu-dm) (display localhost:10.0) (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 /home/mike/.Xauthority) (pci ()) (guest_os_type default) (notes (SUSPEND_CANCEL 1)))', 'name': 'farnsworth'} [2008-04-11 22:17:10 5757] DEBUG (XendDomainInfo:1027) Storing domain details: {'console/port': '3', 'name': 'farnsworth', 'console/limit': '1048576', 'store/port': '2', 'vm': '/vm/82458fe7-6f48-4736-9f3b-2cfcc28a641e', 'domid': '19', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'memory/target': '524288', 'control/platform-feature-multiprocessor-suspend': '1', 'store/ring-ref': '131070', 'console/type': 'ioemu'} [2008-04-11 22:17:10 5757] DEBUG (DevController:117) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/19/0'} to /local/domain/19/device/console/0. [2008-04-11 22:17:10 5757] DEBUG (DevController:119) DevController: writing {'domain': 'farnsworth', 'protocol': 'vt100', 'uuid': '99652afd-2159-9d37-0d45-703bd78adcea', 'frontend': '/local/domain/19/device/console/0', 'state': '1', 'location': '3', 'online': '1', 'frontend-id': '19'} to /local/domain/0/backend/console/19/0. [2008-04-11 22:17:10 5757] DEBUG (XendDomainInfo:1111) XendDomainInfo.handleShutdownWatch [2008-04-11 22:17:10 5757] DEBUG (DevController:150) Waiting for devices vif.
[2008-04-11 22:17:10 5757] DEBUG (DevController:155) Waiting for 0.
[2008-04-11 22:17:10 5757] DEBUG (DevController:594) hotplugStatusCallback /local/domain/0/backend/vif/19/0/hotplug-status. [2008-04-11 22:17:10 5757] DEBUG (DevController:608) hotplugStatusCallback 1. [2008-04-11 22:17:10 5757] DEBUG (DevController:150) Waiting for devices vbd. [2008-04-11 22:17:10 5757] DEBUG (DevController:150) Waiting for devices irq. [2008-04-11 22:17:10 5757] DEBUG (DevController:150) Waiting for devices vkbd. [2008-04-11 22:17:10 5757] DEBUG (DevController:150) Waiting for devices vfb. [2008-04-11 22:17:10 5757] DEBUG (DevController:150) Waiting for devices console.
[2008-04-11 22:17:10 5757] DEBUG (DevController:155) Waiting for 0.
[2008-04-11 22:17:10 5757] DEBUG (DevController:150) Waiting for devices pci. [2008-04-11 22:17:10 5757] DEBUG (DevController:150) Waiting for devices ioports. [2008-04-11 22:17:10 5757] DEBUG (DevController:150) Waiting for devices tap.
[2008-04-11 22:17:10 5757] DEBUG (DevController:155) Waiting for 768.
[2008-04-11 22:17:10 5757] DEBUG (DevController:594) hotplugStatusCallback /local/domain/0/backend/tap/19/768/hotplug-status. [2008-04-11 22:17:10 5757] DEBUG (DevController:608) hotplugStatusCallback 1.
[2008-04-11 22:17:10 5757] DEBUG (DevController:155) Waiting for 832.
[2008-04-11 22:17:10 5757] DEBUG (DevController:594) hotplugStatusCallback /local/domain/0/backend/tap/19/832/hotplug-status. [2008-04-11 22:17:10 5757] DEBUG (DevController:608) hotplugStatusCallback 1. [2008-04-11 22:17:10 5757] DEBUG (DevController:150) Waiting for devices vtpm. [2008-04-11 22:17:10 5757] INFO (XendDomain:1165) Domain farnsworth (19) unpaused. [2008-04-11 22:17:24 5757] DEBUG (XendDomainInfo:1913) XendDomainInfo.destroy: domid=19 [2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:1930) XendDomainInfo.destroyDomain(19) [2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:1548) Destroying device model
[2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:1555) Releasing devices
[2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:1561) Removing vif/0
[2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:1561) Removing vbd/768
[2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/768
[2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:1561) Removing vbd/832
[2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/832
[2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:1561) Removing vfb/0
[2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0
[2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:1561) Removing console/0
[2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
[2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:1553) No device model
[2008-04-11 22:17:25 5757] DEBUG (XendDomainInfo:1555) Releasing devices
[2008-04-11 22:18:19 5757] DEBUG (XendDomainInfo:84) XendDomainInfo.create(['vm', ['name', 'farnsworth'], ['memory', 512], ['vcpus', 1], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['uuid', '82458fe7-6f48-4736-9f3b-2cfcc28a641e'], ['image', ['hvm', ['kernel', '/usr/lib/xen/boot/hvmloader'], ['device_model', '/usr/lib/xen/bin/qemu-dm'], ['pae', 1], ['vcpus', 1], ['boot', 'dc'], ['fda', ''], ['fdb', ''], ['timer_mode', 0], ['localtime', 0], ['serial', ''], ['stdvga', 0], ['isa', 0], ['nographic', 0], ['soundhw', ''], ['vnc', 1], ['vncunused', 1], ['display', 'localhost:10.0'], ['xauthority', '/home/mike/.Xauthority'], ['rtc_timeoffset', '0'], ['monitor', 0], ['acpi', 1], ['apic', 1], ['usb', 0], ['usbdevice', ''], ['keymap', ''], ['pci', []], ['guest_os_type', 'default']]], ['device', ['tap', ['uname', 'tap:aio:/var/xen/domains/farnsworth/farnsworth.img'], ['dev', 'hda'], ['mode', 'w']]], ['device', ['tap', ['uname', 'tap:aio:/data/storage/images/CentOS-5.1-x86_64-netinstall.iso'], ['dev', 'hdb:cdrom'], ['mode', 'r']]], ['device', ['vif', ['bridge', 'xenbr0'], ['mac', '00:16:3E:D4:73:B2'], ['type', 'ioemu'], ['model', 'ne2k_pci']]]]) [2008-04-11 22:18:19 5757] DEBUG (XendDomainInfo:1637) XendDomainInfo.constructDomain [2008-04-11 22:18:19 5757] DEBUG (balloon:151) Balloon: 542400 KiB free; need 2048; done.
[2008-04-11 22:18:19 5757] DEBUG (XendDomain:443) Adding Domain: 20
[2008-04-11 22:18:19 5757] DEBUG (XendDomainInfo:1722) XendDomainInfo.initDomain: 20 256 [2008-04-11 22:18:19 5757] DEBUG (image:228) No VNC passwd configured for vfb access
[2008-04-11 22:18:19 5757] DEBUG (image:464) args: boot, val: dc
[2008-04-11 22:18:19 5757] DEBUG (image:464) args: fda, val: None
[2008-04-11 22:18:19 5757] DEBUG (image:464) args: fdb, val: None
[2008-04-11 22:18:19 5757] DEBUG (image:464) args: soundhw, val: None
[2008-04-11 22:18:19 5757] DEBUG (image:464) args: localtime, val: 0
[2008-04-11 22:18:19 5757] DEBUG (image:464) args: serial, val: None
[2008-04-11 22:18:19 5757] DEBUG (image:464) args: std-vga, val: 0
[2008-04-11 22:18:19 5757] DEBUG (image:464) args: isa, val: 0
[2008-04-11 22:18:19 5757] DEBUG (image:464) args: acpi, val: 1
[2008-04-11 22:18:19 5757] DEBUG (image:464) args: usb, val: 0
[2008-04-11 22:18:19 5757] DEBUG (image:464) args: usbdevice, val: None
[2008-04-11 22:18:19 5757] DEBUG (image:464) args: pci, val: []
[2008-04-11 22:18:19 5757] DEBUG (XendDomainInfo:1754) _initDomain:shadow_memory=0x0, memory_static_max=0x20000000, memory_static_min=0x0. [2008-04-11 22:18:19 5757] DEBUG (balloon:151) Balloon: 541108 KiB free; need 538112; done. [2008-04-11 22:18:19 5757] INFO (image:139) buildDomain os=hvm dom=20 vcpus=1
[2008-04-11 22:18:19 5757] DEBUG (image:514) domid          = 20
[2008-04-11 22:18:19 5757] DEBUG (image:515) image = /usr/lib/xen/boot/hvmloader
[2008-04-11 22:18:19 5757] DEBUG (image:516) store_evtchn   = 2
[2008-04-11 22:18:19 5757] DEBUG (image:517) memsize        = 512
[2008-04-11 22:18:19 5757] DEBUG (image:518) vcpus          = 1
[2008-04-11 22:18:19 5757] DEBUG (image:519) acpi           = 1
[2008-04-11 22:18:19 5757] DEBUG (image:520) apic           = 1
[2008-04-11 22:18:20 5757] INFO (XendDomainInfo:1530) createDevice: vfb : {'vncunused': 1, 'other_config': {'vncunused': 1, 'type': 'vnc'}, 'type': 'vnc', 'uuid': '4dd2adcd-7a26-31b8-2cf8-22f33593c44c'} [2008-04-11 22:18:20 5757] DEBUG (DevController:117) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/20/0'} to /local/domain/20/device/vfb/0. [2008-04-11 22:18:20 5757] DEBUG (DevController:119) DevController: writing {'vncunused': '1', 'domain': 'farnsworth', 'frontend': '/local/domain/20/device/vfb/0', 'uuid': '4dd2adcd-7a26-31b8-2cf8-22f33593c44c', 'state': '1', 'online': '1', 'frontend-id': '20', 'type': 'vnc'} to /local/domain/0/backend/vfb/20/0. [2008-04-11 22:18:20 5757] INFO (XendDomainInfo:1530) createDevice: tap : {'bootable': 1, 'uname': 'tap:aio:/var/xen/domains/farnsworth/farnsworth.img', 'mode': 'w', 'dev': 'hda', 'uuid': '5252e90f-4df7-9979-9aa4-321ce221b5f6'} [2008-04-11 22:18:20 5757] DEBUG (DevController:117) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/tap/20/768'} to /local/domain/20/device/vbd/768. [2008-04-11 22:18:20 5757] DEBUG (DevController:119) DevController: writing {'domain': 'farnsworth', 'frontend': '/local/domain/20/device/vbd/768', 'uuid': '5252e90f-4df7-9979-9aa4-321ce221b5f6', 'dev': 'hda', 'state': '1', 'params': 'aio:/var/xen/domains/farnsworth/farnsworth.img', 'mode': 'w', 'online': '1', 'frontend-id': '20', 'type': 'tap'} to /local/domain/0/backend/tap/20/768. [2008-04-11 22:18:20 5757] INFO (XendDomainInfo:1530) createDevice: tap : {'bootable': 0, 'uname': 'tap:aio:/data/storage/images/CentOS-5.1-x86_64-netinstall.iso', 'mode': 'r', 'dev': 'hdb:cdrom', 'uuid': '560bbaec-8f5c-7936-9a1e-9a071ee978e0'} [2008-04-11 22:18:20 5757] DEBUG (DevController:117) DevController: writing {'backend-id': '0', 'virtual-device': '832', 'device-type': 'cdrom', 'state': '1', 'backend': '/local/domain/0/backend/tap/20/832'} to /local/domain/20/device/vbd/832. [2008-04-11 22:18:20 5757] DEBUG (DevController:119) DevController: writing {'domain': 'farnsworth', 'frontend': '/local/domain/20/device/vbd/832', 'uuid': '560bbaec-8f5c-7936-9a1e-9a071ee978e0', 'dev': 'hdb', 'state': '1', 'params': 'aio:/data/storage/images/CentOS-5.1-x86_64-netinstall.iso', 'mode': 'r', 'online': '1', 'frontend-id': '20', 'type': 'tap'} to /local/domain/0/backend/tap/20/832. [2008-04-11 22:18:20 5757] INFO (XendDomainInfo:1530) createDevice: vif : {'bridge': 'xenbr0', 'mac': '00:16:3E:D4:73:B2', 'type': 'ioemu', 'model': 'ne2k_pci', 'uuid': '3b71874b-b694-5fba-4a55-669b9d1e3b2a'} [2008-04-11 22:18:20 5757] DEBUG (DevController:117) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vif/20/0'} to /local/domain/20/device/vif/0. [2008-04-11 22:18:20 5757] DEBUG (DevController:119) DevController: writing {'bridge': 'xenbr0', 'domain': 'farnsworth', 'handle': '0', 'uuid': '3b71874b-b694-5fba-4a55-669b9d1e3b2a', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/20/device/vif/0', 'mac': '00:16:3E:D4:73:B2', 'online': '1', 'frontend-id': '20', 'model': 'ne2k_pci', 'type': 'ioemu'} to /local/domain/0/backend/vif/20/0. [2008-04-11 22:18:20 5757] INFO (image:283) spawning device models: /usr/lib/xen/bin/qemu-dm ['/usr/lib/xen/bin/qemu-dm', '-d', '20', '-domain-name', 'farnsworth', '-vnc', '127.0.0.1:0', '-vncunused', '-vcpus', '1', '-boot', 'dc', '-acpi', '-net', 'nic,vlan=1,macaddr=00:16:3E:D4:73:B2,model=ne2k_pci', '-net', 'tap,vlan=1,bridge=xenbr0', '-M', 'xenfv']
[2008-04-11 22:18:20 5757] INFO (image:287) device model pid: 17546
[2008-04-11 22:18:20 5757] DEBUG (XendDomainInfo:2211) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '5', 'uuid': '82458fe7-6f48-4736-9f3b-2cfcc28a641e', 'on_reboot': 'restart', 'start_time': '1207966700.28', 'on_poweroff': 'destroy', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'image': '(hvm (kernel /usr/lib/xen/boot/hvmloader) (acpi 1) (apic 1) (boot dc) (device_model /usr/lib/xen/bin/qemu-dm) (display localhost:10.0) (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 /home/mike/.Xauthority) (pci ()) (guest_os_type default) (notes (SUSPEND_CANCEL 1)))', 'name': 'farnsworth'} [2008-04-11 22:18:20 5757] DEBUG (XendDomainInfo:1027) Storing domain details: {'console/port': '3', 'name': 'farnsworth', 'console/limit': '1048576', 'store/port': '2', 'vm': '/vm/82458fe7-6f48-4736-9f3b-2cfcc28a641e', 'domid': '20', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'memory/target': '524288', 'control/platform-feature-multiprocessor-suspend': '1', 'store/ring-ref': '131070', 'console/type': 'ioemu'} [2008-04-11 22:18:20 5757] DEBUG (DevController:117) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/20/0'} to /local/domain/20/device/console/0. [2008-04-11 22:18:20 5757] DEBUG (DevController:119) DevController: writing {'domain': 'farnsworth', 'protocol': 'vt100', 'uuid': '6aeb50c6-3863-09d5-60ca-af40636ee011', 'frontend': '/local/domain/20/device/console/0', 'state': '1', 'location': '3', 'online': '1', 'frontend-id': '20'} to /local/domain/0/backend/console/20/0. [2008-04-11 22:18:20 5757] DEBUG (XendDomainInfo:1111) XendDomainInfo.handleShutdownWatch [2008-04-11 22:18:20 5757] DEBUG (DevController:150) Waiting for devices vif.
[2008-04-11 22:18:20 5757] DEBUG (DevController:155) Waiting for 0.
[2008-04-11 22:18:20 5757] DEBUG (DevController:594) hotplugStatusCallback /local/domain/0/backend/vif/20/0/hotplug-status. [2008-04-11 22:18:20 5757] DEBUG (DevController:594) hotplugStatusCallback /local/domain/0/backend/vif/20/0/hotplug-status. [2008-04-11 22:18:20 5757] DEBUG (DevController:608) hotplugStatusCallback 1. [2008-04-11 22:18:20 5757] DEBUG (DevController:150) Waiting for devices vbd. [2008-04-11 22:18:20 5757] DEBUG (DevController:150) Waiting for devices irq. [2008-04-11 22:18:20 5757] DEBUG (DevController:150) Waiting for devices vkbd. [2008-04-11 22:18:20 5757] DEBUG (DevController:150) Waiting for devices vfb. [2008-04-11 22:18:20 5757] DEBUG (DevController:150) Waiting for devices console.
[2008-04-11 22:18:20 5757] DEBUG (DevController:155) Waiting for 0.
[2008-04-11 22:18:20 5757] DEBUG (DevController:150) Waiting for devices pci. [2008-04-11 22:18:20 5757] DEBUG (DevController:150) Waiting for devices ioports. [2008-04-11 22:18:20 5757] DEBUG (DevController:150) Waiting for devices tap.
[2008-04-11 22:18:20 5757] DEBUG (DevController:155) Waiting for 768.
[2008-04-11 22:18:20 5757] DEBUG (DevController:594) hotplugStatusCallback /local/domain/0/backend/tap/20/768/hotplug-status. [2008-04-11 22:18:20 5757] DEBUG (DevController:608) hotplugStatusCallback 1.
[2008-04-11 22:18:20 5757] DEBUG (DevController:155) Waiting for 832.
[2008-04-11 22:18:20 5757] DEBUG (DevController:594) hotplugStatusCallback /local/domain/0/backend/tap/20/832/hotplug-status. [2008-04-11 22:18:20 5757] DEBUG (DevController:608) hotplugStatusCallback 1. [2008-04-11 22:18:20 5757] DEBUG (DevController:150) Waiting for devices vtpm. [2008-04-11 22:18:20 5757] INFO (XendDomain:1165) Domain farnsworth (20) unpaused. [2008-04-11 22:18:20 5757] INFO (XendDomainInfo:1299) Domain has shutdown: name=farnsworth id=20 reason=poweroff. [2008-04-11 22:18:20 5757] DEBUG (XendDomainInfo:1913) XendDomainInfo.destroy: domid=20 [2008-04-11 22:18:20 5757] DEBUG (XendDomainInfo:1930) XendDomainInfo.destroyDomain(20) [2008-04-11 22:18:21 5757] DEBUG (XendDomainInfo:1548) Destroying device model
[2008-04-11 22:18:21 5757] DEBUG (XendDomainInfo:1555) Releasing devices
[2008-04-11 22:18:21 5757] DEBUG (XendDomainInfo:1561) Removing vif/0
[2008-04-11 22:18:21 5757] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2008-04-11 22:18:21 5757] DEBUG (XendDomainInfo:1561) Removing vbd/768
[2008-04-11 22:18:21 5757] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/768
[2008-04-11 22:18:21 5757] DEBUG (XendDomainInfo:1561) Removing vbd/832
[2008-04-11 22:18:21 5757] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/832
[2008-04-11 22:18:21 5757] DEBUG (XendDomainInfo:1561) Removing vfb/0
[2008-04-11 22:18:21 5757] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0
[2008-04-11 22:18:21 5757] DEBUG (XendDomainInfo:1561) Removing console/0
[2008-04-11 22:18:21 5757] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0



Michael Brancato wrote:
I tried again using phy: vbds manually setup on /dev/loop* and the domain booted. I wonder where the cause of this problem is. I'm using the ubuntu xen 3.2.0 packages and there are several other bugs with these packages currently reported for Hardy, I wonder if this is a problem with the package or upstream Xen?

Michael Brancato wrote:
I'm trying to boot an HVM guest but it immediately shutsdown with few errors. I originally thought it had something to do with file backed
vbd's but I used tap:aio and even used losetup manually to attach to
/dev/loop* and used phy: backed vbds.  All produced near-identical
results.  From what I can tell there are not really any errors being
reported other than in xen-hotplug.log. If anyone can see something wrong please let me know.

Output from xen-hotplug.log
Nothing to flush.
xenstore-read: couldn't read path /local/domain/1/vm
xenstore-read: couldn't read path /local/domain/1/vm


Here is the output from qemu-dm log:
Strip off blktap sub-type prefix to /data/xen/domains/farnsworth/farnsworth.img Strip off blktap sub-type prefix to /data/storage/images/CentOS-5.1-x86_64-netinstall.iso
Watching /local/domain/0/device-model/1/logdirty/next-active
Watching /local/domain/0/device-model/1/command
shift keysym 003e keycode 86
shift keysym 00a6 keycode 86
keypad keysym ffae keycode 83
keypad keysym ffac keycode 83
keypad keysym ffb0 keycode 82
keypad keysym ffb1 keycode 79
keypad keysym ffb2 keycode 80
keypad keysym ffb3 keycode 81
keypad keysym ffb4 keycode 75
keypad keysym ffb5 keycode 76
keypad keysym ffb6 keycode 77
keypad keysym ffb7 keycode 71
keypad keysym ffb8 keycode 72
keypad keysym ffb9 keycode 73
shift keysym 0021 keycode 2
shift keysym 0040 keycode 3
shift keysym 0023 keycode 4
shift keysym 0024 keycode 5
shift keysym 0025 keycode 6
shift keysym 005e keycode 7
shift keysym 0026 keycode 8
shift keysym 002a keycode 9
shift keysym 0028 keycode 10
shift keysym 0029 keycode 11
shift keysym 005f keycode 12
shift keysym 002b keycode 13
shift keysym 007b keycode 26
shift keysym 007d keycode 27
shift keysym 003a keycode 39
shift keysym 0022 keycode 40
shift keysym 007e keycode 41
shift keysym 007c keycode 43
shift keysym 003c keycode 51
shift keysym 003e keycode 52
shift keysym 003f keycode 53
qemu_map_cache_init nr_buckets = 10000 size 3145728
shared page at pfn ffff
buffered io page at pfn fffd
Time offset set 0
Register xen platform.
Done register platform.


Also, here is what is logged in syslog:
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Received a poll for a new vbd
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Created /dev/xen/blktap1 device
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Received device id 1 and major 253, sent domid 1 and be_id 768
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Detected handle: [aio]
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Process does not exist:
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Created /var/run/tap directory
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Launching process, CMDLINE [tapdisk /var/run/tap/tapctrlwrite1 /var/run/tap/tapctrlread1]
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Write_msg called: CTLMSG_PID
Apr 10 21:11:44 fry TAPDISK[6184]: Tapdisk: Received msg, len 16, type 9, UID 0
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Received CTLMSG_PID_RSP
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: ^IPID: [6184]
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Write_msg called: CTLMSG_PARAMS, sending [aio:/data/xen/domains/farnsworth/farnsworth.img, /data/xen/domains/farnsworth/farnsworth.img]
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Generated cookie, 0
Apr 10 21:11:44 fry TAPDISK[6184]: Tapdisk: Received msg, len 60, type 1, UID 0 Apr 10 21:11:44 fry TAPDISK[6184]: Received CTLMSG_PARAMS: [/data/xen/domains/farnsworth/farnsworth.img] Apr 10 21:11:44 fry TAPDISK[6184]: Loaded driver: name [tapdisk_aio], type [0] Apr 10 21:11:44 fry TAPDISK[6184]: block-aio open('/data/xen/domains/farnsworth/farnsworth.img') Apr 10 21:11:44 fry TAPDISK[6184]: WARNING: Accessing image withoutO_DIRECT! (/data/xen/domains/farnsworth/farnsworth.img) Apr 10 21:11:44 fry TAPDISK[6184]: Image size: ^Ipre sector_shift [16106127360] ^Ipost sector_shift [31457280]
Apr 10 21:11:44 fry TAPDISK[6184]: Adding fd_list_entry
Apr 10 21:11:44 fry TAPDISK[6184]: Entered cookie 0
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Received CTLMSG_IMG: 31457280, 512, 0
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Received a poll for a new devmap
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Write_msg called: CTLMSG_NEWDEV
Apr 10 21:11:44 fry TAPDISK[6184]: Tapdisk: Received msg, len 20, type 4, UID 0
Apr 10 21:11:44 fry TAPDISK[6184]: Retrieving state, cookie 0.....[OK]
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Received CTLMSG_NEWDEV_RSP
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Exiting map_new_blktapctrl
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Received a poll for a new vbd
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Created /dev/xen/blktap2 device
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Received device id 2 and major 253, sent domid 1 and be_id 832
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Detected handle: [aio]
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Process does not exist:
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Launching process, CMDLINE [tapdisk /var/run/tap/tapctrlwrite2 /var/run/tap/tapctrlread2]
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Write_msg called: CTLMSG_PID
Apr 10 21:11:44 fry TAPDISK[6205]: Tapdisk: Received msg, len 16, type 9, UID 1
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Received CTLMSG_PID_RSP
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: ^IPID: [6205]
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Write_msg called: CTLMSG_PARAMS, sending [aio:/data/storage/images/CentOS-5.1-x86_64-netinstall.iso, /data/storage/images/CentOS-5.1-x86_64-netinstall.iso]
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Generated cookie, 1
Apr 10 21:11:44 fry TAPDISK[6205]: Tapdisk: Received msg, len 70, type 1, UID 1 Apr 10 21:11:44 fry TAPDISK[6205]: Received CTLMSG_PARAMS: [/data/storage/images/CentOS-5.1-x86_64-netinstall.iso] Apr 10 21:11:44 fry TAPDISK[6205]: Loaded driver: name [tapdisk_aio], type [0] Apr 10 21:11:44 fry TAPDISK[6205]: block-aio open('/data/storage/images/CentOS-5.1-x86_64-netinstall.iso') Apr 10 21:11:44 fry logger: /etc/xen/scripts/blktap: add XENBUS_PATH=backend/tap/1/768 Apr 10 21:11:44 fry logger: /etc/xen/scripts/blktap: add XENBUS_PATH=backend/tap/1/832 Apr 10 21:11:44 fry logger: /etc/xen/scripts/blktap: add XENBUS_PATH=backend/tap/1/832 Apr 10 21:11:44 fry logger: /etc/xen/scripts/blktap: add XENBUS_PATH=backend/tap/1/768 Apr 10 21:11:44 fry logger: /etc/xen/scripts/vif-bridge: online XENBUS_PATH=backend/vif/1/0 Apr 10 21:11:44 fry TAPDISK[6205]: WARNING: Accessing image withoutO_DIRECT! (/data/storage/images/CentOS-5.1-x86_64-netinstall.iso) Apr 10 21:11:44 fry TAPDISK[6205]: Image size: ^Ipre sector_shift [7903232] ^Ipost sector_shift [15436]
Apr 10 21:11:44 fry TAPDISK[6205]: Adding fd_list_entry
Apr 10 21:11:44 fry TAPDISK[6205]: Entered cookie 1
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Received CTLMSG_IMG: 15436, 512, 4
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Received a poll for a new devmap
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Write_msg called: CTLMSG_NEWDEV
Apr 10 21:11:44 fry TAPDISK[6205]: Tapdisk: Received msg, len 20, type 4, UID 1
Apr 10 21:11:44 fry TAPDISK[6205]: Retrieving state, cookie 1.....[OK]
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Received CTLMSG_NEWDEV_RSP
Apr 10 21:11:44 fry BLKTAPCTRL[5733]: Exiting map_new_blktapctrl
Apr 10 21:11:44 fry kernel: [ 85.308056] device vif1.0 entered promiscuous mode Apr 10 21:11:44 fry kernel: [ 85.308072] audit(1207876304.423:3): dev=vif1.0 prom=256 old_prom=0 auid=4294967295 Apr 10 21:11:44 fry kernel: [ 85.315954] xenbr0: port 1(vif1.0) entering learning state Apr 10 21:11:44 fry kernel: [ 85.319544] xenbr0: topology change detected, propagating Apr 10 21:11:44 fry kernel: [ 85.319552] xenbr0: port 1(vif1.0) entering forwarding state Apr 10 21:11:44 fry logger: /etc/xen/scripts/blktap: Writing backend/tap/1/832/hotplug-status connected to xenstore. Apr 10 21:11:44 fry logger: /etc/xen/scripts/blktap: Writing backend/tap/1/768/hotplug-status connected to xenstore. Apr 10 21:11:44 fry logger: /etc/xen/scripts/vif-bridge: Successful vif-bridge online for vif1.0, bridge xenbr0. Apr 10 21:11:44 fry logger: /etc/xen/scripts/vif-bridge: Writing backend/vif/1/0/hotplug-status connected to xenstore. Apr 10 21:11:44 fry kernel: [ 85.758951] tun: Universal TUN/TAP device driver, 1.6 Apr 10 21:11:44 fry kernel: [ 85.758956] tun: (C) 1999-2004 Max Krasnyansky <maxk@xxxxxxxxxxxx> Apr 10 21:11:44 fry kernel: [ 85.788274] device tap0 entered promiscuous mode Apr 10 21:11:44 fry kernel: [ 85.788287] audit(1207876304.903:4): dev=tap0 prom=256 old_prom=0 auid=4294967295 Apr 10 21:11:44 fry kernel: [ 85.788296] xenbr0: port 2(tap0) entering learning state Apr 10 21:11:44 fry kernel: [ 85.791392] xenbr0: topology change detected, propagating Apr 10 21:11:44 fry kernel: [ 85.791397] xenbr0: port 2(tap0) entering forwarding state Apr 10 21:11:45 fry kernel: [ 86.248205] xenbr0: port 2(tap0) entering disabled state Apr 10 21:11:45 fry kernel: [ 86.265612] device tap0 left promiscuous mode Apr 10 21:11:45 fry kernel: [ 86.265624] audit(1207876305.383:5): dev=tap0 prom=0 old_prom=256 auid=4294967295 Apr 10 21:11:45 fry kernel: [ 86.265628] xenbr0: port 2(tap0) entering disabled state Apr 10 21:11:45 fry logger: /etc/xen/scripts/xen-hotplug-cleanup: XENBUS_PATH=backend/console/1/0 Apr 10 21:11:45 fry kernel: [ 86.383125] xenbr0: port 1(vif1.0) entering disabled state Apr 10 21:11:45 fry logger: /etc/xen/scripts/xen-hotplug-cleanup: XENBUS_PATH=backend/vfb/1/0 Apr 10 21:11:45 fry kernel: [ 86.413780] xenbr0: port 1(vif1.0) entering disabled state Apr 10 21:11:45 fry logger: /etc/xen/scripts/blktap: remove XENBUS_PATH=backend/tap/1/768 Apr 10 21:11:45 fry logger: /etc/xen/scripts/blktap: remove XENBUS_PATH=backend/tap/1/832 Apr 10 21:11:45 fry logger: /etc/xen/scripts/vif-bridge: offline XENBUS_PATH=backend/vif/1/0 Apr 10 21:11:45 fry logger: /etc/xen/scripts/blktap: remove XENBUS_PATH=backend/tap/1/832 Apr 10 21:11:45 fry logger: /etc/xen/scripts/blktap: remove XENBUS_PATH=backend/tap/1/768 Apr 10 21:11:45 fry logger: /etc/xen/scripts/blktap: Writing backend/tap/1/832/hotplug-error xenstore-read /local/domain/1/vm failed. backend/tap/1/832/hotplug-status error to xenstore. Apr 10 21:11:45 fry logger: /etc/xen/scripts/blktap: xenstore-read /local/domain/1/vm failed. Apr 10 21:11:45 fry logger: /etc/xen/scripts/blktap: Writing backend/tap/1/832/hotplug-error /etc/xen/scripts/blktap failed; error detected. backend/tap/1/832/hotplug-status error to xenstore. Apr 10 21:11:45 fry logger: /etc/xen/scripts/blktap: /etc/xen/scripts/blktap failed; error detected. Apr 10 21:11:45 fry logger: /etc/xen/scripts/blktap: Writing backend/tap/1/768/hotplug-error xenstore-read /local/domain/1/vm failed. backend/tap/1/768/hotplug-status error to xenstore. Apr 10 21:11:45 fry logger: /etc/xen/scripts/xen-hotplug-cleanup: XENBUS_PATH=backend/tap/1/832 Apr 10 21:11:45 fry logger: /etc/xen/scripts/vif-bridge: brctl delif xenbr0 vif1.0 failed Apr 10 21:11:45 fry logger: /etc/xen/scripts/blktap: xenstore-read /local/domain/1/vm failed. Apr 10 21:11:45 fry logger: /etc/xen/scripts/blktap: Writing backend/tap/1/768/hotplug-error /etc/xen/scripts/blktap failed; error detected. backend/tap/1/768/hotplug-status error to xenstore. Apr 10 21:11:45 fry logger: /etc/xen/scripts/blktap: /etc/xen/scripts/blktap failed; error detected. Apr 10 21:11:45 fry logger: /etc/xen/scripts/vif-bridge: ifconfig vif1.0 down failed
Apr 10 21:11:45 fry BLKTAPCTRL[5733]: Unmapping vbd
Apr 10 21:11:45 fry BLKTAPCTRL[5733]: Write_msg called: CTLMSG_CLOSE
Apr 10 21:11:45 fry BLKTAPCTRL[5733]: DEL_DISKTYPE: Freeing entry
Apr 10 21:11:45 fry TAPDISK[6205]: Tapdisk: Received msg, len 16, type 7, UID 1 Apr 10 21:11:45 fry logger: /etc/xen/scripts/xen-hotplug-cleanup: XENBUS_PATH=backend/tap/1/768 Apr 10 21:11:45 fry logger: /etc/xen/scripts/vif-bridge: Successful vif-bridge offline for vif1.0, bridge xenbr0. Apr 10 21:11:45 fry logger: /etc/xen/scripts/xen-hotplug-cleanup: XENBUS_PATH=backend/vif/1/0
Apr 10 21:11:45 fry BLKTAPCTRL[5733]: Unmapping vbd
Apr 10 21:11:45 fry BLKTAPCTRL[5733]: Write_msg called: CTLMSG_CLOSE
Apr 10 21:11:45 fry BLKTAPCTRL[5733]: DEL_DISKTYPE: Freeing entry
Apr 10 21:11:45 fry TAPDISK[6184]: Tapdisk: Received msg, len 16, type 7, UID 0


And here is the output from xend.log:
[2008-04-10 21:11:43 5741] DEBUG (XendDomainInfo:84) XendDomainInfo.create(['vm', ['name', 'farnsworth'], ['memory', 256], ['vcpus', 1], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['uuid', 'e76057c4-0b24-4c2f-9d3a-bdda8dba32e5'], ['image', ['hvm', ['kernel', '/usr/lib/xen/boot/hvmloader'], ['device_model', '/usr/lib/xen/bin/qemu-dm'], ['pae', 1], ['vcpus', 1], ['boot', 'cd'], ['fda', ''], ['fdb', ''], ['timer_mode', 0], ['localtime', 0], ['serial', ''], ['stdvga', 0], ['isa', 0], ['nographic', 0], ['soundhw', ''], ['vnc', 1], ['vncunused', 1], ['display', 'localhost:10.0'], ['xauthority', '/home/mike/.Xauthority'], ['rtc_timeoffset', '0'], ['monitor', 0], ['acpi', 1], ['apic', 1], ['usb', 0], ['usbdevice', ''], ['keymap', ''], ['pci', []], ['guest_os_type', 'default']]], ['device', ['tap', ['uname', 'tap:aio:/data/xen/domains/farnsworth/farnsworth.img'], ['dev', 'ioemu:hda'], ['mode', 'w']]], ['device', ['tap', ['uname', 'tap:aio:/data/storage/images/CentOS-5.1-x86_64-netinstall.iso'], ['dev', 'ioemu:hdb:cdrom'], ['mode', 'r']]], ['device', ['vif', ['bridge', 'xenbr0'], ['mac', '00:16:3E:85:B6:D4'], ['type', 'ioemu']]]]) [2008-04-10 21:11:43 5741] DEBUG (XendDomainInfo:1637) XendDomainInfo.constructDomain [2008-04-10 21:11:43 5741] DEBUG (balloon:151) Balloon: 128300 KiB free; need 2048; done.
[2008-04-10 21:11:43 5741] DEBUG (XendDomain:443) Adding Domain: 1
[2008-04-10 21:11:43 5741] DEBUG (XendDomainInfo:1722) XendDomainInfo.initDomain: 1 256 [2008-04-10 21:11:43 5741] DEBUG (image:226) Stored a VNC password for vfb access
[2008-04-10 21:11:43 5741] DEBUG (image:464) args: boot, val: cd
[2008-04-10 21:11:43 5741] DEBUG (image:464) args: fda, val: None
[2008-04-10 21:11:43 5741] DEBUG (image:464) args: fdb, val: None
[2008-04-10 21:11:43 5741] DEBUG (image:464) args: soundhw, val: None
[2008-04-10 21:11:43 5741] DEBUG (image:464) args: localtime, val: 0
[2008-04-10 21:11:43 5741] DEBUG (image:464) args: serial, val: None
[2008-04-10 21:11:43 5741] DEBUG (image:464) args: std-vga, val: 0
[2008-04-10 21:11:43 5741] DEBUG (image:464) args: isa, val: 0
[2008-04-10 21:11:43 5741] DEBUG (image:464) args: acpi, val: 1
[2008-04-10 21:11:43 5741] DEBUG (image:464) args: usb, val: 0
[2008-04-10 21:11:43 5741] DEBUG (image:464) args: usbdevice, val: None
[2008-04-10 21:11:43 5741] DEBUG (image:464) args: pci, val: []
[2008-04-10 21:11:43 5741] DEBUG (XendDomainInfo:1754) _initDomain:shadow_memory=0x0, memory_static_max=0x10000000, memory_static_min=0x0. [2008-04-10 21:11:43 5741] DEBUG (balloon:157) Balloon: 127008 KiB free; 0 to scrub; need 273920; retries: 20. [2008-04-10 21:11:43 5741] DEBUG (balloon:172) Balloon: setting dom0 target to 1735 MiB. [2008-04-10 21:11:43 5741] DEBUG (XendDomainInfo:714) Setting memory target of domain Domain-0 (0) to 1735 MiB. [2008-04-10 21:11:44 5741] DEBUG (balloon:151) Balloon: 274868 KiB free; need 273920; done. [2008-04-10 21:11:44 5741] INFO (image:139) buildDomain os=hvm dom=1 vcpus=1
[2008-04-10 21:11:44 5741] DEBUG (image:514) domid          = 1
[2008-04-10 21:11:44 5741] DEBUG (image:515) image = /usr/lib/xen/boot/hvmloader
[2008-04-10 21:11:44 5741] DEBUG (image:516) store_evtchn   = 2
[2008-04-10 21:11:44 5741] DEBUG (image:517) memsize        = 256
[2008-04-10 21:11:44 5741] DEBUG (image:518) vcpus          = 1
[2008-04-10 21:11:44 5741] DEBUG (image:519) acpi           = 1
[2008-04-10 21:11:44 5741] DEBUG (image:520) apic           = 1
[2008-04-10 21:11:44 5741] INFO (XendDomainInfo:1530) createDevice: vfb : {'vncunused': 1, 'other_config': {'vncunused': 1, 'type': 'vnc'}, 'type': 'vnc', 'uuid': '88e8cd8d-9139-2c83-062b-8d962f7c69b4'} [2008-04-10 21:11:44 5741] DEBUG (DevController:117) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/1/0'} to /local/domain/1/device/vfb/0. [2008-04-10 21:11:44 5741] DEBUG (DevController:119) DevController: writing {'vncunused': '1', 'domain': 'farnsworth', 'frontend': '/local/domain/1/device/vfb/0', 'uuid': '88e8cd8d-9139-2c83-062b-8d962f7c69b4', 'state': '1', 'online': '1', 'frontend-id': '1', 'type': 'vnc'} to /local/domain/0/backend/vfb/1/0. [2008-04-10 21:11:44 5741] INFO (XendDomainInfo:1530) createDevice: tap : {'bootable': 1, 'uname': 'tap:aio:/data/xen/domains/farnsworth/farnsworth.img', 'mode': 'w', 'dev': 'ioemu:hda', 'uuid': '507f06f0-a9e8-2d1f-a307-38090d07a7e7'} [2008-04-10 21:11:44 5741] DEBUG (DevController:117) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/tap/1/768'} to /local/domain/1/device/vbd/768. [2008-04-10 21:11:44 5741] DEBUG (DevController:119) DevController: writing {'domain': 'farnsworth', 'frontend': '/local/domain/1/device/vbd/768', 'uuid': '507f06f0-a9e8-2d1f-a307-38090d07a7e7', 'dev': 'hda', 'state': '1', 'params': 'aio:/data/xen/domains/farnsworth/farnsworth.img', 'mode': 'w', 'online': '1', 'frontend-id': '1', 'type': 'tap'} to /local/domain/0/backend/tap/1/768. [2008-04-10 21:11:44 5741] INFO (XendDomainInfo:1530) createDevice: tap : {'bootable': 0, 'uname': 'tap:aio:/data/storage/images/CentOS-5.1-x86_64-netinstall.iso', 'mode': 'r', 'dev': 'ioemu:hdb:cdrom', 'uuid': '4f720b1e-d664-2f82-c4d6-9e5a795ff97f'} [2008-04-10 21:11:44 5741] DEBUG (DevController:117) DevController: writing {'backend-id': '0', 'virtual-device': '832', 'device-type': 'cdrom', 'state': '1', 'backend': '/local/domain/0/backend/tap/1/832'} to /local/domain/1/device/vbd/832. [2008-04-10 21:11:44 5741] DEBUG (DevController:119) DevController: writing {'domain': 'farnsworth', 'frontend': '/local/domain/1/device/vbd/832', 'uuid': '4f720b1e-d664-2f82-c4d6-9e5a795ff97f', 'dev': 'hdb', 'state': '1', 'params': 'aio:/data/storage/images/CentOS-5.1-x86_64-netinstall.iso', 'mode': 'r', 'online': '1', 'frontend-id': '1', 'type': 'tap'} to /local/domain/0/backend/tap/1/832. [2008-04-10 21:11:44 5741] INFO (XendDomainInfo:1530) createDevice: vif : {'bridge': 'xenbr0', 'mac': '00:16:3E:85:B6:D4', 'type': 'ioemu', 'uuid': '218b8f03-38cc-192a-d484-70e801ca8836'} [2008-04-10 21:11:44 5741] DEBUG (DevController:117) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vif/1/0'} to /local/domain/1/device/vif/0. [2008-04-10 21:11:44 5741] DEBUG (DevController:119) DevController: writing {'bridge': 'xenbr0', 'domain': 'farnsworth', 'handle': '0', 'uuid': '218b8f03-38cc-192a-d484-70e801ca8836', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/1/device/vif/0', 'mac': '00:16:3E:85:B6:D4', 'online': '1', 'frontend-id': '1', 'type': 'ioemu'} to /local/domain/0/backend/vif/1/0. [2008-04-10 21:11:44 5741] INFO (image:283) spawning device models: /usr/lib/xen/bin/qemu-dm ['/usr/lib/xen/bin/qemu-dm', '-d', '1', '-domain-name', 'farnsworth', '-vnc', '127.0.0.1:0,password', '-vncunused', '-vcpus', '1', '-boot', 'cd', '-acpi', '-net', 'nic,vlan=1,macaddr=00:16:3E:85:B6:D4,model=rtl8139', '-net', 'tap,vlan=1,bridge=xenbr0', '-M', 'xenfv']
[2008-04-10 21:11:44 5741] INFO (image:287) device model pid: 6191
[2008-04-10 21:11:44 5741] DEBUG (XendDomainInfo:2211) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '3', 'uuid': 'e76057c4-0b24-4c2f-9d3a-bdda8dba32e5', 'on_reboot': 'restart', 'start_time': '1207876304.25', 'on_poweroff': 'destroy', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'image': '(hvm (kernel /usr/lib/xen/boot/hvmloader) (acpi 1) (apic 1) (boot cd) (device_model /usr/lib/xen/bin/qemu-dm) (display localhost:10.0) (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 /home/mike/.Xauthority) (pci ()) (guest_os_type default) (notes (SUSPEND_CANCEL 1)))', 'name': 'farnsworth'} [2008-04-10 21:11:44 5741] DEBUG (XendDomainInfo:1027) Storing domain details: {'console/port': '3', 'name': 'farnsworth', 'console/limit': '1048576', 'store/port': '2', 'vm': '/vm/e76057c4-0b24-4c2f-9d3a-bdda8dba32e5', 'domid': '1', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'memory/target': '262144', 'control/platform-feature-multiprocessor-suspend': '1', 'store/ring-ref': '65534', 'console/type': 'ioemu'} [2008-04-10 21:11:44 5741] DEBUG (DevController:117) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/1/0'} to /local/domain/1/device/console/0. [2008-04-10 21:11:44 5741] DEBUG (DevController:119) DevController: writing {'domain': 'farnsworth', 'protocol': 'vt100', 'uuid': 'ad18cbb7-951d-b0d6-bbad-8d81ef2a49f7', 'frontend': '/local/domain/1/device/console/0', 'state': '1', 'location': '3', 'online': '1', 'frontend-id': '1'} to /local/domain/0/backend/console/1/0. [2008-04-10 21:11:44 5741] DEBUG (XendDomainInfo:1111) XendDomainInfo.handleShutdownWatch [2008-04-10 21:11:44 5741] DEBUG (DevController:150) Waiting for devices vif.
[2008-04-10 21:11:44 5741] DEBUG (DevController:155) Waiting for 0.
[2008-04-10 21:11:44 5741] DEBUG (DevController:594) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status. [2008-04-10 21:11:44 5741] DEBUG (DevController:594) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status. [2008-04-10 21:11:44 5741] DEBUG (DevController:608) hotplugStatusCallback 1. [2008-04-10 21:11:44 5741] DEBUG (DevController:150) Waiting for devices vbd. [2008-04-10 21:11:44 5741] DEBUG (DevController:150) Waiting for devices irq. [2008-04-10 21:11:44 5741] DEBUG (DevController:150) Waiting for devices vkbd. [2008-04-10 21:11:44 5741] DEBUG (DevController:150) Waiting for devices vfb. [2008-04-10 21:11:44 5741] DEBUG (DevController:150) Waiting for devices console.
[2008-04-10 21:11:44 5741] DEBUG (DevController:155) Waiting for 0.
[2008-04-10 21:11:44 5741] DEBUG (DevController:150) Waiting for devices pci. [2008-04-10 21:11:44 5741] DEBUG (DevController:150) Waiting for devices ioports. [2008-04-10 21:11:44 5741] DEBUG (DevController:150) Waiting for devices tap.
[2008-04-10 21:11:44 5741] DEBUG (DevController:155) Waiting for 768.
[2008-04-10 21:11:44 5741] DEBUG (DevController:594) hotplugStatusCallback /local/domain/0/backend/tap/1/768/hotplug-status. [2008-04-10 21:11:44 5741] DEBUG (DevController:608) hotplugStatusCallback 1.
[2008-04-10 21:11:44 5741] DEBUG (DevController:155) Waiting for 832.
[2008-04-10 21:11:44 5741] DEBUG (DevController:594) hotplugStatusCallback /local/domain/0/backend/tap/1/832/hotplug-status. [2008-04-10 21:11:44 5741] DEBUG (DevController:608) hotplugStatusCallback 1. [2008-04-10 21:11:44 5741] DEBUG (DevController:150) Waiting for devices vtpm. [2008-04-10 21:11:44 5741] INFO (XendDomain:1165) Domain farnsworth (1) unpaused. [2008-04-10 21:11:45 5741] INFO (XendDomainInfo:1299) Domain has shutdown: name=farnsworth id=1 reason=poweroff. [2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:1913) XendDomainInfo.destroy: domid=1 [2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:1930) XendDomainInfo.destroyDomain(1) [2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:1548) Destroying device model
[2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:1555) Releasing devices
[2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:1561) Removing vif/0
[2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:1561) Removing vbd/768
[2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/768
[2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:1561) Removing vbd/832
[2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/832
[2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:1561) Removing vfb/0
[2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0
[2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:1561) Removing console/0
[2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:590) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
[2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:1553) No device model
[2008-04-10 21:11:45 5741] DEBUG (XendDomainInfo:1555) Releasing devices





--
Mike Brancato, CISSP

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

<Prev in Thread] Current Thread [Next in Thread>