Re: [Xen-users] After memory upgrade - slow boot and "ERROR: Inv

No ideas?

No hints, suggestions, help?

TBH, this only (and many other hardware issues) happens with 2.6.18kernel from xen. Any other (newer) kernel doesn't have such problems.

Is the 2.6.18 kernel simply to old for new hardware?? Do I really needto use a patched kernel from my distribution?


Or, is there a newer kernel from xen under development, which I could try?

Ralf

Ralf Hornik Mailings <ralf@xxxxxxxxxxxxxxxxx> schreibte:

Hi list,

since my memory upgrade from 2G to 6G RAM, the xen 2.6.18.8 kernelfrom xen 3.3.1 does boot very slow and with call traces (see dmesgoutput below).


root@dom0:~# dmesg
0x000003a5 MSFT 0x01000013) @ 0x00000000ceff4000
ACPI:  (v001 INTEL  DQ3510J  0x000003a5 MSFT 0x01000013) @ 0x00000000ceff2000
 >>> ERROR: Invalid checksum

ACPI: ASPT (v001 INTEL DQ3510J 0x000003a5 MSFT 0x01000013) @0x00000000ceff1000ACPI: WDTT (v001 INTEL DQ3510J 0x000003a5 MSFT 0x01000013) @0x00000000ceff0000ACPI: SSDT (v001 INTEL CpuPm 0x000003a5 MSFT 0x01000013) @0x00000000cefef000ACPI: SSDT (v001 INTEL Cpu0Ist 0x000003a5 MSFT 0x01000013) @0x00000000cefee000ACPI: SSDT (v001 INTEL Cpu1Ist 0x000003a5 MSFT 0x01000013) @0x00000000cefed000ACPI: SSDT (v001 INTEL Cpu2Ist 0x000003a5 MSFT 0x01000013) @0x00000000cefec000ACPI: SSDT (v001 INTEL Cpu3Ist 0x000003a5 MSFT 0x01000013) @0x00000000cefeb000ACPI: SSDT (v001 INTEL Cpu0Cst 0x000003a5 MSFT 0x01000013) @0x00000000cefea000ACPI: SSDT (v001 INTEL Cpu1Cst 0x000003a5 MSFT 0x01000013) @0x00000000cefe9000ACPI: SSDT (v001 INTEL Cpu2Cst 0x000003a5 MSFT 0x01000013) @0x00000000cefe8000ACPI: SSDT (v001 INTEL Cpu3Cst 0x000003a5 MSFT 0x01000013) @0x00000000cefe7000ACPI: TCPA (v002 INTEL TIANO 0x00000002 MSFT 0x01000013) @0x00000000cef7b000ACPI: DSDT (v001 INTEL DQ3510J 0x000003a5 MSFT 0x01000013) @0x0000000000000000

ACPI: Local APIC address 0xfee00000
ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled)
ACPI: LAPIC (acpi_id[0x03] lapic_id[0x02] enabled)
ACPI: LAPIC (acpi_id[0x02] lapic_id[0x01] enabled)
ACPI: LAPIC (acpi_id[0x04] lapic_id[0x03] enabled)
ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1])
ACPI: LAPIC_NMI (acpi_id[0x02] dfl dfl lint[0x1])
ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-23
ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
ACPI: IRQ0 used by override.
ACPI: IRQ2 used by override.
ACPI: IRQ9 used by override.
Setting APIC routing to xen
Using ACPI (MADT) for SMP configuration information
Allocating PCI resources starting at d2000000 (gap: d0000000:20000000)
Built 1 zonelists.  Total pages: 1470489

Kernel command line: root=/dev/mapper/sys_vol-sys_root roconsole=tty0 xencons=ttyS16 console=ttyS16,19200 selinux=0pciback.hide=(00:1d.1) idebus=66

ide_setup: idebus=66
Initializing CPU#0
PID hash table entries: 4096 (order: 12, 32768 bytes)
Xen reported: 2388.006 MHz processor.
start_kernel(): bug: interrupts were enabled early
Console: colour VGA+ 80x25
Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes)
Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes)
Software IO TLB enabled:
Aperture:     64 megabytes
Kernel range: ffff880008e11000 - ffff88000ce11000
Address size: 27 bits
PCI-DMA: Using software bounce buffering for IO (SWIOTLB)

Memory: 5748184k/5963488k available (2085k kernel code, 206224kreserved, 909k data, 180k init)Calibrating delay using timer specific routine.. 4776.77 BogoMIPS(lpj=2388388)

