[Xen-users] 64-bit PV domU (2.6.27) hangs during boot?

["Logan, John G." <John.G.Logan@xxxxxxx>]

I'm trying to get a 64-bit 2.6.27 domU (ubuntu intrepid, using debootstrap) running under Xen 3.3, and boot appears to hang at some point on or after dm initiatlization and before the root gets mounted.  

 

Has anyone else encountered this?  What can I do to understand better where things might be sticking?

 

thanks in advance, jgl

 

======================================================

console log:

 

root@hmx:/home/guest# xm create -c /etc/xen/u3-intrepid.cfg
Using config file "/etc/xen/u3-intrepid.cfg".
Started domain kaanxen-u3
[    0.000000]   DMA      0x00000000 -> 0x00001000
[    0.000000]   DMA32    0x00001000 -> 0x00100000
[    0.000000]   Normal   0x00100000 -> 0x00100000
[    0.000000] Movable zone start PFN for each node
[    0.000000] early_node_map[3] active PFN ranges
[    0.000000]     0: 0x00000000 -> 0x000000a0
[    0.000000]     0: 0x00000100 -> 0x000018e9
[    0.000000]     0: 0x00001aec -> 0x00040000
[    0.000000] SMP: Allowing 1 CPUs, 0 hotplug CPUs
[    0.000000] No local APIC present
[    0.000000] PM: Registered nosave memory: 00000000000a0000 - 0000000000100000
[    0.000000] PM: Registered nosave memory: 00000000018e9000 - 0000000001aec000
[    0.000000] Allocating PCI resources starting at 50000000 (gap: 40000000:c0000000)
[    0.000000] PERCPU: Allocating 64928 bytes of per cpu data
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 255682
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: root=/dev/xvda2 ro ip=:127.0.255.255::::eth0:dhcp 2 console=xvc0 single
[    0.000000] Initializing CPU#0
[    0.000000] PID hash table entries: 4096 (order: 12, 32768 bytes)
[    0.000000] Detected 2500.094 MHz processor.
[    0.010000] Console: colour dummy device 80x25
[    0.010000] console [tty0] enabled
[    0.010000] console [hvc0] enabled
[    0.010000] Checking aperture...
[    0.010000] No AGP bridge found
[    0.010000] Memory: 1003992k/1048576k available (3210k kernel code, 42140k reserved, 1595k data, 540k init)
[    0.010000] SLUB: Genslabs=13, HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.010000] installing Xen timer for CPU 0
[    0.010000] Calibrating delay loop (skipped), value calculated using timer frequency.. 5000.18 BogoMIPS (lpj=25000940)
[    0.010000] Security Framework initialized
[    0.010000] SELinux:  Disabled at boot.
[    0.010000] AppArmor: AppArmor initialized <NULL>
[    0.010000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.010000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.010000] Mount-cache hash table entries: 256
[    0.010000] Initializing cgroup subsys ns
[    0.010000] Initializing cgroup subsys cpuacct
[    0.010000] Initializing cgroup subsys memory
[    0.010000] CPU: L1 I cache: 32K, L1 D cache: 32K
[    0.010000] CPU: L2 cache: 6144K
[    0.010000] CPU 0/0 -> Node 0
[    0.010000] CPU: Physical Processor ID: 0
[    0.010000] CPU: Processor Core ID: 0
[    0.010000] SMP alternatives: switching to UP code
[    0.019251] Freeing SMP alternatives: 24k freed
[    0.019321] cpu 0 spinlock event irq 1
[    0.019410] Brought up 1 CPUs
[    0.019769] net_namespace: 1552 bytes
[    0.019778] Booting paravirtualized kernel on Xen
[    0.019783] Xen version: 3.3.0 (preserve-AD)
[    0.020208] Grant table initialized
[    0.040242] Time: 165:165:165  Date: 165/165/65
[    0.040309] NET: Registered protocol family 16
[    0.041924] PCI: Fatal: No config space access function found
[    0.043409] ACPI: Interpreter disabled.
[    0.043423] Linux Plug and Play Support v0.97 (c) Adam Belay
[    0.043496] pnp: PnP ACPI: disabled
[    0.043642] xen_balloon: Initialising balloon driver.
[    0.044448] PCI: System does not support PCI
[    0.044460] PCI: System does not support PCI
[    0.070170] NET: Registered protocol family 8
[    0.070182] NET: Registered protocol family 20
[    0.070259] NetLabel: Initializing
[    0.070268] NetLabel:  domain hash size = 128
[    0.070277] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.070317] NetLabel:  unlabeled traffic allowed by default
[    0.070335] PCI-GART: No AMD northbridge found.
[    0.071792] tracer: 1286 pages allocated for 65536 entries of 80 bytes
[    0.071805]    actual entries 65586
[    0.071971] AppArmor: AppArmor Filesystem Enabled
[    0.072917] NET: Registered protocol family 2
[    0.180197] IP route cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.181322] TCP established hash table entries: 131072 (order: 9, 2097152 bytes)
[    0.182766] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[    0.183288] TCP: Hash tables configured (established 131072 bind 65536)
[    0.183304] TCP reno registered
[    0.210102] NET: Registered protocol family 1
[    0.210294] checking if image is initramfs... it is
[    0.236914] Freeing initrd memory: 16448k freed
[    0.246851] audit: initializing netlink socket (disabled)
[    0.246898] type=2000 audit(1188454339.106:1): initialized
[    0.252086] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.257939] VFS: Disk quotas dquot_6.5.1
[    0.258126] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.258354] msgmni has been set to 2080
[    0.258604] io scheduler noop registered
[    0.258617] io scheduler anticipatory registered
[    0.258629] io scheduler deadline registered (default)
[    0.258875] io scheduler cfq registered
[    0.316241] Linux agpgart interface v0.103
[    0.316260] Serial: 8250/16550 driver4 ports, IRQ sharing enabled
[    0.321584] brd: module loaded
[    0.321720] input: Macintosh mouse button emulation as /class/input/input0
[    0.322055] PNP: No PS/2 controller found. Probing ports directly.
[    0.322894] i8042.c: No controller found.
[    0.360228] mice: PS/2 mouse device common for all mice
[    0.360352] rtc_cmos: probe of rtc_cmos failed with error -16
[    0.360558] cpuidle: using governor ladder
[    0.360571] cpuidle: using governor menu
[    0.361416] aufs 20080609
[    0.361980] IO APIC resources could be not be allocated.
[    0.362407] registered taskstats version 1
[    0.362434] XENBUS: Device with no driver: device/vbd/51713

