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
|
Home