Security Framework v1.0.0 initialized
Capability LSM initialized
Mount-cache hash table entries: 256
CPU: L1 I cache: 32K, L1 D cache: 32K
CPU: L2 cache: 4096K
CPU: Physical Processor ID: 0
CPU: Processor Core ID: 0
SMP alternatives: switching to UP code
ACPI: Core revision 20060707
SMP alternatives: switching to SMP code
Initializing CPU#1
Initializing CPU#2
CPU: L1 I cache: 32K, L1 D cache: 32K
CPU: L2 cache: 4096K
CPU: Physical Processor ID: 0
CPU: Processor Core ID: 1
Brought up 4 CPUs
Initializing CPU#3
CPU: L1 I cache: 32K, L1 D cache: 32K
CPU: L2 cache: 4096K
CPU: Physical Processor ID: 0
CPU: Processor Core ID: 3
CPU: L1 I cache: 32K, L1 D cache: 32K
CPU: L2 cache: 4096K
CPU: Physical Processor ID: 0
CPU: Processor Core ID: 2
migration_cost=3871
checking if image is initramfs... it is
Freeing initrd memory: 18462k freed
PM: Adding info for No Bus:platform
NET: Registered protocol family 16
PM: Adding info for No Bus:xen
PM: Adding info for No Bus:xen-backend
ACPI: bus type pci registered
PCI: Using configuration type 1
ACPI: Interpreter enabled
ACPI: Using IOAPIC for interrupt routing
PM: Adding info for acpi:acpi
ACPI: PCI Root Bridge [PCI0] (0000:00)
PCI: Probing PCI hardware (bus 00)
PM: Adding info for No Bus:pci0000:00
ACPI: Assume root bridge [_SB_.PCI0] bus is 0
Boot video device is 0000:00:02.0
PCI: Transparent bridge - 0000:00:1e.0
ACPI: PCI Interrupt Routing Table [_SB_.PCI0._PRT]
PM: Adding info for pci:0000:00:00.0
PM: Adding info for pci:0000:00:02.0
PM: Adding info for pci:0000:00:03.0
PM: Adding info for pci:0000:00:03.2
PM: Adding info for pci:0000:00:03.3
PM: Adding info for pci:0000:00:19.0
PM: Adding info for pci:0000:00:1a.0
PM: Adding info for pci:0000:00:1a.1
PM: Adding info for pci:0000:00:1a.2
PM: Adding info for pci:0000:00:1a.7
PM: Adding info for pci:0000:00:1b.0
PM: Adding info for pci:0000:00:1c.0
PM: Adding info for pci:0000:00:1c.1
PM: Adding info for pci:0000:00:1c.2
PM: Adding info for pci:0000:00:1c.3
PM: Adding info for pci:0000:00:1c.4
PM: Adding info for pci:0000:00:1d.0
PM: Adding info for pci:0000:00:1d.1
PM: Adding info for pci:0000:00:1d.2
PM: Adding info for pci:0000:00:1d.7
PM: Adding info for pci:0000:00:1e.0
PM: Adding info for pci:0000:00:1f.0
PM: Adding info for pci:0000:00:1f.2
PM: Adding info for pci:0000:00:1f.3
PM: Adding info for pci:0000:00:1f.5
PM: Adding info for pci:0000:03:00.0
PM: Adding info for pci:0000:06:00.0
ACPI: PCI Interrupt Routing Table [_SB_.PCI0.P32_._PRT]
ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 7 9 10 *11 12)
ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 7 9 *10 11 12)
ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 7 *9 10 11 12)
ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 7 9 10 *11 12)
ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 7 9 10 *11 12)
ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 7 *9 10 11 12)
ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 7 9 *10 11 12)
ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 7 9 10 *11 12)
ACPI: PCI Interrupt Routing Table [_SB_.PCI0.PEX0._PRT]
ACPI: PCI Interrupt Routing Table [_SB_.PCI0.PEX1._PRT]
ACPI: PCI Interrupt Routing Table [_SB_.PCI0.PEX2._PRT]
ACPI: PCI Interrupt Routing Table [_SB_.PCI0.PEX3._PRT]
ACPI: PCI Interrupt Routing Table [_SB_.PCI0.PEX4._PRT]
Linux Plug and Play Support v0.97 (c) Adam Belay
pnp: PnP ACPI init
PM: Adding info for No Bus:pnp0
pnp: ACPI device : hid PNP0A03
PM: Adding info for pnp:00:00
pnp: ACPI device : hid PNP0C02
PM: Adding info for pnp:00:01
pnp: ACPI device : hid PNP0200
PM: Adding info for pnp:00:02
pnp: ACPI device : hid PNP0B00
PM: Adding info for pnp:00:03
pnp: ACPI device : hid PNP0C04
PM: Adding info for pnp:00:04
pnp: ACPI device : hid PNP0800
PM: Adding info for pnp:00:05
pnp: ACPI device : hid PNP0C02
PM: Adding info for pnp:00:06
pnp: ACPI device : hid WEC1000
PM: Adding info for pnp:00:07
pnp: ACPI device : hid PNP0C02
PM: Adding info for pnp:00:08
pnp: ACPI device : hid PNP0501
PM: Adding info for pnp:00:09
pnp: ACPI device : hid PNP0003
PM: Adding info for pnp:00:0a
pnp: PnP ACPI: found 11 devices
xen_mem: Initialising balloon driver.
PCI: Using ACPI for IRQ routing

PCI: If a device doesn't work, try "pci=routeirq". If it helps,post a report

