Andy Smith
2012-Jul-27 16:15 UTC
Failure to boot, Debian squeeze with 4.0.1 hypervisor, timer problems?
Hi, I have a system based on a Supermicro X8DTH-i motherboard and a Xeon E5506 CPU. It was previously running Debian lenny with xen-hypervisor-3.2-1-amd64 (3.2.1-2) / linux-image-2.6.26-2-xen-amd64 (2.6.26-29) without incident. I upgraded it to Debian squeeze, with xen-hypervisor-4.0-amd64 (4.0.1-4) / linux-image-2.6.32-5-xen-amd64 (2.6.32-45) and now it does not complete a boot of the dom0 kernel. I can boot it into a vanilla kernel directly on bare metal and it seems okay with that. Reproduced below is full serial console output of boot of hypervisor and dom0 kernel. I''ve indicated the points where progress seems to halt or be abnormally slow. The boot ends with a repeated message of: [10065189777.645584] CE: xen increasing min_delta_ns to 7197131137619773666 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 1572324669574884690 nsec etc. Between this and some other messages about timers earlier on I gather there are timer problems here at the very least. If anyone has any suggestions for boot parameters that may allow further diagnosis or workarounds I would be extremely grateful. Otherwise I guess I replace the motherboard with a different one and hope it works. Thanks, Andy Boot parameters: multiboot /xen-4.0-amd64.gz placeholder dom0_mem=1024M loglvl=all guest_loglvl=all com1=9600,8n1 console=com1,vga serial_tx_buffer=64k module /vmlinuz-2.6.32-5-xen-amd64 placeholder root=UUID=10aafd06-36d7-4181-a343-c542150957e3 ro console=tty0 console=hvc0 Loading Xen 4.0-amd64 ... Loading Linux 2.6.32-5-xen-amd64 ... Loading initial ramdisk ... (XEN) Xen version 4.0.1 (Debian 4.0.1-5.2) (fw@deneb.enyo.de) (gcc version 4.4.5 (Debian 4.4.5-8) ) Thu Jun 21 06:41:09 UTC 2012 (XEN) Bootloader: GRUB 1.99-22.1 (XEN) Command line: placeholder dom0_mem=1024M loglvl=all guest_loglvl=all com1=9600,8n1 console=com1,vga serial_tx_buffer=64k (XEN) Video information: (XEN) VGA is text mode 80x25, font 8x16 (XEN) VBE/DDC methods: none; EDID transfer time: 0 seconds (XEN) EDID info not retrieved because no DDC retrieval method detected (XEN) Disc information: (XEN) Found 1 MBR signatures (XEN) Found 1 EDD information structures (XEN) Xen-e820 RAM map: (XEN) 0000000000000000 - 0000000000099000 (usable) (XEN) 0000000000099000 - 00000000000a0000 (reserved) (XEN) 00000000000e0000 - 0000000000100000 (reserved) (XEN) 0000000000100000 - 00000000bf780000 (usable) (XEN) 00000000bf78e000 - 00000000bf790000 type 9 (XEN) 00000000bf790000 - 00000000bf79e000 (ACPI data) (XEN) 00000000bf79e000 - 00000000bf7d0000 (ACPI NVS) (XEN) 00000000bf7d0000 - 00000000bf7e0000 (reserved) (XEN) 00000000bf7ec000 - 00000000c0000000 (reserved) (XEN) 00000000e0000000 - 00000000f0000000 (reserved) (XEN) 00000000fee00000 - 00000000fee01000 (reserved) (XEN) 00000000ffc00000 - 0000000100000000 (reserved) (XEN) 0000000100000000 - 0000000640000000 (usable) (XEN) ACPI: RSDP 000FADA0, 0024 (r2 ACPIAM) (XEN) ACPI: XSDT BF790100, 007C (r1 SMCI 20100304 MSFT 97) (XEN) ACPI: FACP BF790290, 00F4 (r3 030410 FACP1133 20100304 MSFT 97) (XEN) ACPI: DSDT BF7906D0, 74E0 (r1 10400 10400000 0 INTL 20051117) (XEN) ACPI: FACS BF79E000, 0040 (XEN) ACPI: APIC BF790390, 012A (r1 030410 APIC1133 20100304 MSFT 97) (XEN) ACPI: MCFG BF7904C0, 003C (r1 030410 OEMMCFG 20100304 MSFT 97) (XEN) ACPI: SPMI BF790500, 0041 (r5 030410 OEMSPMI 20100304 MSFT 97) (XEN) ACPI: OEMB BF79E040, 009B (r1 030410 OEMB1133 20100304 MSFT 97) (XEN) ACPI: HPET BF79A6D0, 0038 (r1 030410 OEMHPET 20100304 MSFT 97) (XEN) ACPI: SSDT BF79EEB0, 0363 (r1 DpgPmm CpuPm 12 INTL 20051117) (XEN) ACPI: EINJ BF79A710, 0130 (r1 AMIER AMI_EINJ 20100304 MSFT 97) (XEN) ACPI: BERT BF79A8A0, 0030 (r1 AMIER AMI_BERT 20100304 MSFT 97) (XEN) ACPI: ERST BF79A8D0, 01B0 (r1 AMIER AMI_ERST 20100304 MSFT 97) (XEN) ACPI: HEST BF79AA80, 00A8 (r1 AMIER ABC_HEST 20100304 MSFT 97) (XEN) System RAM: 24567MB (25156708kB) (XEN) No NUMA configuration found (XEN) Faking a node at 0000000000000000-0000000640000000 (XEN) Domain heap initialised (XEN) found SMP MP-table at 000ff780 (XEN) DMI present. (XEN) Using APIC driver default (XEN) ACPI: PM-Timer IO Port: 0x808 (XEN) ACPI: ACPI SLEEP INFO: pm1x_cnt[804,0], pm1x_evt[800,0] (XEN) ACPI: wakeup_vec[bf79e00c], vec_size[20] (XEN) ACPI: Local APIC address 0xfee00000 (XEN) ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled) (XEN) Processor #0 7:10 APIC version 21 (XEN) ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] enabled) (XEN) Processor #2 7:10 APIC version 21 (XEN) ACPI: LAPIC (acpi_id[0x03] lapic_id[0x04] enabled) (XEN) Processor #4 7:10 APIC version 21 (XEN) ACPI: LAPIC (acpi_id[0x04] lapic_id[0x06] enabled) (XEN) Processor #6 7:10 APIC version 21 (XEN) ACPI: LAPIC (acpi_id[0x05] lapic_id[0x84] disabled) (XEN) ACPI: LAPIC (acpi_id[0x06] lapic_id[0x85] disabled) (XEN) ACPI: LAPIC (acpi_id[0x07] lapic_id[0x86] disabled) (XEN) ACPI: LAPIC (acpi_id[0x08] lapic_id[0x87] disabled) (XEN) ACPI: LAPIC (acpi_id[0x09] lapic_id[0x88] disabled) (XEN) ACPI: LAPIC (acpi_id[0x0a] lapic_id[0x89] disabled) (XEN) ACPI: LAPIC (acpi_id[0x0b] lapic_id[0x8a] disabled) (XEN) ACPI: LAPIC (acpi_id[0x0c] lapic_id[0x8b] disabled) (XEN) ACPI: LAPIC (acpi_id[0x0d] lapic_id[0x8c] disabled) (XEN) ACPI: LAPIC (acpi_id[0x0e] lapic_id[0x8d] disabled) (XEN) ACPI: LAPIC (acpi_id[0x0f] lapic_id[0x8e] disabled) (XEN) ACPI: LAPIC (acpi_id[0x10] lapic_id[0x8f] disabled) (XEN) ACPI: LAPIC (acpi_id[0x11] lapic_id[0x90] disabled) (XEN) ACPI: LAPIC (acpi_id[0x12] lapic_id[0x91] disabled) (XEN) ACPI: LAPIC (acpi_id[0x13] lapic_id[0x92] disabled) (XEN) ACPI: LAPIC (acpi_id[0x14] lapic_id[0x93] disabled) (XEN) ACPI: LAPIC (acpi_id[0x15] lapic_id[0x94] disabled) (XEN) ACPI: LAPIC (acpi_id[0x16] lapic_id[0x95] disabled) (XEN) ACPI: LAPIC (acpi_id[0x17] lapic_id[0x96] disabled) (XEN) ACPI: LAPIC (acpi_id[0x18] lapic_id[0x97] disabled) (XEN) ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) (XEN) ACPI: IOAPIC (id[0x01] address[0xfec00000] gsi_base[0]) (XEN) IOAPIC[0]: apic_id 1, version 32, address 0xfec00000, GSI 0-23 (XEN) ACPI: IOAPIC (id[0x03] address[0xfec8a000] gsi_base[24]) (XEN) IOAPIC[1]: apic_id 3, version 32, address 0xfec8a000, GSI 24-47 (XEN) ACPI: IOAPIC (id[0x05] address[0xfec9a000] gsi_base[48]) (XEN) IOAPIC[2]: apic_id 5, version 32, address 0xfec9a000, GSI 48-71 (XEN) ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) (XEN) ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) (XEN) ACPI: IRQ0 used by override. (XEN) ACPI: IRQ2 used by override. (XEN) ACPI: IRQ9 used by override. (XEN) Enabling APIC mode: Flat. Using 3 I/O APICs (XEN) ACPI: HPET id: 0x8086a301 base: 0xfed00000 (XEN) PCI: MCFG configuration 0: base e0000000 segment 0 buses 0 - 255 (XEN) PCI: MCFG area at e0000000 reserved in E820 (XEN) Using ACPI (MADT) for SMP configuration information (XEN) Using scheduler: SMP Credit Scheduler (credit) (XEN) Detected 2133.464 MHz processor. (XEN) Initing memory sharing. (XEN) VMX: Supported advanced features: (XEN) - APIC MMIO access virtualisation (XEN) - APIC TPR shadow (XEN) - Extended Page Tables (EPT) (XEN) - Virtual-Processor Identifiers (VPID) (XEN) - Virtual NMI (XEN) - MSR direct-access bitmap (XEN) EPT supports 2MB super page. (XEN) HVM: ASIDs enabled. (XEN) HVM: VMX enabled (XEN) HVM: Hardware Assisted Paging detected. (XEN) Intel machine check reporting enabled (XEN) I/O virtualisation disabled (XEN) Total of 4 processors activated. (XEN) ENABLING IO-APIC IRQs (XEN) -> Using new ACK method (XEN) ..TIMER: vector=0xF0 apic1=0 pin1=2 apic2=-1 pin2=-1 (XEN) TSC is reliable, synchronization unnecessary (XEN) Platform timer appears to have unexpectedly wrapped 10 or more times. (XEN) Platform timer is 14.318MHz HPET ÿ(XEN) Allocated console ring of 32 KiB. (XEN) microcode.c:73:d32767 microcode: CPU1 resumed (XEN) Brought up 4 CPUs (XEN) microcode.c:73:d32767 microcode: CPU2 resumed (XEN) microcode.c:73:d32767 microcode: CPU3 resumed (XEN) HPET: 4 timers in total, 0 timers will be used for broadcast (XEN) ACPI sleep modes: S3 (XEN) mcheck_poll: Machine check polling timer started. (XEN) *** LOADING DOMAIN 0 *** (XEN) Xen kernel: 64-bit, lsb, compat32 (XEN) Dom0 kernel: 64-bit, PAE, lsb, paddr 0x1000000 -> 0x1708000 (XEN) PHYSICAL MEMORY ARRANGEMENT: (XEN) Dom0 alloc.: 0000000628000000->000000062c000000 (245760 pages to be allocated) (XEN) VIRTUAL MEMORY ARRANGEMENT: (XEN) Loaded kernel: ffffffff81000000->ffffffff81708000 (XEN) Init. ramdisk: ffffffff81708000->ffffffff8332a000 (XEN) Phys-Mach map: ffffffff8332a000->ffffffff8352a000 (XEN) Start info: ffffffff8352a000->ffffffff8352a4b4 (XEN) Page tables: ffffffff8352b000->ffffffff8354a000 (XEN) Boot stack: ffffffff8354a000->ffffffff8354b000 (XEN) TOTAL: ffffffff80000000->ffffffff83800000 (XEN) ENTRY ADDRESS: ffffffff81531200 (XEN) Dom0 has maximum 4 VCPUs (XEN) Scrubbing Free RAM: ........................................................................................................................................................................................................................................done. (XEN) trace.c:89:d32767 calc_tinfo_first_offset: NR_CPUs 128, offset_in_bytes 258, t_info_first_offset 65 (XEN) Xen trace buffers: disabled (XEN) Std. Loglevel: All (XEN) Guest Loglevel: All (XEN) Xen is relinquishing VGA console. (XEN) *** Serial input -> DOM0 (type ''CTRL-a'' three times to switch input to Xen) (XEN) Freed 176kB init memory. mapping kernel into physical memory Xen: setup ISA identity maps about to get started... [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Linux version 2.6.32-5-xen-amd64 (Debian 2.6.32-45) (dannf@debian.org) (gcc version 4.3.5 (Debian 4.3.5-4) ) #1 SMP Sun May 6 08:57:29 UTC 2012 [ 0.000000] Command line: placeholder root=UUID=10aafd06-36d7-4181-a343-c542150957e3 ro console=tty0 console=hvc0 [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] AMD AuthenticAMD [ 0.000000] Centaur CentaurHauls [ 0.000000] released 0 pages of unused memory [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] Xen: 0000000000000000 - 0000000000099000 (usable) [ 0.000000] Xen: 0000000000099000 - 0000000000100000 (reserved) [ 0.000000] Xen: 0000000000100000 - 0000000040000000 (usable) [ 0.000000] Xen: 00000000bf78e000 - 00000000bf790000 type 9 [ 0.000000] Xen: 00000000bf790000 - 00000000bf79e000 (ACPI data) [ 0.000000] Xen: 00000000bf79e000 - 00000000bf7d0000 (ACPI NVS) [ 0.000000] Xen: 00000000bf7d0000 - 00000000bf7e0000 (reserved) [ 0.000000] Xen: 00000000bf7ec000 - 00000000c0000000 (reserved) [ 0.000000] Xen: 00000000e0000000 - 00000000f0000000 (reserved) [ 0.000000] Xen: 00000000fec00000 - 00000000fec01000 (reserved) [ 0.000000] Xen: 00000000fec8a000 - 00000000fec8b000 (reserved) [ 0.000000] Xen: 00000000fec9a000 - 00000000fec9b000 (reserved) [ At this point output stalls and it has appeared to hang, but if I wait a long time (minutes) some more output does appear: [ 0.000000] Xen: 00000000fee00000 - 00000000fee01 I waited tens of minutes and after it gets past the BIOS RAM map bit, booting proceeds at a normal speed with the following output: [ 0.000000] Xen: 00000000fec00000 - 00000000fec01000 (reserved) [ 0.000000] Xen: 00000000fec8a000 - 00000000fec8b000 (reserved) [ 0.000000] Xen: 00000000fec9a000 - 00000000fec9b000 (reserved) [ 0.000000] Xen: 00000000fee00000 - 00000000fee01000 (reserved) [ 0.000000] Xen: 00000000ffc00000 - 0000000100000000 (reserved) [ 0.000000] DMI present. [ 0.000000] AMI BIOS detected: BIOS may corrupt low RAM, working around it. [ 0.000000] last_pfn = 0x40000 max_arch_pfn = 0x400000000 [ 0.000000] x86 PAT enabled: cpu 0, old 0x50100070406, new 0x7010600070106 [ 0.000000] init_memory_mapping: 0000000000000000-0000000040000000 [ 0.000000] RAMDISK: 01708000 - 0332a000 [ 0.000000] ACPI: RSDP 00000000000fada0 00024 (v02 ACPIAM) [ 0.000000] ACPI: XSDT 00000000bf790100 0007C (v01 SMCI 20100304 MSFT 00000097) [ 0.000000] ACPI: FACP 00000000bf790290 000F4 (v03 030410 FACP1133 20100304 MSFT 00000097) [ 0.000000] ACPI: DSDT 00000000bf7906d0 074E0 (v01 10400 10400000 00000000 INTL 20051117) [ 0.000000] ACPI: FACS 00000000bf79e000 00040 [ 0.000000] ACPI: APIC 00000000bf790390 0012A (v01 030410 APIC1133 20100304 MSFT 00000097) [ 0.000000] ACPI: MCFG 00000000bf7904c0 0003C (v01 030410 OEMMCFG 20100304 MSFT 00000097) [ 0.000000] ACPI: SPMI 00000000bf790500 00041 (v05 030410 OEMSPMI 20100304 MSFT 00000097) [ 0.000000] ACPI: OEMB 00000000bf79e040 0009B (v01 030410 OEMB1133 20100304 MSFT 00000097) [ 0.000000] ACPI: HPET 00000000bf79a6d0 00038 (v01 030410 OEMHPET 20100304 MSFT 00000097) [ 0.000000] ACPI: SSDT 00000000bf79eeb0 00363 (v01 DpgPmm CpuPm 00000012 INTL 20051117) [ 0.000000] ACPI: EINJ 00000000bf79a710 00130 (v01 AMIER AMI_EINJ 20100304 MSFT 00000097) [ 0.000000] ACPI: BERT 00000000bf79a8a0 00030 (v01 AMIER AMI_BERT 20100304 MSFT 00000097) [ 0.000000] ACPI: ERST 00000000bf79a8d0 001B0 (v01 AMIER AMI_ERST 20100304 MSFT 00000097) [ 0.000000] ACPI: HEST 00000000bf79aa80 000A8 (v01 AMIER ABC_HEST 20100304 MSFT 00000097) [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at 0000000000000000-0000000040000000 [ 0.000000] Bootmem setup node 0 0000000000000000-0000000040000000 [ 0.000000] NODE_DATA [0000000000010000 - 0000000000017fff] [ 0.000000] bootmap [0000000000018000 - 000000000001ffff] pages 8 [ 0.000000] (8 early reservations) ==> bootmem [0000000000 - 0040000000] [ 0.000000] #0 [0000000000 - 0000001000] BIOS data page ==> [0000000000 - 0000001000] [ 0.000000] #1 [000352b000 - 000354a000] XEN PAGETABLES ==> [000352b000 - 000354a000] [ 0.000000] #2 [0000006000 - 0000008000] TRAMPOLINE ==> [0000006000 - 0000008000] [ 0.000000] #3 [0001000000 - 00016e7b44] TEXT DATA BSS ==> [0001000000 - 00016e7b44] [ 0.000000] #4 [0001708000 - 000332a000] RAMDISK ==> [0001708000 - 000332a000] [ 0.000000] #5 [000332a000 - 000352b000] XEN START INFO ==> [000332a000 - 000352b000] [ 0.000000] #6 [00016e8000 - 00016e8225] BRK ==> [00016e8000 - 00016e8225] [ 0.000000] #7 [0000100000 - 00002e0000] PGTABLE ==> [0000100000 - 00002e0000] [ 0.000000] found SMP MP-table at [ffff8800000ff780] ff780 [ 0.000000] Zone PFN ranges: [ 0.000000] DMA 0x00000010 -> 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[2] active PFN ranges [ 0.000000] 0: 0x00000010 -> 0x00000099 [ 0.000000] 0: 0x00000100 -> 0x00040000 [ 0.000000] ACPI: PM-Timer IO Port: 0x808 [ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x04] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x04] lapic_id[0x06] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x05] lapic_id[0x84] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x06] lapic_id[0x85] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x07] lapic_id[0x86] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x08] lapic_id[0x87] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x09] lapic_id[0x88] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x0a] lapic_id[0x89] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x0b] lapic_id[0x8a] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x0c] lapic_id[0x8b] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x0d] lapic_id[0x8c] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x0e] lapic_id[0x8d] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x0f] lapic_id[0x8e] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x10] lapic_id[0x8f] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x11] lapic_id[0x90] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x12] lapic_id[0x91] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x13] lapic_id[0x92] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x14] lapic_id[0x93] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x15] lapic_id[0x94] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x16] lapic_id[0x95] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x17] lapic_id[0x96] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x18] lapic_id[0x97] disabled) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] ACPI: IOAPIC (id[0x01] address[0xfec00000] gsi_base[0]) [ 0.000000] IOAPIC[0]: apic_id 1, version 0, address 0xfec00000, GSI 0-0 [ 0.000000] ACPI: IOAPIC (id[0x03] address[0xfec8a000] gsi_base[24]) [ 0.000000] IOAPIC[1]: apic_id 3, version 0, address 0xfec8a000, GSI 24-24 [ 0.000000] ACPI: IOAPIC (id[0x05] address[0xfec9a000] gsi_base[48]) [ 0.000000] IOAPIC[2]: apic_id 5, version 0, address 0xfec9a000, GSI 48-48 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ERROR: Unable to locate IOAPIC for GSI 2 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ERROR: Unable to locate IOAPIC for GSI 9 [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a301 base: 0xfed00000 [ 0.000000] SMP: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] PM: Registered nosave memory: 0000000000099000 - 0000000000100000 [ 0.000000] Allocating PCI resources starting at 40000000 (gap: 40000000:7f78e000) [ 0.000000] Booting paravirtualized kernel on Xen [ 0.000000] Xen version: 4.0.1 (preserve-AD) (dom0) [ 0.000000] NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 30 pages/cpu @ffff880003580000 s90328 r8192 d24360 u122880 [ 0.000000] pcpu-alloc: s90328 r8192 d24360 u122880 alloc=30*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [ 5.398767] Xen: using vcpu_info placement [ 5.398773] Built 1 zonelists in Node order, mobility grouping on. Total pages: 257958 [ 5.398775] Policy zone: DMA32 [ 5.398778] Kernel command line: placeholder root=UUID=10aafd06-36d7-4181-a343-c542150957e3 ro console=tty0 console=hvc0 [ 5.398810] PID hash table entries: 4096 (order: 3, 32768 bytes) [ 5.399145] Initializing CPU#0 [ 5.407809] DMA: Placing 64MB software IO TLB between ffff880004754000 - ffff880008754000 [ 5.407814] DMA: software IO TLB at phys 0x4754000 - 0x8754000 [ 5.407817] xen_swiotlb_fixup: buf=ffff880004754000 size=67108864 [ 5.428597] xen_swiotlb_fixup: buf=ffff8800087b4000 size=32768 [ 5.433194] Memory: 927008k/1048576k available (3152k kernel code, 476k absent, 121092k reserved, 2064k data, 604k init) [ 5.433231] SLUB: Genslabs=14, HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 5.433255] Hierarchical RCU implementation. [ 5.433262] NR_IRQS:4352 nr_irqs:1280 [ 5.433420] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 5.433423] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 5.433426] xen: sci override: source_irq=9 global_irq=9 trigger=c polarity=1 [ 5.433431] xen_allocate_pirq: returning irq 9 for gsi 9 [ 5.433439] xen: acpi sci 9 [ 5.439564] Console: colour VGA+ 80x25 [ 5.447914] console [tty0] enabled [ 5.462204] console [hvc0] enabled [ 5.462288] installing Xen timer for CPU 0 [ 5.462376] Detected 2133.464 MHz processor. [ 5.462441] Calibrating delay loop (skipped), value calculated using timer frequency.. 4266.92 BogoMIPS (lpj=8533856) [ 5.462581] Security Framework initialized [ 5.462644] SELinux: Disabled at boot. [ 5.462916] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 5.463266] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes) [ 5.463434] Mount-cache hash table entries: 256 [ 5.463620] Initializing cgroup subsys ns [ 5.463683] Initializing cgroup subsys cpuacct [ 5.463746] Initializing cgroup subsys devices [ 5.463808] Initializing cgroup subsys freezer [ 5.463869] Initializing cgroup subsys net_cls [ 5.463964] CPU: L1 I cache: 32K, L1 D cache: 32K [ 5.464058] CPU: L2 cache: 256K [ 5.464116] CPU: L3 cache: 4096K [ 5.464177] CPU 0/0x0 -> Node 0 [ 5.464235] CPU: Unsupported number of siblings 16 [ 5.464283] mce: CPU supports 9 MCE banks [ 5.464415] Performance Events: unsupported p6 CPU model 26 no PMU driver, software events only. [ 5.464579] SMP alternatives: switching to UP code [ 5.491389] ACPI: Core revision 20090903 [ 5.523554] installing Xen timer for CPU 1 [ 5.523647] SMP alternatives: switching to SMP code [ 5.549478] Initializing CPU#1 [ 5.549518] CPU: L1 I cache: 32K, L1 D cache: 32K [ 5.549521] CPU: L2 cache: 256K [ 5.549522] CPU: L3 cache: 4096K [ 5.549526] CPU 1/0x0 -> Node 0 [ 5.549528] CPU: Unsupported number of siblings 16 [ 5.549629] installing Xen timer for CPU 2 [ 5.550102] Initializing CPU#2 [ 5.550140] CPU: L1 I cache: 32K, L1 D cache: 32K [ 5.550142] CPU: L2 cache: 256K [ 5.550144] CPU: L3 cache: 4096K [ 5.550147] CPU 2/0x0 -> Node 0 [ 5.550150] CPU: Unsupported number of siblings 16 [ 5.550248] installing Xen timer for CPU 3 [ 5.550712] Initializing CPU#3 [ 5.550749] CPU: L1 I cache: 32K, L1 D cache: 32K [ 5.550752] CPU: L2 cache: 256K [ 5.550753] CPU: L3 cache: 4096K [ 5.550757] CPU 3/0x0 -> Node 0 [ 5.550759] CPU: Unsupported number of siblings 16 [ 5.550797] Brought up 4 CPUs [ 5.551536] devtmpfs: initialized [ 5.554586] Grant table initialized [ 5.554648] regulator: core version 0.5 [ 5.554758] NET: Registered protocol family 16 [ 5.560215] ACPI: bus type pci registered [ 5.560412] PCI: MCFG configuration 0: base e0000000 segment 0 buses 0 - 255 [ 5.560481] PCI: MCFG area at e0000000 reserved in E820 [ 5.607794] PCI: Using MMCONFIG at e0000000 - efffffff [ 5.607859] PCI: Using configuration type 1 for base access [ 5.608513] bio: create slab <bio-0> at 0 [ 5.610126] ERROR: Unable to locate IOAPIC for GSI 9 [ 5.613509] ACPI Warning for \_SB_._OSC: Return type mismatch - found Integer, expected Buffer (20090903/nspredef-1006) [ 5.614951] ACPI: Executed 1 blocks of module-level executable AML code [ 5.632345] ACPI: Interpreter enabled [ 5.632407] ACPI: (supports S0 S1 S4 S5) [ 5.632770] ACPI: Using IOAPIC for interrupt routing [ 5.649717] ACPI Warning: Incorrect checksum in table [OEMB] - 02, should be FF (20090903/tbutils-314) [ 5.650202] ACPI: No dock devices found. [ 5.650578] ACPI: PCI Root Bridge [PCI0] (0000:00) [ 5.650820] pci 0000:00:00.0: PME# supported from D0 D3hot D3cold [ 5.650890] pci 0000:00:00.0: PME# disabled [ 5.651131] pci 0000:00:01.0: PME# supported from D0 D3hot D3cold [ 5.651201] pci 0000:00:01.0: PME# disabled [ 5.651448] pci 0000:00:03.0: PME# supported from D0 D3hot D3cold [ 5.651517] pci 0000:00:03.0: PME# disabled [ 5.651761] pci 0000:00:05.0: PME# supported from D0 D3hot D3cold [ 5.651830] pci 0000:00:05.0: PME# disabled [ 5.652073] pci 0000:00:07.0: PME# supported from D0 D3hot D3cold [ 5.652142] pci 0000:00:07.0: PME# disabled [ 5.652386] pci 0000:00:09.0: PME# supported from D0 D3hot D3cold [ 5.652455] pci 0000:00:09.0: PME# disabled [ 5.652681] pci 0000:00:13.0: PME# supported from D0 D3hot D3cold [ 5.652750] pci 0000:00:13.0: PME# disabled [ 5.655210] pci 0000:00:1a.7: PME# supported from D0 D3hot D3cold [ 5.655281] pci 0000:00:1a.7: PME# disabled [ 5.656049] pci 0000:00:1d.7: PME# supported from D0 D3hot D3cold [ 5.656119] pci 0000:00:1d.7: PME# disabled [ 5.656438] pci 0000:00:1f.0: ICH7 LPC Generic IO decode 1 PIO at 0a00 (mask 00ff) [ 5.656530] pci 0000:00:1f.0: ICH7 LPC Generic IO decode 3 PIO at 164c (mask 0007) [ 5.656618] pci 0000:00:1f.0: ICH7 LPC Generic IO decode 4 PIO at 0ca0 (mask 000f) [ 5.657097] pci 0000:01:00.0: PME# supported from D0 D3hot D3cold [ 5.657167] pci 0000:01:00.0: PME# disabled [ 5.657516] pci 0000:01:00.1: PME# supported from D0 D3hot D3cold [ 5.657586] pci 0000:01:00.1: PME# disabled [ 5.670436] pci 0000:00:1e.0: transparent bridge (XEN) PCI add device 00:00.0 (XEN) PCI add device 00:01.0 (XEN) PCI add device 00:03.0 (XEN) PCI add device 00:05.0 (XEN) PCI add device 00:07.0 (XEN) PCI add device 00:09.0 (XEN) PCI add device 00:13.0 (XEN) PCI add device 00:14.0 (XEN) PCI add device 00:14.1 (XEN) PCI add device 00:14.2 (XEN) PCI add device 00:14.3 (XEN) PCI add device 00:16.0 (XEN) PCI add device 00:16.1 (XEN) PCI add device 00:16.2 (XEN) PCI add device 00:16.3 (XEN) PCI add device 00:16.4 (XEN) PCI add device 00:16.5 (XEN) PCI add device 00:16.6 (XEN) PCI add device 00:16.7 (XEN) PCI add device 00:1a.0 (XEN) PCI add device 00:1a.1 (XEN) PCI add device 00:1a.7 (XEN) PCI add device 00:1d.0 (XEN) PCI add device 00:1d.1 (XEN) PCI add device 00:1d.2 (XEN) PCI add device 00:1d.7 (XEN) PCI add device 00:1e.0 (XEN) PCI add device 00:1f.0 (XEN) PCI add device 00:1f.3 (XEN) PCI add device 01:00.0 (XEN) PCI add device 01:00.1 (XEN) PCI add device 03:00.0 (XEN) PCI add device 06:04.0 [ 5.718791] ACPI: PCI Root Bridge [BR50] (0000:80) [ 5.719061] pci 0000:80:00.0: PME# supported from D0 D3hot D3cold [ 5.719132] pci 0000:80:00.0: PME# disabled [ 5.719393] pci 0000:80:01.0: PME# supported from D0 D3hot D3cold [ 5.719463] pci 0000:80:01.0: PME# disabled [ 5.719721] pci 0000:80:03.0: PME# supported from D0 D3hot D3cold [ 5.719790] pci 0000:80:03.0: PME# disabled [ 5.720049] pci 0000:80:05.0: PME# supported from D0 D3hot D3cold [ 5.720118] pci 0000:80:05.0: PME# disabled [ 5.720375] pci 0000:80:07.0: PME# supported from D0 D3hot D3cold [ 5.720445] pci 0000:80:07.0: PME# disabled [ 5.720703] pci 0000:80:09.0: PME# supported from D0 D3hot D3cold [ 5.720773] pci 0000:80:09.0: PME# disabled [ 5.721009] pci 0000:80:13.0: PME# supported from D0 D3hot D3cold [ 5.721079] pci 0000:80:13.0: PME# disabled (XEN) PCI add device 80:00.0 (XEN) PCI add device 80:01.0 (XEN) PCI add device 80:03.0 (XEN) PCI add device 80:05.0 (XEN) PCI add device 80:07.0 (XEN) PCI add device 80:09.0 (XEN) PCI add device 80:13.0 (XEN) PCI add device 80:14.0 (XEN) PCI add device 80:14.1 (XEN) PCI add device 80:14.2 (XEN) PCI add device 80:14.3 (XEN) PCI add device 80:16.0 (XEN) PCI add device 80:16.1 (XEN) PCI add device 80:16.2 (XEN) PCI add device 80:16.3 (XEN) PCI add device 80:16.4 (XEN) PCI add device 80:16.5 (XEN) PCI add device 80:16.6 (XEN) PCI add device 80:16.7 [ 5.728879] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 6 7 *10 11 12 14 15) [ 5.729463] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 6 7 10 *11 12 14 15) [ 5.730047] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 6 7 10 11 12 14 *15) [ 5.730632] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 6 7 10 11 12 *14 15) [ 5.731224] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 6 7 10 11 12 14 15) *0, disabled. [ 5.731891] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 6 *7 10 11 12 14 15) [ 5.732472] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 6 7 10 11 12 14 15) *0, disabled. [ 5.733137] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 *6 7 10 11 12 14 15) [ 5.733686] xen_balloon: Initialising balloon driver with page order 0. [ 5.733823] vgaarb: device added: PCI:0000:06:04.0,decodes=io+mem,owns=io+mem,locks=none [ 5.733921] vgaarb: loaded [ 5.734063] PCI: Using ACPI for IRQ routing [ 5.734530] Switching to clocksource xen [ 5.736111] pnp: PnP ACPI init [ 5.736186] ACPI: bus type pnp registered [ 5.736976] xen_allocate_pirq: returning irq 8 for gsi 8 [ 5.737171] xen_allocate_pirq: returning irq 13 for gsi 13 [ 5.738173] xen_allocate_pirq: returning irq 4 for gsi 4 [ 5.738241] Already setup the GSI :4 [ 5.738970] xen_allocate_pirq: returning irq 3 for gsi 3 [ 5.739510] pnp 00:09: mem resource (0x400-0x4ff) overlaps 0000:01:00.0 BAR 7 (0x0-0x1ffff), disabling [ 5.739603] pnp 00:09: mem resource (0x400-0x4ff) overlaps 0000:01:00.0 BAR 10 (0x0-0x1ffff), disabling [ 5.739698] pnp 00:09: mem resource (0x400-0x4ff) overlaps 0000:01:00.1 BAR 7 (0x0-0x1ffff), disabling [ 5.739790] pnp 00:09: mem resource (0x400-0x4ff) overlaps 0000:01:00.1 BAR 10 (0x0-0x1ffff), disabling [ 5.742359] pnp: PnP ACPI: found 17 devices [ 5.742421] ACPI: ACPI bus type pnp unregistered [ 5.742491] system 00:01: iomem range 0xfed1c000-0xfed1ffff has been reserved [ 5.742564] system 00:06: ioport range 0xa10-0xa1f has been reserved [ 5.742635] system 00:09: ioport range 0xcf8-0xcff could not be reserved [ 5.742702] system 00:09: ioport range 0x4d0-0x4d1 has been reserved [ 5.742769] system 00:09: ioport range 0x800-0x87f has been reserved [ 5.742836] system 00:09: ioport range 0x500-0x57f has been reserved [ 5.742903] system 00:09: iomem range 0xfed1c000-0xfed1ffff has been reserved [ 5.742972] system 00:09: iomem range 0xfed20000-0xfed3ffff has been reserved [ 5.743040] system 00:09: iomem range 0xfed40000-0xfed8ffff has been reserved [ 5.743112] system 00:0b: iomem range 0xfec00000-0xfec00fff could not be reserved [ 5.743199] system 00:0b: iomem range 0xfee00000-0xfee00fff has been reserved [ 5.743270] system 00:0c: iomem range 0xe0000000-0xefffffff has been reserved [ 5.743344] system 00:0d: iomem range 0xc0000-0xcffff could not be reserved [ 5.743413] system 00:0d: iomem range 0xe0000-0xfffff could not be reserved [ 5.743481] system 00:0d: iomem range 0xfed90000-0xffffffff could not be reserved [ 5.748771] PM-Timer failed consistency check (0x0xffffff) - aborting. [ 5.749174] pci 0000:01:00.0: BAR 10: can''t allocate mem resource [0xfaf00000-0xfaefffff] [ 5.749282] pci 0000:01:00.1: BAR 7: can''t allocate mem resource [0xfaf00000-0xfaefffff] [ 5.749389] pci 0000:01:00.1: BAR 10: can''t allocate mem resource [0xfaf00000-0xfaefffff] [ 5.749476] pci 0000:00:01.0: PCI bridge, secondary bus 0000:01 [ 5.749543] pci 0000:00:01.0: IO window: 0xd000-0xdfff [ 5.749611] pci 0000:00:01.0: MEM window: 0xfae00000-0xfaefffff [ 5.749680] pci 0000:00:01.0: PREFETCH window: disabled [ 5.749751] pci 0000:00:03.0: PCI bridge, secondary bus 0000:02 [ 5.749815] pci 0000:00:03.0: IO window: disabled [ 5.749882] pci 0000:00:03.0: MEM window: disabled [ 5.749948] pci 0000:00:03.0: PREFETCH window: disabled [ 5.750019] pci 0000:00:05.0: PCI bridge, secondary bus 0000:03 [ 5.750085] pci 0000:00:05.0: IO window: 0xe000-0xefff [ 5.750154] pci 0000:00:05.0: MEM window: 0xfaf00000-0xfaffffff [ 5.750223] pci 0000:00:05.0: PREFETCH window: 0x000000f6000000-0x000000f7ffffff [ 5.750315] pci 0000:00:07.0: PCI bridge, secondary bus 0000:04 [ 5.750379] pci 0000:00:07.0: IO window: disabled [ 5.750446] pci 0000:00:07.0: MEM window: disabled [ 5.750512] pci 0000:00:07.0: PREFETCH window: disabled [ 5.750583] pci 0000:00:09.0: PCI bridge, secondary bus 0000:05 [ 5.750647] pci 0000:00:09.0: IO window: disabled [ 5.750714] pci 0000:00:09.0: MEM window: disabled [ 5.750780] pci 0000:00:09.0: PREFETCH window: disabled [ 5.750851] pci 0000:00:1e.0: PCI bridge, secondary bus 0000:06 [ 5.750915] pci 0000:00:1e.0: IO window: disabled [ 5.750984] pci 0000:00:1e.0: MEM window: 0xfb000000-0xfbefffff [ 5.751053] pci 0000:00:1e.0: PREFETCH window: 0x000000f9000000-0x000000f9ffffff [ 5.751253] pci 0000:80:00.0: PCI bridge, secondary bus 0000:81 [ 5.751318] pci 0000:80:00.0: IO window: disabled [ 5.751386] pci 0000:80:00.0: MEM window: disabled [ 5.751452] pci 0000:80:00.0: PREFETCH window: disabled [ 5.751524] pci 0000:80:01.0: PCI bridge, secondary bus 0000:82 [ 5.751588] pci 0000:80:01.0: IO window: disabled [ 5.751656] pci 0000:80:01.0: MEM window: disabled [ 5.751722] pci 0000:80:01.0: PREFETCH window: disabled [ 5.751794] pci 0000:80:03.0: PCI bridge, secondary bus 0000:83 [ 5.751858] pci 0000:80:03.0: IO window: disabled [ 5.751926] pci 0000:80:03.0: MEM window: disabled [ 5.751992] pci 0000:80:03.0: PREFETCH window: disabled [ 5.752063] pci 0000:80:05.0: PCI bridge, secondary bus 0000:84 [ 5.752128] pci 0000:80:05.0: IO window: disabled [ 5.752196] pci 0000:80:05.0: MEM window: disabled [ 5.752263] pci 0000:80:05.0: PREFETCH window: disabled [ 5.752334] pci 0000:80:07.0: PCI bridge, secondary bus 0000:85 [ 5.752398] pci 0000:80:07.0: IO window: disabled [ 5.752465] pci 0000:80:07.0: MEM window: disabled [ 5.752531] pci 0000:80:07.0: PREFETCH window: disabled [ 5.752602] pci 0000:80:09.0: PCI bridge, secondary bus 0000:86 [ 5.752667] pci 0000:80:09.0: IO window: disabled [ 5.752734] pci 0000:80:09.0: MEM window: disabled [ 5.752800] pci 0000:80:09.0: PREFETCH window: disabled [ 5.753026] NET: Registered protocol family 2 [ 5.753233] IP route cache hash table entries: 32768 (order: 6, 262144 bytes) [ 5.753892] TCP established hash table entries: 131072 (order: 9, 2097152 bytes) [ 5.754527] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes) [ 5.754783] TCP: Hash tables configured (established 131072 bind 65536) [ 5.754851] TCP reno registered [ 5.754993] NET: Registered protocol family 1 [ 5.755125] pci 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 5.755215] pci 0000:00:1a.0: PCI INT A disabled [ 5.755296] pci 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [ 5.755385] pci 0000:00:1a.1: PCI INT B disabled [ 5.760705] pci 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 5.760811] pci 0000:00:1a.7: PCI INT C disabled [ 5.760894] pci 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 5.760983] pci 0000:00:1d.0: PCI INT A disabled [ 5.761064] pci 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [ 5.761153] pci 0000:00:1d.1: PCI INT B disabled [ 5.761222] xen_allocate_pirq: returning irq 18 for gsi 18 [ 5.761288] Already setup the GSI :18 [ 5.761347] pci 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 5.761436] pci 0000:00:1d.2: PCI INT C disabled [ 5.761512] xen_allocate_pirq: returning irq 23 for gsi 23 [ 5.761578] Already setup the GSI :23 [ 5.761637] pci 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 5.761727] pci 0000:00:1d.7: PCI INT A disabled [ 5.761873] Unpacking initramfs... [ 5.785190] Freeing initrd memory: 28808k freed [ 5.791083] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 5.791158] DMA: Placing 64MB software IO TLB between ffff880004754000 - ffff880008754000 [ 5.791245] DMA: software IO TLB at phys 0x4754000 - 0x8754000 [ 5.791839] audit: initializing netlink socket (disabled) [ 5.791915] type=2000 audit(1343404490.714:1): initialized [ 5.795340] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 5.796900] VFS: Disk quotas dquot_6.5.2 [ 5.797010] Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 5.797154] msgmni has been set to 1866 [ 5.797522] alg: No test for stdrng (krng) [ 5.797637] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253) [ 5.797725] io scheduler noop registered [ 5.797785] io scheduler anticipatory registered [ 5.797846] io scheduler deadline registered [ 5.797964] io scheduler cfq registered (default) [ 5.805439] registering netback [ 5.807143] hpet_acpi_add: no address or irqs in _CRS [ 5.807224] Linux agpgart interface v0.103 [ 5.807336] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 5.807557] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A [ 5.807970] 00:08: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A [ 5.808225] input: Macintosh mouse button emulation as /devices/virtual/input/input0 [ 5.808382] PNP: No PS/2 controller found. Probing ports directly. [ 5.811319] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 5.811390] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 5.811531] mice: PS/2 mouse device common for all mice [ 5.811635] rtc_cmos 00:03: RTC can wake from S4 [ 5.811738] rtc_cmos 00:03: rtc core: registered rtc_cmos as rtc0 [ 5.811836] rtc0: alarms up to one month, y3k, 114 bytes nvram [ 5.811912] cpuidle: using governor ladder [ 5.811973] cpuidle: using governor menu [ 5.812036] No iBFT detected. [ 5.812368] TCP cubic registered [ 5.812537] NET: Registered protocol family 10 [ 5.813259] Mobile IPv6 [ 5.813319] NET: Registered protocol family 17 [ 5.813464] registered taskstats version 1 [ 5.815090] rtc_cmos 00:03: setting system clock to 2012-07-27 15:54:49 UTC (1343404489) [ 5.815240] Initalizing network drop monitor service [ 5.815381] Freeing unused kernel memory: 604k freed [ 5.815560] Write protecting the kernel read-only data: 4336k Loading, please wait... [ 5.846845] udev[83]: starting version 164 [ 5.886930] dca service started, version 1.12.1 [ 5.930417] Intel(R) Gigabit Ethernet Network Driver - version 3.0.6-k2 [ 5.930500] Copyright (c) 2007-2011 Intel Corporation. [ 5.930637] igb 0000:01:00.0: PCI INT A -> GSI 28 (level, low) -> IRQ 28 [ 5.931667] igb 0000:01:00.0: 0 vfs allocated [ 5.981055] SCSI subsystem initialized [ 5.981720] usbcore: registered new interface driver usbfs [ 5.981807] usbcore: registered new interface driver hub [ 5.983435] 3ware 9000 Storage Controller device driver for Linux v2.26.02.013. [ 5.983581] 3w-9xxx 0000:03:00.0: PCI INT A -> GSI 26 (level, low) -> IRQ 26 [ 5.983730] usbcore: registered new device driver usb [ 5.985561] ehci_hcd: USB 2.0 ''Enhanced'' Host Controller (EHCI) Driver [ 5.985652] xen_allocate_pirq: returning irq 18 for gsi 18 [ 5.985719] Already setup the GSI :18 [ 5.985780] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 5.985872] ehci_hcd 0000:00:1a.7: EHCI Host Controller [ 5.985958] ehci_hcd 0000:00:1a.7: new USB bus registered, assigned bus number 1 [ 5.986101] ehci_hcd 0000:00:1a.7: debug port 1 [ 5.990092] ehci_hcd 0000:00:1a.7: irq 18, io mem 0xfadfc000 [ 6.004251] ehci_hcd 0000:00:1a.7: USB 2.0 started, EHCI 1.00 [ 6.004347] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 [ 6.004414] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 6.004499] usb usb1: Product: EHCI Host Controller [ 6.004561] usb usb1: Manufacturer: Linux 2.6.32-5-xen-amd64 ehci_hcd [ 6.004626] usb usb1: SerialNumber: 0000:00:1a.7 [ 6.004759] usb usb1: configuration #1 chosen from 1 choice [ 6.004856] hub 1-0:1.0: USB hub found [ 6.004931] hub 1-0:1.0: 4 ports detected [ 6.005048] xen_allocate_pirq: returning irq 23 for gsi 23 [ 6.005114] Already setup the GSI :23 [ 6.005174] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 6.005266] ehci_hcd 0000:00:1d.7: EHCI Host Controller [ 6.005334] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 2 [ 6.005465] ehci_hcd 0000:00:1d.7: debug port 1 [ 6.009431] ehci_hcd 0000:00:1d.7: irq 23, io mem 0xfadda000 [ 6.024245] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00 [ 6.024339] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002 [ 6.024406] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 6.024491] usb usb2: Product: EHCI Host Controller [ 6.024553] usb usb2: Manufacturer: Linux 2.6.32-5-xen-amd64 ehci_hcd [ 6.024619] usb usb2: SerialNumber: 0000:00:1d.7 [ 6.024742] usb usb2: configuration #1 chosen from 1 choice [ 6.024833] hub 2-0:1.0: USB hub found [ 6.024908] hub 2-0:1.0: 6 ports detected [ 6.026346] uhci_hcd: USB Universal Host Controller Interface driver [ 6.026451] xen_allocate_pirq: returning irq 16 for gsi 16 [ 6.026518] Already setup the GSI :16 [ 6.026578] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 6.026659] uhci_hcd 0000:00:1a.0: UHCI Host Controller [ 6.026730] uhci_hcd 0000:00:1a.0: new USB bus registered, assigned bus number 3 [ 6.026858] uhci_hcd 0000:00:1a.0: irq 16, io base 0x0000bf80 [ 6.026975] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001 [ 6.027042] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 6.027127] usb usb3: Product: UHCI Host Controller [ 6.027189] usb usb3: Manufacturer: Linux 2.6.32-5-xen-amd64 uhci_hcd [ 6.027254] usb usb3: SerialNumber: 0000:00:1a.0 [ 6.027374] usb usb3: configuration #1 chosen from 1 choice [ 6.027468] hub 3-0:1.0: USB hub found [ 6.027540] hub 3-0:1.0: 2 ports detected [ 6.027656] xen_allocate_pirq: returning irq 21 for gsi 21 [ 6.027722] Already setup the GSI :21 [ 6.027782] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [ 6.027863] uhci_hcd 0000:00:1a.1: UHCI Host Controller [ 6.027932] uhci_hcd 0000:00:1a.1: new USB bus registered, assigned bus number 4 [ 6.028059] uhci_hcd 0000:00:1a.1: irq 21, io base 0x0000bf40 [ 6.028173] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001 [ 6.028242] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 6.028327] usb usb4: Product: UHCI Host Controller [ 6.028389] usb usb4: Manufacturer: Linux 2.6.32-5-xen-amd64 uhci_hcd [ 6.028455] usb usb4: SerialNumber: 0000:00:1a.1 [ 6.028583] usb usb4: configuration #1 chosen from 1 choice [ 6.028675] hub 4-0:1.0: USB hub found [ 6.028748] hub 4-0:1.0: 2 ports detected [ 6.028865] xen_allocate_pirq: returning irq 23 for gsi 23 [ 6.028931] Already setup the GSI :23 [ 6.028991] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 6.029072] uhci_hcd 0000:00:1d.0: UHCI Host Controller [ 6.029141] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 5 [ 6.029256] uhci_hcd 0000:00:1d.0: irq 23, io base 0x0000bf20 [ 6.029371] usb usb5: New USB device found, idVendor=1d6b, idProduct=0001 [ 6.029438] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 6.029523] usb usb5: Product: UHCI Host Controller [ 6.029585] usb usb5: Manufacturer: Linux 2.6.32-5-xen-amd64 uhci_hcd [ 6.029651] usb usb5: SerialNumber: 0000:00:1d.0 [ 6.029770] usb usb5: configuration #1 chosen from 1 choice [ 6.029862] hub 5-0:1.0: USB hub found [ 6.029934] hub 5-0:1.0: 2 ports detected [ 6.030048] xen_allocate_pirq: returning irq 19 for gsi 19 [ 6.030114] Already setup the GSI :19 [ 6.030174] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [ 6.030255] uhci_hcd 0000:00:1d.1: UHCI Host Controller [ 6.030326] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 6 [ 6.030453] uhci_hcd 0000:00:1d.1: irq 19, io base 0x0000bf00 [ 6.030567] usb usb6: New USB device found, idVendor=1d6b, idProduct=0001 [ 6.030634] usb usb6: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 6.030719] usb usb6: Product: UHCI Host Controller [ 6.030781] usb usb6: Manufacturer: Linux 2.6.32-5-xen-amd64 uhci_hcd [ 6.030846] usb usb6: SerialNumber: 0000:00:1d.1 [ 6.030967] usb usb6: configuration #1 chosen from 1 choice [ 6.031061] hub 6-0:1.0: USB hub found [ 6.031133] hub 6-0:1.0: 2 ports detected [ 6.031247] xen_allocate_pirq: returning irq 18 for gsi 18 [ 6.031314] Already setup the GSI :18 [ 6.031374] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 6.031456] uhci_hcd 0000:00:1d.2: UHCI Host Controller [ 6.036760] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 7 [ 6.036886] uhci_hcd 0000:00:1d.2: irq 18, io base 0x0000bec0 [ 6.037002] usb usb7: New USB device found, idVendor=1d6b, idProduct=0001 [ 6.037069] usb usb7: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 6.037154] usb usb7: Product: UHCI Host Controller [ 6.037216] usb usb7: Manufacturer: Linux 2.6.32-5-xen-amd64 uhci_hcd [ 6.037281] usb usb7: SerialNumber: 0000:00:1d.2 [ 6.037407] usb usb7: configuration #1 chosen from 1 choice [ 6.037499] hub 7-0:1.0: USB hub found [ 6.037572] hub 7-0:1.0: 2 ports detected [ 6.134647] igb 0000:01:00.0: Intel(R) Gigabit Ethernet Network Connection [ 6.134721] igb 0000:01:00.0: eth0: (PCIe:2.5Gb/s:Width x4) 00:25:90:04:4b:14 [ 6.134864] igb 0000:01:00.0: eth0: PBA No: FFFFFF-0FF [ 6.134927] igb 0000:01:00.0: Using MSI-X interrupts. 4 rx queue(s), 4 tx queue(s) [ 6.135067] igb 0000:01:00.1: PCI INT B -> GSI 40 (level, low) -> IRQ 40 [ 6.136089] igb 0000:01:00.1: 0 vfs allocated [ 6.327377] igb 0000:01:00.1: Intel(R) Gigabit Ethernet Network Connection [ 6.327448] igb 0000:01:00.1: eth1: (PCIe:2.5Gb/s:Width x4) 00:25:90:04:4b:15 [ 6.327590] igb 0000:01:00.1: eth1: PBA No: FFFFFF-0FF [ 6.327653] igb 0000:01:00.1: Using MSI-X interrupts. 4 rx queue(s), 4 tx queue(s) [ 6.654750] 3w-9xxx: scsi0: AEN: INFO (0x04:0x0053): Battery capacity test is overdue:. [ 6.674745] usb 4-2: new full speed USB device using uhci_hcd and address 2 [ 6.766743] scsi0 : 3ware 9000 Storage Controller [ 6.766887] 3w-9xxx: scsi0: Found a 3ware 9000 Storage Controller at 0xfafde000, IRQ: 26. [ 6.850186] usb 4-2: New USB device found, idVendor=046b, idProduct=ff10 [ 6.850263] usb 4-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 6.850330] usb 4-2: Product: Virtual Keyboard and Mouse [ 6.850393] usb 4-2: Manufacturer: American Megatrends Inc. [ 6.850457] usb 4-2: SerialNumber: serial [ 6.850590] usb 4-2: configuration #1 chosen from 1 choice [ 6.897267] usbcore: registered new interface driver hiddev [ 6.901457] input: American Megatrends Inc. Virtual Keyboard and Mouse as /devices/pci0000:00/0000:00:1a.1/usb4/4-2/4-2:1.0/input/input1 [ 6.901641] generic-usb 0003:046B:FF10.0001: input,hidraw0: USB HID v1.10 Keyboard [American Megatrends Inc. Virtual Keyboard and Mouse] on usb-0000:00:1a.1-2/input0 [ 6.916496] input: American Megatrends Inc. Virtual Keyboard and Mouse as /devices/pci0000:00/0000:00:1a.1/usb4/4-2/4-2:1.1/input/input2 [ 6.916815] generic-usb 0003:046B:FF10.0002: input,hidraw1: USB HID v1.10 Mouse [American Megatrends Inc. Virtual Keyboard and Mouse] on usb-0000:00:1a.1-2/input1 [ 6.916963] usbcore: registered new interface driver usbhid [ 6.917125] usbhid: v2.6:USB HID core driver [ 7.102745] 3w-9xxx: scsi0: Firmware FE9X 4.08.00.006, BIOS BE9X 4.08.00.001, Ports: 8. [ 7.103223] scsi 0:0:0:0: Direct-Access AMCC 9650SE-8LP DISK 4.08 PQ: 0 ANSI: 5 [ 7.124029] sd 0:0:0:0: [sda] 3906166784 512-byte logical blocks: (1.99 TB/1.81 TiB) [ 7.124633] sd 0:0:0:0: [sda] Write Protect is off [ 7.125125] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn''t support DPO or FUA [ 7.126389] sda: sda1 sda2 sda3 sda4 < sda5 > [ 7.184774] sd 0:0:0:0: [sda] Attached SCSI disk Begin: Loading essential drivers ... done. Begin: Running /scripts/init-premount ... done. Begin: Mounting root file system ... Begin: Running /scripts/local-top ... [ 7.586515] device-mapper: uevent: version 1.0.3 [ 7.587016] device-mapper: ioctl: 4.15.0-ioctl (2009-04-01) initialised: dm-devel@redhat.com done. Begin: Running /scripts/local-premount ... [ 7.606872] PM: Starting manual resume from disk done. [ 7.646063] kjournald starting. Commit interval 5 seconds [ 7.646072] EXT3-fs: mounted filesystem with ordered data mode. Begin: Running /scripts/local-bottom ... done. done. Begin: Running /scripts/init-bottom ... done. INIT: version 2.88 booting Using makefile-style concurrent boot in runlevel S. Starting the hotplug events dispatcher: udevd[ 8.531285] udev[558]: starting version 164 . Synthesizing the initial hotplug events...done. [ 8.613609] ACPI: SSDT 00000000bf79e0e0 008F0 (v01 DpgPmm P001Ist 00000011 INTL 20051117) [ 8.614565] ACPI: SSDT 00000000bf79e9d0 004D5 (v01 PmRef P001Cst 00003001 INTL 20051117) Waiting for /dev to be fully populated...[ 8.625918] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input3 [ 8.626027] ACPI: Power Button [PWRB] (XEN) cpuid.MWAIT[.eax=40, .ebx=40, .ecx=3, .edx=1120] (XEN) Monitor-Mwait will be used to enter C-3 state (XEN) cpuid.MWAIT[.eax=40, .ebx=40, .ecx=3, .edx=1120] (XEN) Monitor-Mwait will be used to enter C-3 state [ 8.641593] ACPI: CPU-1 (power states: C1[C1] C2[C3] C3[C3]) [ 8.641901] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4 [ 8.641991] ACPI: Power Button [PWRF] (XEN) cpuid.MWAIT[.eax=40, .ebx=40, .ecx=3, .edx=1120] (XEN) Monitor-Mwait will be used to enter C-3 state (XEN) cpuid.MWAIT[.eax=40, .ebx=40, .ecx=3, .edx=1120] (XEN) Monitor-Mwait will be used to enter C-3 state [ 8.643737] ACPI: CPU-1 (power states: C1[C1] C2[C3] C3[C3]) [ 8.673194] ioatdma: Intel(R) QuickData Technology Driver 4.00 [ 8.673374] ioatdma 0000:00:16.0: PCI INT A -> GSI 43 (level, low) -> IRQ 43 [ 8.676070] ioatdma 0000:00:16.1: PCI INT B -> GSI 44 (level, low) -> IRQ 44 [ 8.676683] ioatdma 0000:00:16.2: PCI INT C -> GSI 45 (level, low) -> IRQ 45 [ 8.677233] ioatdma 0000:00:16.3: PCI INT D -> GSI 46 (level, low) -> IRQ 46 [ 8.677860] xen_allocate_pirq: returning irq 43 for gsi 43 [ 8.677944] Already setup the GSI :43 [ 8.678015] ioatdma 0000:00:16.4: PCI INT A -> GSI 43 (level, low) -> IRQ 43 [ 8.679571] xen_allocate_pirq: returning irq 44 for gsi 44 [ 8.679640] Already setup the GSI :44 [ 8.679700] ioatdma 0000:00:16.5: PCI INT B -> GSI 44 (level, low) -> IRQ 44 [ 8.680335] xen_allocate_pirq: returning irq 45 for gsi 45 [ 8.680410] Already setup the GSI :45 [ 8.680476] ioatdma 0000:00:16.6: PCI INT C -> GSI 45 (level, low) -> IRQ 45 [ 8.681066] xen_allocate_pirq: returning irq 46 for gsi 46 [ 8.681134] Already setup the GSI :46 [ 8.681194] ioatdma 0000:00:16.7: PCI INT D -> GSI 46 (level, low) -> IRQ 46 [ 8.681772] ioatdma 0000:80:16.0: PCI INT A -> GSI 67 (level, low) -> IRQ 67 [ 8.682400] ioatdma 0000:80:16.1: PCI INT B -> GSI 68 (level, low) -> IRQ 68 [ 8.683037] ioatdma 0000:80:16.2: PCI INT C -> GSI 69 (level, low) -> IRQ 69 [ 8.686561] ioatdma 0000:80:16.3: PCI INT D -> GSI 70 (level, low) -> IRQ 70 [ 8.687562] xen_allocate_pirq: returning irq 67 for gsi 67 [ 8.687636] Already setup the GSI :67 [ 8.687704] ioatdma 0000:80:16.4: PCI INT A -> GSI 67 (level, low) -> IRQ 67 [ 8.688305] xen_allocate_pirq: returning irq 68 for gsi 68 [ 8.688379] Already setup the GSI :68 [ 8.688440] ioatdma 0000:80:16.5: PCI INT B -> GSI 68 (level, low) -> IRQ 68 (XEN) cpuid.MWAIT[.eax=40, .ebx=40, .ecx=3, .edx=1120] (XEN) Monitor-Mwait will be used to enter C-3 state (XEN) cpuid.MWAIT[.eax=40, .ebx=40, .ecx=3, .edx=1120] (XEN) Monitor-Mwait will be used to enter C-3 state [ 8.688934] ACPI: CPU-1 (power states: C1[C1] C2[C3] C3[C3]) [ 8.694138] xen_allocate_pirq: returning irq 69 for gsi 69 [ 8.694211] Already setup the GSI :69 [ 8.694273] ioatdma 0000:80:16.6: PCI INT C -> GSI 69 (level, low) -> IRQ 69 [ 8.697751] xen_allocate_pirq: returning irq 70 for gsi 70 [ 8.697833] Already setup the GSI :70 [ 8.697896] ioatdma 0000:80:16.7: PCI INT D -> GSI 70 (level, low) -> IRQ 70 [ 8.709359] xen_allocate_pirq: returning irq 18 for gsi 18 [ 8.709431] Already setup the GSI :18 [ 8.709491] i801_smbus 0000:00:1f.3: PCI INT C -> GSI 18 (level, low) -> IRQ 18 (XEN) cpuid.MWAIT[.eax=40, .ebx=40, .ecx=3, .edx=1120] (XEN) Monitor-Mwait will be used to enter C-3 state (XEN) cpuid.MWAIT[.eax=40, .ebx=40, .ecx=3, .edx=1120] (XEN) Monitor-Mwait will be used to enter C-3 state [ 8.718341] ACPI: CPU-1 (power states: C1[C1] C2[C3] C3[C3]) [ 8.810081] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 8.925381] Error: Driver ''pcspkr'' is already registered, aborting... [ 113.636747] 3w-9xxx: scsi0: AEN: INFO (0x04:0x000C): Initialize started:unit=0, subunit=1. [10065189777.645584] ------------[ cut here ]------------ [10065189777.645584] ------------[ cut here ]------------ [10065189777.645584] WARNING: at /build/buildd-linux-2.6_2.6.32-45-amd64-FcX7RM/linux-2.6-2.6.32/debian/build/source_amd64_xen/kernel/time/clockevents.c:112 clockevents_program_event+0x26/0x7e() [10065189777.645584] Hardware name: X8DTH-i/6/iF/6F [10065189777.645584] Modules linked in: psmouse serio_raw snd_pcm snd_timer snd soundcore snd_page_alloc joydev pcspkr evdev i2c_i801 i2c_core ioatdma button processor acpi_processor ext3 jbd mbcache dm_mod sd_mod crc_t10dif usbhid hid uhci_hcd ehci_hcd 3w_9xxx usbcore nls_base scsi_mod igb dca thermal thermal_sys [last unloaded: scsi_wait_scan] [10065189777.645584] Pid: 0, comm: swapper Not tainted 2.6.32-5-xen-amd64 #1 [10065189777.645584] Call Trace: [10065189777.645584] <IRQ> [<ffffffff81070297>] ? clockevents_program_event+0x26/0x7e [10065189777.645584] [<ffffffff81070297>] ? clockevents_program_event+0x26/0x7e [10065189777.645584] [<ffffffff8104ef90>] ? warn_slowpath_common+0x77/0xa3 [10065189777.645584] [<ffffffff81070297>] ? clockevents_program_event+0x26/0x7e [10065189777.645584] [<ffffffff8107131b>] ? tick_dev_program_event+0x2d/0x95 [10065189777.645584] [<ffffffff81068942>] ? hrtimer_interrupt+0x15d/0x18d [10065189777.645584] [<ffffffff8100e9af>] ? xen_timer_interrupt+0x34/0x18d [10065189777.645584] [<ffffffffa00a5ae2>] ? twa_interrupt+0xed/0x5e8 [3w_9xxx] [10065189777.645584] [<ffffffff8100dc7b>] ? drop_other_mm_ref+0x42/0x62 [10065189777.645584] [<ffffffff81095c44>] ? handle_IRQ_event+0x58/0x126 [10065189777.645584] [<ffffffff810973e3>] ? handle_percpu_irq+0x39/0x6a [10065189777.645584] [<ffffffff811f2aea>] ? __xen_evtchn_do_upcall+0x1d0/0x28d [10065189777.645584] [<ffffffff811f334b>] ? xen_evtchn_do_upcall+0x2e/0x42 [10065189777.645584] [<ffffffff81012cfe>] ? xen_do_hypervisor_callback+0x1e/0x30 [10065189777.645584] <EOI> [<ffffffff810093aa>] ? hypercall_page+0x3aa/0x1001 [10065189777.645584] [<ffffffff810093aa>] ? hypercall_page+0x3aa/0x1001 [10065189777.645584] [<ffffffff8100e6b3>] ? xen_safe_halt+0xc/0x15 [10065189777.645584] [<ffffffff8100bfc7>] ? xen_idle+0x37/0x40 [10065189777.645584] [<ffffffff81010e97>] ? cpu_idle+0xa2/0xda [10065189777.645584] [<ffffffff81531cdd>] ? start_kernel+0x3dc/0x3e8 [10065189777.645584] [<ffffffff81533c93>] ? xen_start_kernel+0x586/0x58a [10065189777.645584] ---[ end trace 8a7858275426dc3b ]--- [10065189777.645584] CE: xen increasing min_delta_ns to 300000 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 450000 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 675000 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 1012500 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 1518750 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 2278124 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 3417186 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 5125778 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 7688666 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 11532998 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 17299496 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 25949244 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 38923866 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 58385798 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 87578696 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 131368044 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 197052066 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 295578098 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 443367146 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 665050718 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 997576076 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 12580719133206580970 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 9647706662955095646 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 14471559994432643468 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 3260595917939413586 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 14114265913763896186 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 11948026833791068470 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 17922040250686602704 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 8436316302320352440 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 3431102416625752852 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 5146653624938629278 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 7719980437407943916 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 11579970656111915874 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 17369955984167873810 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 7608189902542259098 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 2188912816958612838 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 3283369225437919256 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 4925053838156878884 nsec [10065189777.645584] CE: xen increasing min_delta_ns to 7387580757235318326 nsec I sat watching this for a few minutes and it showed no sign of stopping. The machine is also completely unresponsive aside from printing that to console.
Jan Beulich
2012-Jul-30 07:58 UTC
Re: Failure to boot, Debian squeeze with 4.0.1 hypervisor, timer problems?
>>> On 27.07.12 at 18:15, Andy Smith <andy@strugglers.net> wrote: > I upgraded it to Debian squeeze, with xen-hypervisor-4.0-amd64 > (4.0.1-4) / linux-image-2.6.32-5-xen-amd64 (2.6.32-45) and now it > does not complete a boot of the dom0 kernel.The minimum would be to check against 4.0.3 (ideally tip of 4.0-testing), to exclude a problem already fixed even on that branch. Next would be to try with -unstable. Other options to try would be - dropping "dom0_mem=" - trying "clocksource=acpi" - trying "clocksource=pit" Jan> I can boot it into a vanilla kernel directly on bare metal and it > seems okay with that. > > Reproduced below is full serial console output of boot of hypervisor > and dom0 kernel. I''ve indicated the points where progress seems to > halt or be abnormally slow. The boot ends with a repeated message > of: > > [10065189777.645584] CE: xen increasing min_delta_ns to 7197131137619773666 > nsec > [10065189777.645584] CE: xen increasing min_delta_ns to 1572324669574884690 > nsec > etc. > > Between this and some other messages about timers earlier on I > gather there are timer problems here at the very least. > > If anyone has any suggestions for boot parameters that may allow > further diagnosis or workarounds I would be extremely grateful. > Otherwise I guess I replace the motherboard with a different one and > hope it works.
Andy Smith
2012-Aug-06 03:55 UTC
Re: Failure to boot, Debian squeeze with 4.0.1 hypervisor, timer problems?
Hi Jan, Thanks for getting back to me. On Mon, Jul 30, 2012 at 08:58:03AM +0100, Jan Beulich wrote:> >>> On 27.07.12 at 18:15, Andy Smith <andy@strugglers.net> wrote: > > I upgraded it to Debian squeeze, with xen-hypervisor-4.0-amd64 > > (4.0.1-4) / linux-image-2.6.32-5-xen-amd64 (2.6.32-45) and now it > > does not complete a boot of the dom0 kernel. > > The minimum would be to check against 4.0.3 (ideally tip of > 4.0-testing), to exclude a problem already fixed even on that > branch.I checked out http://xenbits.xen.org/hg/xen-4.0-testing.hg and compiled the hypervisor. It seems to boot okay, though I have not yet done any exhaustive stress tests. "xm info" now says: host : sol release : 2.6.32-5-xen-amd64 version : #1 SMP Sun May 6 08:57:29 UTC 2012 machine : x86_64 nr_cpus : 4 nr_nodes : 1 cores_per_socket : 4 threads_per_core : 1 cpu_mhz : 2133 hw_caps : bfebfbff:28100800:00000000:00001b40:009ce3bd:00000000:00000001:00000000 virt_caps : hvm total_memory : 24567 free_memory : 23280 node_to_cpu : node0:0-3 node_to_memory : node0:23280 node_to_dma32_mem : node0:2996 max_node_id : 0 xen_major : 4 xen_minor : 0 xen_extra : .4-rc4-pre 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 : Mon Jul 30 13:39:47 2012 +0100 21607:6d7ae840463c xen_commandline : placeholder dom0_mem=1024M loglvl=all guest_loglvl=all com1=9600,8n1 console=com1,vga serial_tx_buffer=64k cc_compiler : gcc version 4.4.5 (Debian 4.4.5-8) cc_compile_by : andy cc_compile_domain : strugglers.net cc_compile_date : Mon Aug 6 02:40:47 UTC 2012 xend_config_format : 4 (a keen observer will note that my last set of logs showed 96G RAM, whereas it now has only 24G. I had to take those 6x16G DIMMs out and use them for something else in the meantime, and I replaced them with 6x4G ones. I did confirm that the problem was still evident before carrying on.) Is there any point at this stage in trying to find out which commit appears to have fixed the problem I was seeing? If so, any pointers on the best ways to do that would be appreciated as I''m not that familiar with hg (or git). I am not ready to use 4.1 in production at the moment so I have to stick with 4.0.x. I''m assuming that if I wish to continue using 4.0.x on this host I would be better off compiling the released 4.0.3 and using that (if it works for me), as opposed to the tip of 4.0-testing that I have here? Thanks, Andy
Pasi Kärkkäinen
2012-Aug-06 06:36 UTC
Re: Failure to boot, Debian squeeze with 4.0.1 hypervisor, timer problems?
On Mon, Aug 06, 2012 at 03:55:40AM +0000, Andy Smith wrote:> > I am not ready to use 4.1 in production at the moment so I have to > stick with 4.0.x. I''m assuming that if I wish to continue using > 4.0.x on this host I would be better off compiling the released > 4.0.3 and using that (if it works for me), as opposed to the tip of > 4.0-testing that I have here? >Well.. if you read the changelog at http://xenbits.xen.org/xen-4.0-testing.hg there''s only 3 changesets after 4.0.3 release. So judge yourself :) they look like pretty straight forward bugfixes. Also one of them is a security fix! -- Pasi
Andy Smith
2012-Aug-06 06:45 UTC
Re: Failure to boot, Debian squeeze with 4.0.1 hypervisor, timer problems?
Hi Pasi, On Mon, Aug 06, 2012 at 09:36:29AM +0300, Pasi Kärkkäinen wrote:> So judge yourself :) they look like pretty straight forward bugfixes. > Also one of them is a security fix!I see. Yes, going without that one is not going to be an option. Thanks, Andy
Jan Beulich
2012-Aug-06 07:32 UTC
Re: Failure to boot, Debian squeeze with 4.0.1 hypervisor, timer problems?
>>> On 06.08.12 at 05:55, Andy Smith <andy@strugglers.net> wrote: > I checked out http://xenbits.xen.org/hg/xen-4.0-testing.hg and > compiled the hypervisor. It seems to boot okay, though I have not > yet done any exhaustive stress tests.Good.> Is there any point at this stage in trying to find out which commit > appears to have fixed the problem I was seeing?That''s a question you have to ask yourself (or the Debian folks if you want them to deliver a fixed packed). From our (developers'') perspective knowing the problem is fixed is sufficient.> If so, any pointers > on the best ways to do that would be appreciated as I''m not that > familiar with hg (or git).Bi-section would probably be the best thing to do, unless by going through the differences or individual changeset descriptions you''re able to right away turn up possible candidates. Jan
Andy Smith
2012-Aug-08 20:53 UTC
Re: Failure to boot, Debian squeeze with 4.0.1 hypervisor, timer problems?
Hi, On Mon, Aug 06, 2012 at 08:32:20AM +0100, Jan Beulich wrote:> >>> On 06.08.12 at 05:55, Andy Smith <andy@strugglers.net> wrote: > > Is there any point at this stage in trying to find out which commit > > appears to have fixed the problem I was seeing? > > That''s a question you have to ask yourself (or the Debian folks > if you want them to deliver a fixed packed). From our > (developers'') perspective knowing the problem is fixed is > sufficient.I bisected it down to changeset 21332 fixing the problem for me: http://xenbits.xen.org/hg/xen-4.0-testing.hg/rev/ab1fb1b8b569?revcount=480 And have reported it as Debian bug #684334: http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=684334 in case they should want to fix it for their soon-to-be oldstable release. Thanks for your help, Andy
Possibly Parallel Threads
- Bug#603727: xen-hypervisor-4.0-amd64: i386 Dom0 crashes after doing some I/O on local storage (software Raid1 on SAS-drives with mpt2sas driver)
- pv_ops kernel 2.6.29-rc6 boot failure
- Bug#748052: dom0 USB failing with "ehci-pci: probe of 0000:00:1d.0 failed with error -110"
- Is: Xen 4.2 and using 'xl' to save/restore is buggy with PVHVM Linux guests (v3.10 and v3.11 and presumarily earlier as well). Works with Xen 4.3 and Xen 4.4. Was:Re: FAILURE 3.11.0-rc7upstream(x86_64) 3.11.0-rc7upstream(i386)\: 2013-08-26 (tst001)
- Bug#748052: Bug#748052: Bug#748052: Bug#748052: xen-hypervisor-4.3-a