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

[Michael Brancato <mike@xxxxxxxxxxxxxxxx>]

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