pnp: the driver 'system' has been registered
pnp: match found with the PnP device '00:01' and the driver 'system'
pnp: match found with the PnP device '00:06' and the driver 'system'
pnp: 00:06: ioport range 0x500-0x53f has been reserved
pnp: 00:06: ioport range 0x400-0x47f could not be reserved
pnp: 00:06: ioport range 0x680-0x6ff has been reserved
pnp: match found with the PnP device '00:08' and the driver 'system'
pciback 0000:00:1d.1: seizing device
PCI: Ignore bogus resource 6 [0:0] of 0000:00:02.0
PCI: Bridge: 0000:00:1c.0
 IO window: disabled.
 MEM window: disabled.
 PREFETCH window: disabled.
PCI: Bridge: 0000:00:1c.1
 IO window: disabled.
 MEM window: disabled.
 PREFETCH window: disabled.
PCI: Bridge: 0000:00:1c.2
 IO window: 2000-2fff
 MEM window: e0100000-e01fffff
 PREFETCH window: disabled.
PCI: Bridge: 0000:00:1c.3
 IO window: disabled.
 MEM window: disabled.
 PREFETCH window: disabled.
PCI: Bridge: 0000:00:1c.4
 IO window: disabled.
 MEM window: disabled.
 PREFETCH window: disabled.
PCI: Bridge: 0000:00:1e.0
 IO window: 1000-1fff
 MEM window: e0000000-e00fffff
 PREFETCH window: e0400000-e04fffff
GSI 16 sharing vector 0xC0 and IRQ 16
ACPI: PCI Interrupt 0000:00:1c.0[A] -> GSI 17 (level, low) -> IRQ 16
PCI: Setting latency timer of device 0000:00:1c.0 to 64
GSI 17 sharing vector 0xC8 and IRQ 17
ACPI: PCI Interrupt 0000:00:1c.1[B] -> GSI 20 (level, low) -> IRQ 17
PCI: Setting latency timer of device 0000:00:1c.1 to 64
GSI 18 sharing vector 0xD0 and IRQ 18
ACPI: PCI Interrupt 0000:00:1c.2[C] -> GSI 18 (level, low) -> IRQ 18
PCI: Setting latency timer of device 0000:00:1c.2 to 64
GSI 19 sharing vector 0xD8 and IRQ 19
ACPI: PCI Interrupt 0000:00:1c.3[D] -> GSI 19 (level, low) -> IRQ 19
PCI: Setting latency timer of device 0000:00:1c.3 to 64
ACPI: PCI Interrupt 0000:00:1c.4[A] -> GSI 17 (level, low) -> IRQ 16
PCI: Setting latency timer of device 0000:00:1c.4 to 64
PCI: Setting latency timer of device 0000:00:1e.0 to 64
NET: Registered protocol family 2
IP route cache hash table entries: 262144 (order: 9, 2097152 bytes)
TCP established hash table entries: 262144 (order: 10, 4194304 bytes)
TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
TCP: Hash tables configured (established 262144 bind 65536)
TCP reno registered
PM: Adding info for platform:pcspkr
IA-32 Microcode Update Driver: v1.14a-xen <tigran@xxxxxxxxxxx>
audit: initializing netlink socket (disabled)
audit(1233229473.844:1): initialized
VFS: Disk quotas dquot_6.5.1
Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Initializing Cryptographic API
io scheduler noop registered
io scheduler anticipatory registered
io scheduler deadline registered
io scheduler cfq registered (default)
PM: Adding info for platform:vesafb.0
Real Time Clock Driver v1.12ac
Non-volatile memory driver v1.2
Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
PM: Adding info for platform:serial8250
serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
pnp: the driver 'serial' has been registered
pnp: match found with the PnP device '00:09' and the driver 'serial'
00:09: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
ACPI: PCI Interrupt 0000:00:03.3[B] -> GSI 17 (level, low) -> IRQ 16
0000:00:03.3: ttyS1 at I/O 0x3470 (irq = 16) is a 16550A
RAMDISK driver initialized: 16 RAM disks of 16384K size 1024 blocksize
loop: loaded (max 8 devices)
Xen virtual console successfully installed as ttyS16
Event-channel device installed.
netfront: Initialising virtual ethernet driver.
ACPI: PCI Interrupt 0000:00:1d.1[B] -> GSI 19 (level, low) -> IRQ 19
ACPI: PCI interrupt for device 0000:00:1d.1 disabled
Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
ide: Assuming 66MHz system bus speed for PIO modes
pnp: the driver 'ide' has been registered
pnp: the driver 'i8042 kbd' has been registered
pnp: the driver 'i8042 aux' has been registered
pnp: the driver 'i8042 kbd' has been unregistered
pnp: the driver 'i8042 aux' has been unregistered
PNP: No PS/2 controller found. Probing ports directly.
PM: Adding info for platform:i8042
serio: i8042 AUX port at 0x60,0x64 irq 12
serio: i8042 KBD port at 0x60,0x64 irq 1
PM: Adding info for serio:serio0
PM: Adding info for serio:serio1
mice: PS/2 mouse device common for all mice
md: md driver 0.90.3 MAX_MD_DEVS=256, MD_SB_DISKS=27
md: bitmap version 4.39

device-mapper: ioctl: 4.7.0-ioctl (2006-06-24) initialised:dm-devel@xxxxxxxxxx

