Michael Brancato
2008-Apr-11 01:43 UTC
[Xen-users] xenstore-read: couldn''t read path /local/domain/1/vm
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@qualcomm.com> 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@lists.xensource.com http://lists.xensource.com/xen-users
Michael Brancato
2008-Apr-11 22:59 UTC
[Xen-users] Re: xenstore-read: couldn''t read path /local/domain/1/vm
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@qualcomm.com> > 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@lists.xensource.com http://lists.xensource.com/xen-users
Michael Brancato
2008-Apr-12 02:32 UTC
[Xen-users] Re: xenstore-read: couldn''t read path /local/domain/1/vm
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@qualcomm.com> >> 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@lists.xensource.com http://lists.xensource.com/xen-users