[    0.362448] XENBUS: Device with no driver: device/vbd/51714
[    0.362461] XENBUS: Device with no driver: device/vif/0
[    0.362473] XENBUS: Device with no driver: device/console/0
[    0.362499]   Magic number: 1:252:3141
[    0.362600] /build/buildd/linux-2.6.27/drivers/rtc/hctosys.c: unable to open rtc device (rtc0)
[    0.362621] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[    0.362634] EDD information not available.
[    0.362705] Freeing unused kernel memory: 540k freed
[    0.587247] fuse init (API version 7.9)
[    0.663240] thermal: Unknown symbol acpi_processor_set_thermal_limit
[    0.688809] device-mapper: uevent: version 1.0.3
[    0.689107] device-mapper: ioctl: 4.14.0-ioctl (2008-04-23) initialised: dm-devel@xxxxxxxxxx
<<boot hangs here>>

 

======================================================

config file:

 

kernel      = '/boot/vmlinuz-2.6.27-2-server'
ramdisk     = '/boot/initrd.img-2.6.27-2-server'
memory      = '1024'
root        = '/dev/xvda2 ro'
disk        = [
                  'tap:aio:/home/xen/domains/u3-intrepid/swap.img,xvda1,w',
                  'tap:aio:/home/xen/domains/u3-intrepid/disk.img,xvda2,w',
              ]

name        = 'kaanxen-u3'
dhcp        = 'dhcp'
vif         = [ 'mac=00:16:3e:00:00:13' ]

on_reboot   = 'restart'
on_crash    = 'restart'

extra = '2 console=xvc0'

 

======================================================

xm info:

host                   : hmx
release                : 2.6.24-19-xen
version                : #1 SMP Wed Aug 20 21:08:51 UTC 2008
machine                : x86_64
nr_cpus                : 8
nr_nodes               : 1
cores_per_socket       : 4
threads_per_core       : 1
cpu_mhz                : 2500
hw_caps                : bfebfbff:20100800:00000000:00000140:000ce3bd:00000000:00000001:00000000
virt_caps              : hvm
total_memory           : 8190
free_memory            : 6
node_to_cpu            : node0:0-7
node_to_memory         : node0:6
xen_major              : 3
xen_minor              : 3
xen_extra              : .0
xen_caps               : xen-3.0-x86_64 xen-3.0-x86_32p hvm-3.0-x86_32 hvm-3.0-x86_32p hvm-3.0-x86_64
xen_scheduler          : credit
xen_pagesize           : 4096
platform_params        : virt_start=0xffff800000000000
xen_changeset          : unavailable
cc_compiler            : gcc version 4.3.1 (Ubuntu 4.3.1-9ubuntu1)
cc_compile_by          : buildd
cc_compile_domain      : buildd
cc_compile_date        : Wed Aug 27 13:40:13 UTC 2008
xend_config_format     : 4

 

==================================================

xend.log:

 

[2007-09-02 17:03:37 5342] DEBUG (XendDomainInfo:89) XendDomainInfo.create(['vm', ['name', 'kaanxen-u3'], ['memory', '1024'], ['on_poweroff', 'destroy'], ['on_reboot', 'restart'], ['on_crash', 'restart'], ['vcpus', 1], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['image', ['linux', ['kernel', '/boot/vmlinuz-2.6.27-2-server'], ['ramdisk', '/boot/initrd.img-2.6.27-2-server'], ['ip', ':127.0.255.255::::eth0:dhcp'], ['root', '/dev/hda2 ro'], ['args', '2 console=xvc0 nmi_watchdog=2']]], ['device', ['tap', ['uname', 'tap:aio:/home/xen/domains/u3-intrepid/swap.img'], ['dev', 'hda1'], ['mode', 'w']]], ['device', ['tap', ['uname', 'tap:aio:/home/xen/domains/u3-intrepid/disk.img'], ['dev', 'hda2'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:00:00:13']]]])
[2007-09-02 17:03:37 5342] DEBUG (XendDomainInfo:2028) XendDomainInfo.constructDomain
[2007-09-02 17:03:37 5342] DEBUG (balloon:132) Balloon: 1055148 KiB free; need 2048; done.
[2007-09-02 17:03:37 5342] DEBUG (XendDomain:447) Adding Domain: 24
[2007-09-02 17:03:37 5342] DEBUG (XendDomainInfo:2134) XendDomainInfo.initDomain: 24 256
[2007-09-02 17:03:37 5342] DEBUG (XendDomainInfo:2215) _initDomain:shadow_memory=0x0, memory_static_max=0x40000000, memory_static_min=0x0.
[2007-09-02 17:03:37 5342] DEBUG (balloon:132) Balloon: 1055140 KiB free; need 1052672; done.
[2007-09-02 17:03:37 5342] INFO (image:166) buildDomain os=linux dom=24 vcpus=1
[2007-09-02 17:03:37 5342] DEBUG (image:631) domid          = 24
[2007-09-02 17:03:37 5342] DEBUG (image:632) memsize        = 1024
[2007-09-02 17:03:37 5342] DEBUG (image:633) image          = /boot/vmlinuz-2.6.27-2-server
[2007-09-02 17:03:37 5342] DEBUG (image:634) store_evtchn   = 1
[2007-09-02 17:03:37 5342] DEBUG (image:635) console_evtchn = 2
[2007-09-02 17:03:37 5342] DEBUG (image:636) cmdline        = root=/dev/hda2 ro ip=:127.0.255.255::::eth0:dhcp 2 console=xvc0 nmi_watchdog=2
[2007-09-02 17:03:37 5342] DEBUG (image:637) ramdisk        = /boot/initrd.img-2.6.27-2-server
[2007-09-02 17:03:37 5342] DEBUG (image:638) vcpus          = 1
[2007-09-02 17:03:37 5342] DEBUG (image:639) features       =
[2007-09-02 17:03:37 5342] INFO (XendDomainInfo:1900) createDevice: tap : {'bootable': 1, 'uname': 'tap:aio:/home/xen/domains/u3-intrepid/swap.img', 'mode': 'w', 'dev': 'hda1', 'uuid': '7cf1f9cb-895d-4b66-1fe0-7ee8be889927'}
[2007-09-02 17:03:37 5342] DEBUG (DevController:122) DevController: writing {'virtual-device': '769', 'device-type': 'disk', 'protocol': 'x86_64-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/tap/24/769'} to /local/domain/24/device/vbd/769.
[2007-09-02 17:03:37 5342] DEBUG (DevController:124) DevController: writing {'domain': 'kaanxen-u3', 'frontend': '/local/domain/24/device/vbd/769', 'uuid': '7cf1f9cb-895d-4b66-1fe0-7ee8be889927', 'dev': 'hda1', 'state': '1', 'params': 'aio:/home/xen/domains/u3-intrepid/swap.img', 'mode': 'w', 'online': '1', 'frontend-id': '24', 'type': 'tap'} to /local/domain/0/backend/tap/24/769.
[2007-09-02 17:03:37 5342] INFO (XendDomainInfo:1900) createDevice: tap : {'bootable': 0, 'uname': 'tap:aio:/home/xen/domains/u3-intrepid/disk.img', 'mode': 'w', 'dev': 'hda2', 'uuid': '6d7dc676-c175-2208-dd53-2468236b6875'}
[2007-09-02 17:03:37 5342] DEBUG (DevController:122) DevController: writing {'virtual-device': '770', 'device-type': 'disk', 'protocol': 'x86_64-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/tap/24/770'} to /local/domain/24/device/vbd/770.
[2007-09-02 17:03:37 5342] DEBUG (DevController:124) DevController: writing {'domain': 'kaanxen-u3', 'frontend': '/local/domain/24/device/vbd/770', 'uuid': '6d7dc676-c175-2208-dd53-2468236b6875', 'dev': 'hda2', 'state': '1', 'params': 'aio:/home/xen/domains/u3-intrepid/disk.img', 'mode': 'w', 'online': '1', 'frontend-id': '24', 'type': 'tap'} to /local/domain/0/backend/tap/24/770.
[2007-09-02 17:03:37 5342] INFO (XendDomainInfo:1900) createDevice: vif : {'mac': '00:16:3e:00:00:13', 'uuid': 'a2852094-648c-96bd-0333-5d66d9ddef8f'}
[2007-09-02 17:03:37 5342] DEBUG (DevController:122) DevController: writing {'mac': '00:16:3e:00:00:13', 'handle': '0', 'protocol': 'x86_64-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/24/0'} to /local/domain/24/device/vif/0.
[2007-09-02 17:03:37 5342] DEBUG (DevController:124) DevController: writing {'domain': 'kaanxen-u3', 'frontend': '/local/domain/24/device/vif/0', 'uuid': 'a2852094-648c-96bd-0333-5d66d9ddef8f', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:00:00:13', 'frontend-id': '24', 'state': '1', 'online': '1', 'handle': '0'} to /local/domain/0/backend/vif/24/0.
[2007-09-02 17:03:37 5342] DEBUG (XendDomainInfo:2729) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '0', 'uuid': 'baca416a-f41d-843b-2823-c2d7a06f6331', 'on_reboot': 'restart', 'start_time': '1188777817.83', 'on_poweroff': 'destroy', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'image': "(linux (kernel /boot/vmlinuz-2.6.27-2-server) (ramdisk /boot/initrd.img-2.6.27-2-server) (args 'root=/dev/hda2 ro ip=:127.0.255.255::::eth0:dhcp 2 console=xvc0 nmi_watchdog=2') (notes (HV_START_LOW 18446603336221196288) (FEATURES '!writable_page_tables|pae_pgdir_above_4gb') (VIRT_BASE 18446744071562067968) (GUEST_VERSION 2.6) (PADDR_OFFSET 0) (GUEST_OS linux) (HYPERCALL_PAGE 18446744071564201984) (LOADER generic) (SUSPEND_CANCEL 1) (PAE_MODE yes) (ENTRY 18446744071569654272) (XEN_VERSION xen-3.0)))", 'name': 'kaanxen-u3'}
[2007-09-02 17:03:37 5342] DEBUG (XendDomainInfo:1359) Storing domain details: {'console/ring-ref': '2211483', 'image/entry': '18446744071569654272', 'console/port': '2', 'store/ring-ref': '2211484', 'image/loader': 'generic', 'vm': '/vm/baca416a-f41d-843b-2823-c2d7a06f6331', 'control/platform-feature-multiprocessor-suspend': '1', 'image/hv-start-low': '18446603336221196288', 'image/guest-os': 'linux', 'image/virt-base': '18446744071562067968', 'memory/target': '1048576', 'image/guest-version': '2.6', 'image/pae-mode': 'yes', 'console/limit': '1048576', 'image/paddr-offset': '0', 'image/hypercall-page': '18446744071564201984', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'image/features/pae-pgdir-above-4gb': '1', 'image/features/writable-page-tables': '0', 'console/type': 'xenconsoled', 'name': 'kaanxen-u3', 'domid': '24', 'image/xen-version': 'xen-3.0', 'store/port': '1'}
[2007-09-02 17:03:37 5342] DEBUG (DevController:122) DevController: writing {'protocol': 'x86_64-abi', 'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/24/0'} to /local/domain/24/device/console/0.
[2007-09-02 17:03:37 5342] DEBUG (DevController:124) DevController: writing {'domain': 'kaanxen-u3', 'frontend': '/local/domain/24/device/console/0', 'uuid': 'e38095fc-13a7-4a1c-f11b-2a1857025f21', 'frontend-id': '24', 'state': '1', 'location': '2', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/24/0.
[2007-09-02 17:03:37 5342] DEBUG (XendDomainInfo:1443) XendDomainInfo.handleShutdownWatch
[2007-09-02 17:03:37 5342] DEBUG (DevController:155) Waiting for devices vif.
[2007-09-02 17:03:37 5342] DEBUG (DevController:160) Waiting for 0.
[2007-09-02 17:03:37 5342] DEBUG (DevController:645) hotplugStatusCallback /local/domain/0/backend/vif/24/0/hotplug-status.
[2007-09-02 17:03:37 5342] DEBUG (DevController:659) hotplugStatusCallback 1.
[2007-09-02 17:03:37 5342] DEBUG (DevController:155) Waiting for devices vscsi.
[2007-09-02 17:03:37 5342] DEBUG (DevController:155) Waiting for devices vbd.
[2007-09-02 17:03:37 5342] DEBUG (DevController:155) Waiting for devices irq.
[2007-09-02 17:03:37 5342] DEBUG (DevController:155) Waiting for devices vkbd.
[2007-09-02 17:03:37 5342] DEBUG (DevController:155) Waiting for devices vfb.
[2007-09-02 17:03:37 5342] DEBUG (DevController:155) Waiting for devices console.
[2007-09-02 17:03:37 5342] DEBUG (DevController:160) Waiting for 0.
[2007-09-02 17:03:37 5342] DEBUG (DevController:155) Waiting for devices pci.
[2007-09-02 17:03:37 5342] DEBUG (DevController:155) Waiting for devices ioports.
[2007-09-02 17:03:37 5342] DEBUG (DevController:155) Waiting for devices tap.
[2007-09-02 17:03:37 5342] DEBUG (DevController:160) Waiting for 769.
[2007-09-02 17:03:37 5342] DEBUG (DevController:645) hotplugStatusCallback /local/domain/0/backend/tap/24/769/hotplug-status.
[2007-09-02 17:03:37 5342] DEBUG (DevController:659) hotplugStatusCallback 1.
[2007-09-02 17:03:37 5342] DEBUG (DevController:160) Waiting for 770.
[2007-09-02 17:03:37 5342] DEBUG (DevController:645) hotplugStatusCallback /local/domain/0/backend/tap/24/770/hotplug-status.
[2007-09-02 17:03:37 5342] DEBUG (DevController:659) hotplugStatusCallback 1.
[2007-09-02 17:03:37 5342] DEBUG (DevController:155) Waiting for devices vtpm.

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