NET: Registered protocol family 1
NET: Registered protocol family 17
ACPI: (supports S0 S1 S3 S4 S5)
BIOS EDD facility v0.16 2004-Jun-25, 2 devices found
Freeing unused kernel memory: 180k freed
fuse init (API version 7.7)
ACPI: Processor [CPU0] (supports 8 throttling states)
ACPI: Processor [CPU1] (supports 8 throttling states)
ACPI: Processor [CPU2] (supports 8 throttling states)
ACPI: Processor [CPU3] (supports 8 throttling states)
usbcore: registered new driver usbfs
usbcore: registered new driver hub
SCSI subsystem initialized
PDC20269: IDE controller at PCI slot 0000:06:00.0
GSI 20 sharing vector 0x21 and IRQ 20
ACPI: PCI Interrupt 0000:06:00.0[A] -> GSI 21 (level, low) -> IRQ 20
PDC20269: chipset revision 2
PDC20269: ROM enabled at 0xe0400000
PDC20269: 100% native mode on irq 20
   ide0: BM-DMA at 0x1000-0x1007, BIOS settings: hda:pio, hdb:pio
   ide1: BM-DMA at 0x1008-0x100f, BIOS settings: hdc:pio, hdd:pio
Probing IDE interface ide0...
USB Universal Host Controller Interface driver v3.0
ACPI: PCI Interrupt 0000:00:1a.0[A] -> <7>libata version 2.00 loaded.
GSI 18 (level, low) -> IRQ 18
PCI: Setting latency timer of device 0000:00:1a.0 to 64
uhci_hcd 0000:00:1a.0: UHCI Host Controller
uhci_hcd 0000:00:1a.0: new USB bus registered, assigned bus number 1
uhci_hcd 0000:00:1a.0: irq 18, io base 0x000030c0
PM: Adding info for usb:usb1
PM: Adding info for No Bus:usbdev1.1_ep00
usb usb1: configuration #1 chosen from 1 choice
PM: Adding info for usb:1-0:1.0
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 2 ports detected
PM: Adding info for No Bus:usbdev1.1_ep81
ACPI: PCI Interrupt 0000:00:1a.1[B] -> hda: SAMSUNG HD300LD, ATA DISK drive
PM: Adding info for No Bus:ide0
usb 1-1: new low speed USB device using uhci_hcd and address 2
PM: Adding info for usb:1-1
PM: Adding info for No Bus:usbdev1.2_ep00
usb 1-1: configuration #1 chosen from 1 choice
PM: Adding info for usb:1-1:1.0
PM: Adding info for No Bus:usbdev1.2_ep81
PM: Adding info for usb:1-1:1.1
PM: Adding info for No Bus:usbdev1.2_ep82
ide0 at 0x1018-0x101f,0x1026 on irq 20
PM: Adding info for ide:0.0
Probing IDE interface ide1...
GSI 21 (level, low) -> IRQ 20
PCI: Setting latency timer of device 0000:00:1a.1 to 64
uhci_hcd 0000:00:1a.1: UHCI Host Controller
uhci_hcd 0000:00:1a.1: new USB bus registered, assigned bus number 2
uhci_hcd 0000:00:1a.1: irq 20, io base 0x000030a0
PM: Adding info for usb:usb2
PM: Adding info for No Bus:usbdev2.1_ep00
usb usb2: configuration #1 chosen from 1 choice
PM: Adding info for usb:2-0:1.0
hub 2-0:1.0: USB hub found
hub 2-0:1.0: 2 ports detected
hda: max request size: 512KiB
PM: Adding info for No Bus:usbdev2.1_ep81
ACPI: PCI Interrupt 0000:00:1a.2[C] -> GSI 17 (level, low) -> IRQ 16
PCI: Setting latency timer of device 0000:00:1a.2 to 64
uhci_hcd 0000:00:1a.2: UHCI Host Controller
uhci_hcd 0000:00:1a.2: new USB bus registered, assigned bus number 3
uhci_hcd 0000:00:1a.2: irq 16, io base 0x00003080
PM: Adding info for usb:usb3
PM: Adding info for No Bus:usbdev3.1_ep00
usb usb3: configuration #1 chosen from 1 choice
PM: Adding info for usb:3-0:1.0
hub 3-0:1.0: USB hub found
hub 3-0:1.0: 2 ports detected
PM: Adding info for No Bus:usbdev3.1_ep81
GSI 21 sharing vector 0x29 and IRQ 21
ACPI: PCI Interrupt 0000:00:1d.0[A] -> GSI 23 (level, low) -> IRQ 21
PCI: Setting latency timer of device 0000:00:1d.0 to 64
uhci_hcd 0000:00:1d.0: UHCI Host Controller
uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 4
uhci_hcd 0000:00:1d.0: irq 21, io base 0x00003060
PM: Adding info for usb:usb4
PM: Adding info for No Bus:usbdev4.1_ep00
usb usb4: configuration #1 chosen from 1 choice
PM: Adding info for usb:4-0:1.0
hub 4-0:1.0: USB hub found
hub 4-0:1.0: 2 ports detected
PM: Adding info for No Bus:usbdev4.1_ep81
ACPI: PCI Interrupt 0000:00:1d.2[C] -> GSI 18 (level, low) -> IRQ 18
PCI: Setting latency timer of device 0000:00:1d.2 to 64
uhci_hcd 0000:00:1d.2: UHCI Host Controller
uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 5
uhci_hcd 0000:00:1d.2: irq 18, io base 0x00003020
PM: Adding info for usb:usb5
PM: Adding info for No Bus:usbdev5.1_ep00
usb usb5: configuration #1 chosen from 1 choice
PM: Adding info for usb:5-0:1.0
hub 5-0:1.0: USB hub found
hub 5-0:1.0: 2 ports detected
PM: Adding info for No Bus:usbdev5.1_ep81
ACPI: PCI Interrupt 0000:00:1a.7[C] -> GSI 17 (level, low) -> IRQ 16
PCI: Setting latency timer of device 0000:00:1a.7 to 64
ehci_hcd 0000:00:1a.7: EHCI Host Controller
ehci_hcd 0000:00:1a.7: new USB bus registered, assigned bus number 6
ehci_hcd 0000:00:1a.7: debug port 1
PCI: cache line size of 32 is not supported by device 0000:00:1a.7
ehci_hcd 0000:00:1a.7: irq 16, io mem 0xe03a6400
ehci_hcd 0000:00:1a.7: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
PM: Adding info for usb:usb6
PM: Adding info for No Bus:usbdev6.1_ep00
usb usb6: configuration #1 chosen from 1 choice
PM: Adding info for usb:6-0:1.0
hub 6-0:1.0: USB hub found
hub 6-0:1.0: 6 ports detected
usb 1-1: USB disconnect, address 2
PM: Adding info for No Bus:usbdev6.1_ep81
ACPI: PCI Interrupt 0000:00:1d.7[A] -> GSI 23 (level, low) -> IRQ 21
PCI: Setting latency timer of device 0000:00:1d.7 to 64
ehci_hcd 0000:00:1d.7: EHCI Host Controller
ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 7
ehci_hcd 0000:00:1d.7: debug port 1
PCI: cache line size of 32 is not supported by device 0000:00:1d.7
ehci_hcd 0000:00:1d.7: irq 21, io mem 0xe03a6000
ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
PM: Adding info for usb:usb7
PM: Adding info for No Bus:usbdev7.1_ep00
usb usb7: configuration #1 chosen from 1 choice
PM: Adding info for usb:7-0:1.0
hub 7-0:1.0: USB hub found
hub 7-0:1.0: 6 ports detected

hda: 586072368 sectors (300069 MB) w/8192KiB Cache,CHS=36481/255/63, UDMA(100)

hda: cache flushes supported
hda: hda1
PM: Adding info for No Bus:usbdev7.1_ep81
ata_piix 0000:00:1f.2: version 2.00
ata_piix 0000:00:1f.2: MAP [ P0 P2 P1 P3 ]
ACPI: PCI Interrupt 0000:00:1f.2[A] -> GSI 21 (level, low) -> IRQ 20
PCI: Setting latency timer of device 0000:00:1f.2 to 64
BUG: soft lockup detected on CPU#0!

Call Trace:
<IRQ> [<ffffffff80257898>] softlockup_tick+0xd5/0xe7
[<ffffffff8020f03f>] timer_interrupt+0x3db/0x439
[<ffffffff80257b83>] handle_IRQ_event+0x4d/0x95
[<ffffffff80257c6f>] __do_IRQ+0xa4/0x105
[<ffffffff8020cda7>] do_IRQ+0x44/0x4d
[<ffffffff80352737>] evtchn_do_upcall+0x189/0x236
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff8020adce>] do_hypervisor_callback+0x1e/0x2c
<EOI> [<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff80213ade>] __smp_call_function+0x68/0x89
[<ffffffff80213ad6>] __smp_call_function+0x60/0x89
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff80213d39>] smp_call_function+0x32/0x49
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff80233356>] on_each_cpu+0xf/0x5d
[<ffffffff802804ba>] invalidate_bdev+0x2b/0x36
[<ffffffff80284f4e>] kill_bdev+0xe/0x21
[<ffffffff80285507>] __blkdev_put+0x4c/0x159
[<ffffffff802b9f31>] register_disk+0x146/0x199
[<ffffffff802dfcb1>] add_disk+0x34/0x3d
[<ffffffff880be9e7>] :ide_disk:ide_disk_probe+0x77b/0x7a8
[<ffffffff8034b950>] driver_probe_device+0x52/0xa2
[<ffffffff8034b9ff>] __driver_attach+0x0/0x8f
[<ffffffff8034ba57>] __driver_attach+0x58/0x8f
[<ffffffff8034b9ff>] __driver_attach+0x0/0x8f
[<ffffffff8034b374>] bus_for_each_dev+0x43/0x6e
[<ffffffff8034afdc>] bus_add_driver+0x73/0x122
[<ffffffff8024b13d>] sys_init_module+0x1720/0x184f
[<ffffffff8020a5e4>] system_call+0x68/0x6d
[<ffffffff8020a57c>] system_call+0x0/0x6d

PM: Removing info for No Bus:usbdev1.2_ep81
PM: Removing info for usb:1-1:1.0
PM: Removing info for No Bus:usbdev1.2_ep82
PM: Removing info for usb:1-1:1.1
PM: Removing info for No Bus:usbdev1.2_ep00
PM: Removing info for usb:1-1
ata1: SATA max UDMA/133 cmd 0x3468 ctl 0x349E bmdma 0x3430 irq 20
usbcore: registered new driver hiddev
usb 7-2: new high speed USB device using ehci_hcd and address 2
PM: Adding info for usb:7-2
PM: Adding info for No Bus:usbdev7.2_ep00
usb 7-2: configuration #1 chosen from 1 choice
PM: Adding info for usb:7-2:1.0
PM: Adding info for No Bus:usbdev7.2_ep01
PM: Adding info for No Bus:usbdev7.2_ep82
usbcore: registered new driver usbhid

/opt/install/xen/xen-3.3.1/linux-2.6.18-xen.hg/drivers/usb/input/hid-core.c:v2.6:USB HID core driver

usb 1-1: new low speed USB device using uhci_hcd and address 3
PM: Adding info for usb:1-1
PM: Adding info for No Bus:usbdev1.3_ep00
usb 1-1: configuration #1 chosen from 1 choice
PM: Adding info for usb:1-1:1.0

input: Plus More Enterprise LTD. USB-compliant keyboard as/class/input/input0input: USB HID v1.10 Keyboard [Plus More Enterprise LTD.USB-compliant keyboard] on usb-0000:00:1a.0-1

PM: Adding info for No Bus:usbdev1.3_ep81
PM: Adding info for usb:1-1:1.1

input: Plus More Enterprise LTD. USB-compliant keyboard as/class/input/input1input: USB HID v1.10 Mouse [Plus More Enterprise LTD. USB-compliantkeyboard] on usb-0000:00:1a.0-1

PM: Adding info for No Bus:usbdev1.3_ep82
BUG: soft lockup detected on CPU#0!

Call Trace:
<IRQ> [<ffffffff80257898>] softlockup_tick+0xd5/0xe7
[<ffffffff8020f03f>] timer_interrupt+0x3db/0x439
[<ffffffff80257b83>] handle_IRQ_event+0x4d/0x95
[<ffffffff80257c6f>] __do_IRQ+0xa4/0x105
[<ffffffff8020cda7>] do_IRQ+0x44/0x4d
[<ffffffff80352737>] evtchn_do_upcall+0x189/0x236
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff8020adce>] do_hypervisor_callback+0x1e/0x2c
<EOI> [<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff80213ada>] __smp_call_function+0x64/0x89
[<ffffffff80213ad6>] __smp_call_function+0x60/0x89
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff80213d39>] smp_call_function+0x32/0x49
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff80233356>] on_each_cpu+0xf/0x5d
[<ffffffff802804ba>] invalidate_bdev+0x2b/0x36
[<ffffffff80284f4e>] kill_bdev+0xe/0x21
[<ffffffff80285507>] __blkdev_put+0x4c/0x159
[<ffffffff8027f002>] __fput+0x9e/0x16e
[<ffffffff8027c7ac>] filp_close+0x5c/0x64
[<ffffffff8022fec6>] put_files_struct+0x6c/0xc3
[<ffffffff80231224>] do_exit+0x29b/0x921
[<ffffffff802ea213>] __up_write+0x1d/0x112
[<ffffffff80231988>] sys_exit_group+0x0/0xe
[<ffffffff8020a5e4>] system_call+0x68/0x6d
[<ffffffff8020a57c>] system_call+0x0/0x6d

ata2: SATA max UDMA/133 cmd 0x3460 ctl 0x349A bmdma 0x3438 irq 20
scsi0 : ata_piix
PM: Adding info for No Bus:host0
Initializing USB Mass Storage driver...
scsi2 : SCSI emulation for USB Mass Storage devices
ATA: abnormal status 0x7F on port 0x346F
scsi1 : ata_piix
PM: Adding info for No Bus:host1
ATA: abnormal status 0x7F on port 0x3467
ATA: abnormal status 0x7F on port 0x3467
ata2.01: ATA-7, max UDMA/133, 488397168 sectors: LBA48 NCQ (depth 0/32)
ata2.01: ata2: dev 1 multi count 16
ata2.01: configured for UDMA/133
PM: Adding info for No Bus:target1:0:1
 Vendor: ATA       Model: MAXTOR STM325031  Rev: 3.AA
 Type:   Direct-Access                      ANSI SCSI revision: 05
PM: Adding info for scsi:1:0:1:0
ata_piix 0000:00:1f.5: MAP [ P0 P2 P1 P3 ]
ACPI: PCI Interrupt 0000:00:1f.5[A] -> GSI 21 (level, low) -> IRQ 20
PCI: Setting latency timer of device 0000:00:1f.5 to 64
ata3: SATA max UDMA/133 cmd 0x3458 ctl 0x3496 bmdma 0x3410 irq 20
ata4: SATA max UDMA/133 cmd 0x3450 ctl 0x3492 bmdma 0x3418 irq 20
scsi3 : ata_piix
PM: Adding info for No Bus:host3
ATA: abnormal status 0x7F on port 0x345F
scsi4 : ata_piix
PM: Adding info for No Bus:host4
ATA: abnormal status 0x7F on port 0x3457
BUG: soft lockup detected on CPU#0!

Call Trace:
<IRQ> [<ffffffff80257898>] softlockup_tick+0xd5/0xe7
[<ffffffff8020f03f>] timer_interrupt+0x3db/0x439
[<ffffffff80257b83>] handle_IRQ_event+0x4d/0x95
[<ffffffff80257c6f>] __do_IRQ+0xa4/0x105
[<ffffffff8020cda7>] do_IRQ+0x44/0x4d
[<ffffffff80352737>] evtchn_do_upcall+0x189/0x236
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff8020adce>] do_hypervisor_callback+0x1e/0x2c
<EOI> [<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff80213ada>] __smp_call_function+0x64/0x89
[<ffffffff80213ad6>] __smp_call_function+0x60/0x89
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff80213d39>] smp_call_function+0x32/0x49
[<ffffffff8027f990>] invalidate_bh_lru+0x0/0x42
[<ffffffff80233356>] on_each_cpu+0xf/0x5d
[<ffffffff802804ba>] invalidate_bdev+0x2b/0x36
[<ffffffff80284f4e>] kill_bdev+0xe/0x21
[<ffffffff80285507>] __blkdev_put+0x4c/0x159
[<ffffffff8027f002>] __fput+0x9e/0x16e
[<ffffffff8027c7ac>] filp_close+0x5c/0x64
[<ffffffff8022fec6>] put_files_struct+0x6c/0xc3
[<ffffffff80231224>] do_exit+0x29b/0x921
[<ffffffff8027e7ec>] vfs_write+0x13f/0x174
[<ffffffff80231988>] sys_exit_group+0x0/0xe
[<ffffffff8020a5e4>] system_call+0x68/0x6d
[<ffffffff8020a57c>] system_call+0x0/0x6d

PM: Adding info for No Bus:host2
usbcore: registered new driver usb-storage
usb-storage: device found at 2
usb-storage: waiting for device to settle before scanning
USB Mass Storage support registered.
SCSI device sda: 488397168 512-byte hdwr sectors (250059 MB)
sda: Write Protect is off
sda: Mode Sense: 00 3a 00 00
SCSI device sda: drive cache: write back
PM: Adding info for No Bus:target2:0:0
 Vendor: Generic   Model: USB SD Reader     Rev: 1.00
 Type:   Direct-Access                      ANSI SCSI revision: 00
PM: Adding info for scsi:2:0:0:0
PM: Adding info for No Bus:target2:0:1
PM: Removing info for No Bus:target2:0:1
PM: Adding info for No Bus:target2:0:2
PM: Removing info for No Bus:target2:0:2
PM: Adding info for No Bus:target2:0:3
PM: Removing info for No Bus:target2:0:3
PM: Adding info for No Bus:target2:0:4
PM: Removing info for No Bus:target2:0:4
PM: Adding info for No Bus:target2:0:5
PM: Removing info for No Bus:target2:0:5
PM: Adding info for No Bus:target2:0:6
PM: Removing info for No Bus:target2:0:6
PM: Adding info for No Bus:target2:0:7
PM: Removing info for No Bus:target2:0:7
usb-storage: device scan complete
SCSI device sda: 488397168 512-byte hdwr sectors (250059 MB)
sda: Write Protect is off
sda: Mode Sense: 00 3a 00 00
SCSI device sda: drive cache: write back
sda: sda1 sda2 sda3
sd 1:0:1:0: Attached scsi disk sda
sd 2:0:0:0: Attached scsi removable disk sdb
sd 1:0:1:0: Attached scsi generic sg0 type 0
sd 2:0:0:0: Attached scsi generic sg1 type 0
kjournald starting.  Commit interval 5 seconds
EXT3-fs: mounted filesystem with ordered data mode.
input: PC Speaker as /class/input/input2
device-mapper: multipath: version 1.0.4 loaded
e1000e: Intel(R) PRO/1000 Network Driver - 0.4.1.12-NAPI
e1000e: Copyright (c) 1999-2008 Intel Corporation.
ACPI: PCI Interrupt 0000:00:19.0[A] -> GSI 20 (level, low) -> IRQ 17
PCI: Setting latency timer of device 0000:00:19.0 to 64

0000:00:19.0: : Failed to initialize MSI interrupts. Falling backto legacy interrupts.

0000:00:19.0: eth0: (PCI Express:2.5GB/s:Width x1) 00:1c:c0:41:0e:2c
0000:00:19.0: eth0: Intel(R) PRO/1000 Network Connection
0000:00:19.0: eth0: MAC: 7, PHY: 6, PBA No: ffffff-0ff
GSI 22 sharing vector 0x31 and IRQ 22
ACPI: PCI Interrupt 0000:00:1b.0[A] -> GSI 22 (level, low) -> IRQ 22
PCI: Setting latency timer of device 0000:00:1b.0 to 64
pnp: the driver 'parport_pc' has been registered
lp: driver loaded but no devices found

Adding 2097144k swap on /dev/mapper/sys_vol-sys_swap. Priority:-1extents:1 across:2097144k

EXT3 FS on dm-17, internal journal
kjournald starting.  Commit interval 5 seconds
EXT3 FS on sda1, internal journal
EXT3-fs: mounted filesystem with ordered data mode.
kjournald starting.  Commit interval 5 seconds
EXT3 FS on dm-19, internal journal
EXT3-fs: mounted filesystem with ordered data mode.
kjournald starting.  Commit interval 5 seconds
EXT3 FS on dm-20, internal journal
EXT3-fs: mounted filesystem with ordered data mode.
Bridge firewalling registered
device eth0 entered promiscuous mode
audit(1233229874.464:2): dev=eth0 prom=256 old_prom=0 auid=4294967295
0000:00:19.0: eth0: Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX
0000:00:19.0: eth0: 10/100 speed: disabling TSO
br0: port 1(eth0) entering learning state
br0: topology change detected, propagating
br0: port 1(eth0) entering forwarding state
NET: Registered protocol family 10
lo: Disabled Privacy Extensions
IPv6 over IPv4 tunneling driver
suspend: event channel 26
PM: Adding info for xen-backend:vfb-1-0
PM: Adding info for xen-backend:vbd-1-768
PM: Adding info for xen-backend:vif-1-0
pciback 0000:00:1d.1: enabling permissive mode configuration space accesses!
pciback 0000:00:1d.1: permissive mode is potentially unsafe!
PM: Adding info for xen-backend:pci-1-0
device vif1.0 entered promiscuous mode
audit(1233229901.660:3): dev=vif1.0 prom=256 old_prom=0 auid=4294967295
br0: port 2(vif1.0) entering learning state
ip_tables: (C) 2000-2006 Netfilter Core Team
tun: Universal TUN/TAP device driver, 1.6
tun: (C) 1999-2004 Max Krasnyansky <maxk@xxxxxxxxxxxx>
device tap1.0 entered promiscuous mode
audit(1233229902.193:4): dev=tap1.0 prom=256 old_prom=0 auid=4294967295
br0: port 3(tap1.0) entering learning state
eth0: no IPv6 routers present
PM: Adding info for xen-backend:console-1-0
PM: Adding info for xen-backend:vbd-2-51712
PM: Adding info for xen-backend:vbd-2-51715
PM: Adding info for xen-backend:vif-2-0
PM: Adding info for xen-backend:console-2-0
device vif2.0 entered promiscuous mode
audit(1233229908.988:5): dev=vif2.0 prom=256 old_prom=0 auid=4294967295
br0: port 4(vif2.0) entering learning state
PM: Removing info for xen-backend:console-2-0
PM: Removing info for xen-backend:vbd-2-51712
PM: Removing info for xen-backend:vbd-2-51715
br0: port 4(vif2.0) entering disabled state
device vif2.0 left promiscuous mode
audit(1233229909.419:6): dev=vif2.0 prom=0 old_prom=256 auid=4294967295
br0: port 4(vif2.0) entering disabled state
PM: Removing info for xen-backend:vif-2-0
vif1.0: no IPv6 routers present
tap1.0: no IPv6 routers present
br0: topology change detected, propagating
br0: port 2(vif1.0) entering forwarding state
br0: topology change detected, propagating
br0: port 3(tap1.0) entering forwarding state
PM: Adding info for xen-backend:vbd-3-51712
PM: Adding info for xen-backend:vbd-3-51715
PM: Adding info for xen-backend:vif-3-0
PM: Adding info for xen-backend:console-3-0
device vif3.0 entered promiscuous mode
audit(1233230236.962:7): dev=vif3.0 prom=256 old_prom=0 auid=4294967295
br0: port 4(vif3.0) entering learning state
blkback: ring-ref 2047, event-channel 4, protocol 1 (x86_64-abi)
blkback: ring-ref 2046, event-channel 5, protocol 1 (x86_64-abi)
frontend_changed: backend/vbd/3/51712: prepare for reconnect
frontend_changed: backend/vbd/3/51715: prepare for reconnect
blkback: ring-ref 8, event-channel 15, protocol 1 (x86_64-abi)
blkback: ring-ref 9, event-channel 16, protocol 1 (x86_64-abi)
vif3.0: no IPv6 routers present
br0: topology change detected, propagating
br0: port 4(vif3.0) entering forwarding state
root@dom0:~#

It takes about 10 minutes for booting, other (newer) kernels such as2.6.24-xen from ubuntu do not have this behavior.


My Mainboard is a Intel vPro DQ35MP with QuadCore Q6600.

Can anybody help me? I do not find the reason for this problem.

A memtest86+ did not show errors and I even replaced the RAM withanother, more expensive...:-(


Thank you and best regards


Ralf



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




--
alles bleibt anders...




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

WARNING - OLD ARCHIVES

xen-users

Re: [Xen-users] After memory upgrade - slow boot and "ERROR: Invalid che