From owner-freebsd-current Thu Mar 13 12:55: 6 2003 Delivered-To: freebsd-current@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id DE80F37B401 for ; Thu, 13 Mar 2003 12:54:51 -0800 (PST) Received: from khavrinen.lcs.mit.edu (khavrinen.lcs.mit.edu [18.24.4.193]) by mx1.FreeBSD.org (Postfix) with ESMTP id 9B6E543F93 for ; Thu, 13 Mar 2003 12:54:50 -0800 (PST) (envelope-from wollman@khavrinen.lcs.mit.edu) Received: from khavrinen.lcs.mit.edu (localhost.ipv6.lcs.mit.edu [IPv6:::1]) by khavrinen.lcs.mit.edu (8.12.8/8.12.8) with ESMTP id h2DKsmCd079940 (version=TLSv1/SSLv3 cipher=DHE-RSA-AES256-SHA bits=256 verify=OK); Thu, 13 Mar 2003 15:54:48 -0500 (EST) (envelope-from wollman@khavrinen.lcs.mit.edu) Received: (from wollman@localhost) by khavrinen.lcs.mit.edu (8.12.8/8.12.8/Submit) id h2DKsX17079931; Thu, 13 Mar 2003 15:54:33 -0500 (EST) (envelope-from wollman) Date: Thu, 13 Mar 2003 15:54:33 -0500 (EST) From: Garrett Wollman Message-Id: <200303132054.h2DKsX17079931@khavrinen.lcs.mit.edu> To: acpi-jp@jp.freebsd.org, current@FreeBSD.org Subject: Bad ACPI timer causes uninterruptible hang on boot? Sender: owner-freebsd-current@FreeBSD.ORG Precedence: bulk List-ID: List-Archive: (Web Archive) List-Help: (List Instructions) List-Subscribe: List-Unsubscribe: X-Loop: FreeBSD.ORG I've been trying to figure out why my Intel SR2100 servers would not boot with ACPI enabled, hanging uninterruptibly after probing the ACPI timer. I experimented with disabling various subsystems, and came up with the following results: - With `pci_link' disabled, the boot gets as far as ``Device configuration complete'' before hanging. - With `timer' disabled, the boot gets all the way through. - With `bus', `children', or `all' disabled, the boot fails at mountroot, having failed to probe any devices. A verbose boot of the kernel with `timer' disabled shows the following. Note also that some of the thermal parameters are given as -1, which seems to confuse the thermal module. -GAWollman Copyright (c) 1992-2003 The FreeBSD Project. Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 The Regents of the University of California. All rights reserved. FreeBSD 5.0-CURRENT #0: Thu Mar 13 12:58:29 EST 2003 wollman@grapevine.lcs.mit.edu:/usr/src/sys/i386/compile/GRAPEVINE Preloaded elf kernel "/boot/kernel/kernel" at 0xc051f000. Preloaded elf module "/boot/kernel/acpi.ko" at 0xc051f0b4. Calibrating clock(s) ... i8254 clock: 1193098 Hz CLK_USE_I8254_CALIBRATION not specified - using default frequency Timecounter "i8254" frequency 1193182 Hz Calibrating TSC clock ... TSC clock: 999722211 Hz Timecounter "TSC" frequency 999722211 Hz CPU: Intel Pentium III (999.72-MHz 686-class CPU) Origin = "GenuineIntel" Id = 0x68a Stepping = 10 Features=0x383fbff real memory = 1073676288 (1023 MB) Physical memory chunk(s): 0x00001000 - 0x0009efff, 647168 bytes (158 pages) 0x00546000 - 0x3ffe7fff, 1068113920 bytes (260770 pages) avail memory = 1037578240 (989 MB) bios32: Found BIOS32 Service Directory header at 0xc00f6990 bios32: Entry = 0xfd85e (c00fd85e) Rev = 0 Len = 1 pcibios: PCI BIOS entry at 0xfd7c0+0x397 pnpbios: Found PnP BIOS data at 0xc00f69c0 pnpbios: Entry = f0000:a934 Rev = 1.0 Other BIOS signatures found: Allocating major#253 to "net" null: Allocating major#252 to "pci" mem: Pentium Pro MTRR support enabled random: npx0: on motherboard npx0: INT 16 interface acpi0: on motherboard ACPI-0625: *** Info: GPE Block0 defined as GPE0 to GPE31 pci_open(1): mode 1 addr port (0x0cf8) is 0x80000070 pci_open(1a): mode1res=0x80000000 (0x80000000) pci_cfgcheck: device 0 [class=060000] [hdr=80] is there (id=00091166) pcibios: BIOS version 2.10 acpi0: power button is handled as a fixed feature programming model. acpi_cpu0: on acpi0 acpi_cpu1: on acpi0 acpi_tz0: on acpi0 acpi_tz0: _CRT value is absurd, ignored (-217.-7C) acpi_tz0: _ACx value is absurd, ignored (-247.-7C) acpi_tz1: on acpi0 acpi_tz1: _CRT value is absurd, ignored (-217.-7C) acpi_tz1: _ACx value is absurd, ignored (-247.-7C) acpi_tz2: on acpi0 acpi_tz2: _CRT value is absurd, ignored (-217.-7C) acpi_tz2: _ACx value is absurd, ignored (-247.-7C) acpi_button0: on acpi0 pcib0: port 0xcf8-0xcff on acpi0 ---- initial configuration ------------------------ \\_SB_.IR1_ irq 0: [ 5 10] low,level,sharable 0.15.0 \\_SB_.LN12 irq 10: [ 5 10] low,level,sharable 0.3.0 \\_SB_.LN13 irq 11: [ 5 10] low,level,sharable 0.2.0 \\_SB_.LN1A irq 5: [ 5 10] low,level,sharable 0.6.0 \\_SB_.LN1B irq 0: [ 5 10] low,level,sharable 0.6.1 \\_SB_.LN1C irq 0: [ 5 10] low,level,sharable 0.6.2 \\_SB_.LN1D irq 0: [ 5 10] low,level,sharable 0.6.3 \\_SB_.LN17 irq 0: [ 5 10] low,level,sharable 0.7.0 \\_SB_.LN1B irq 0: [ 5 10] low,level,sharable 0.7.1 \\_SB_.LN1C irq 0: [ 5 10] low,level,sharable 0.7.2 \\_SB_.LN1D irq 0: [ 5 10] low,level,sharable 0.7.3 \\_SB_.LN18 irq 0: [ 5 10] low,level,sharable 0.8.0 \\_SB_.LN1D irq 0: [ 5 10] low,level,sharable 0.8.1 \\_SB_.LN1B irq 0: [ 5 10] low,level,sharable 0.8.2 \\_SB_.LN1C irq 0: [ 5 10] low,level,sharable 0.8.3 \\_SB_.LN19 irq 0: [ 5 10] low,level,sharable 0.9.0 \\_SB_.LN1C irq 0: [ 5 10] low,level,sharable 0.9.1 \\_SB_.LN1D irq 0: [ 5 10] low,level,sharable 0.9.2 \\_SB_.LN1B irq 0: [ 5 10] low,level,sharable 0.9.3 ---- before setting priority for links ------------ \\_SB_.IR1_: interrupts: 5 10 penalty: 1860 1860 references: 1 priority: 0 \\_SB_.LN1B: interrupts: 5 10 penalty: 1860 1860 references: 4 priority: 0 \\_SB_.LN1C: interrupts: 5 10 penalty: 1860 1860 references: 4 priority: 0 \\_SB_.LN1D: interrupts: 5 10 penalty: 1860 1860 references: 4 priority: 0 \\_SB_.LN17: interrupts: 5 10 penalty: 1860 1860 references: 1 priority: 0 \\_SB_.LN18: interrupts: 5 10 penalty: 1860 1860 references: 1 priority: 0 \\_SB_.LN19: interrupts: 5 10 penalty: 1860 1860 references: 1 priority: 0 ---- before fixup boot-disabled links ------------- \\_SB_.LN1B: interrupts: 5 10 penalty: 1860 1860 references: 4 priority: 7440 \\_SB_.LN1C: interrupts: 5 10 penalty: 1860 1860 references: 4 priority: 7440 \\_SB_.LN1D: interrupts: 5 10 penalty: 1860 1860 references: 4 priority: 7440 \\_SB_.IR1_: interrupts: 5 10 penalty: 1860 1860 references: 1 priority: 1860 \\_SB_.LN17: interrupts: 5 10 penalty: 1860 1860 references: 1 priority: 1860 \\_SB_.LN18: interrupts: 5 10 penalty: 1860 1860 references: 1 priority: 1860 \\_SB_.LN19: interrupts: 5 10 penalty: 1860 1860 references: 1 priority: 1860 ---- after fixup boot-disabled links -------------- ---- arbitrated configuration --------------------- \\_SB_.IR1_ irq 5: [ 5 10] low,level,sharable 0.15.0 \\_SB_.LN12 irq 10: [ 5 10] low,level,sharable 0.3.0 \\_SB_.LN13 irq 11: [ 5 10] low,level,sharable 0.2.0 \\_SB_.LN1A irq 5: [ 5 10] low,level,sharable 0.6.0 \\_SB_.LN1B irq 10: [ 5 10] low,level,sharable 0.6.1 \\_SB_.LN1C irq 5: [ 5 10] low,level,sharable 0.6.2 \\_SB_.LN1D irq 10: [ 5 10] low,level,sharable 0.6.3 \\_SB_.LN17 irq 5: [ 5 10] low,level,sharable 0.7.0 \\_SB_.LN1B irq 10: [ 5 10] low,level,sharable 0.7.1 \\_SB_.LN1C irq 5: [ 5 10] low,level,sharable 0.7.2 \\_SB_.LN1D irq 10: [ 5 10] low,level,sharable 0.7.3 \\_SB_.LN18 irq 5: [ 5 10] low,level,sharable 0.8.0 \\_SB_.LN1D irq 10: [ 5 10] low,level,sharable 0.8.1 \\_SB_.LN1B irq 10: [ 5 10] low,level,sharable 0.8.2 \\_SB_.LN1C irq 5: [ 5 10] low,level,sharable 0.8.3 \\_SB_.LN19 irq 5: [ 5 10] low,level,sharable 0.9.0 \\_SB_.LN1C irq 5: [ 5 10] low,level,sharable 0.9.1 \\_SB_.LN1D irq 10: [ 5 10] low,level,sharable 0.9.2 \\_SB_.LN1B irq 10: [ 5 10] low,level,sharable 0.9.3 pci0: on pcib0 pci0: physical bus=0 found-> vendor=0x1166, dev=0x0009, revid=0x06 bus=0, slot=0, func=0 class=06-00-00, hdrtype=0x00, mfdev=1 cmdreg=0x0107, statreg=0x2200, cachelnsz=8 (dwords) lattimer=0x40 (1920 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) found-> vendor=0x1166, dev=0x0009, revid=0x06 bus=0, slot=0, func=1 class=06-00-00, hdrtype=0x00, mfdev=1 cmdreg=0x0107, statreg=0x0200, cachelnsz=8 (dwords) lattimer=0x40 (1920 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) map[10]: type 3, range 32, base fa000000, size 24, enabled map[14]: type 4, range 32, base 00001000, size 8, enabled map[18]: type 1, range 32, base f9000000, size 12, enabled found-> vendor=0x1002, dev=0x4756, revid=0x7a bus=0, slot=2, func=0 class=03-00-00, hdrtype=0x00, mfdev=0 cmdreg=0x0087, statreg=0x0290, cachelnsz=8 (dwords) lattimer=0x42 (1980 ns), mingnt=0x08 (2000 ns), maxlat=0x00 (0 ns) intpin=a, irq=11 powerspec 1 supports D0 D3 current D0 map[10]: type 1, range 32, base f9001000, size 12, enabled map[14]: type 4, range 32, base 00001400, size 6, enabled map[18]: type 1, range 32, base f9100000, size 20, enabled found-> vendor=0x8086, dev=0x1229, revid=0x08 bus=0, slot=3, func=0 class=02-00-00, hdrtype=0x00, mfdev=0 cmdreg=0x0117, statreg=0x0290, cachelnsz=8 (dwords) lattimer=0x42 (1980 ns), mingnt=0x08 (2000 ns), maxlat=0x38 (14000 ns) intpin=a, irq=10 powerspec 2 supports D0 D1 D2 D3 current D0 map[10]: type 1, range 32, base f9004000, size 14, enabled found-> vendor=0x1385, dev=0x630a, revid=0x01 bus=0, slot=6, func=0 class=02-00-00, hdrtype=0x00, mfdev=0 cmdreg=0x0116, statreg=0x02a0, cachelnsz=8 (dwords) lattimer=0x40 (1920 ns), mingnt=0x40 (16000 ns), maxlat=0x00 (0 ns) intpin=a, irq=5 map[90]: type 4, range 32, base 00000580, size 4, enabled found-> vendor=0x1166, dev=0x0200, revid=0x50 bus=0, slot=15, func=0 class=06-01-00, hdrtype=0x00, mfdev=1 cmdreg=0x0107, statreg=0x0200, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) map[18]: type 4, range 32, base 00000170, size 3, enabled map[1c]: type 4, range 32, base 00000374, size 2, enabled map[20]: type 4, range 32, base 00001440, size 4, enabled found-> vendor=0x1166, dev=0x0211, revid=0x00 bus=0, slot=15, func=1 class=01-01-8e, hdrtype=0x00, mfdev=1 cmdreg=0x0105, statreg=0x0200, cachelnsz=0 (dwords) lattimer=0x40 (1920 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) pci0: at device 2.0 (no driver attached) fxp0: port 0x1400-0x143f mem 0xf9100000-0xf91fffff,0xf9001000-0xf9001fff irq 10 at device 3.0 on pci0 fxp0: using memory space register mapping fxp0: Ethernet address 00:d0:b7:b7:45:eb fxp0: PCI IDs: 8086 1229 8086 1229 0008 fxp0: Dynamic Standby mode is disabled inphy0: on miibus0 inphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto fxp0: bpf attached ti0: mem 0xf9004000-0xf9007fff irq 5 at device 6.0 on pci0 ti0: Ethernet address: 00:02:e3:00:4c:36 ti0: bpf attached isab0: port 0x580-0x58f at device 15.0 on pci0 isa0: on isab0 pci0: at device 15.1 (no driver attached) pcib1: on acpi0 ---- initial configuration ------------------------ \\_SB_.LN10 irq 9: [ 5 10] low,level,sharable 1.4.0 \\_SB_.LN11 irq 11: [ 5 10] low,level,sharable 1.4.1 \\_SB_.LN14 irq 9: [ 5 10] low,level,sharable 1.10.0 \\_SB_.LN1B irq 10: [ 5 10] low,level,sharable 1.10.1 \\_SB_.LN1C irq 5: [ 5 10] low,level,sharable 1.10.2 \\_SB_.LN1D irq 10: [ 5 10] low,level,sharable 1.10.3 \\_SB_.LN15 irq 0: [ 5 10] low,level,sharable 1.11.0 \\_SB_.LN1D irq 10: [ 5 10] low,level,sharable 1.11.1 \\_SB_.LN1B irq 10: [ 5 10] low,level,sharable 1.11.2 \\_SB_.LN1C irq 5: [ 5 10] low,level,sharable 1.11.3 ---- before setting priority for links ------------ \\_SB_.LN15: interrupts: 5 10 penalty: 2970 3170 references: 1 priority: 0 ---- before fixup boot-disabled links ------------- \\_SB_.LN15: interrupts: 5 10 penalty: 2970 3170 references: 1 priority: 3070 ---- after fixup boot-disabled links -------------- ---- arbitrated configuration --------------------- \\_SB_.LN10 irq 9: [ 5 10] low,level,sharable 1.4.0 \\_SB_.LN11 irq 11: [ 5 10] low,level,sharable 1.4.1 \\_SB_.LN14 irq 9: [ 5 10] low,level,sharable 1.10.0 \\_SB_.LN1B irq 10: [ 5 10] low,level,sharable 1.10.1 \\_SB_.LN1C irq 5: [ 5 10] low,level,sharable 1.10.2 \\_SB_.LN1D irq 10: [ 5 10] low,level,sharable 1.10.3 \\_SB_.LN15 irq 5: [ 5 10] low,level,sharable 1.11.0 \\_SB_.LN1D irq 10: [ 5 10] low,level,sharable 1.11.1 \\_SB_.LN1B irq 10: [ 5 10] low,level,sharable 1.11.2 \\_SB_.LN1C irq 5: [ 5 10] low,level,sharable 1.11.3 pci1: on pcib1 pci1: physical bus=1 map[10]: type 4, range 32, base 00001800, size 8, enabled map[14]: type 1, range 64, base fb000000, size 12, enabled found-> vendor=0x9005, dev=0x00cf, revid=0x01 bus=1, slot=4, func=0 class=01-00-00, hdrtype=0x00, mfdev=1 cmdreg=0x0117, statreg=0x02b0, cachelnsz=8 (dwords) lattimer=0x48 (2160 ns), mingnt=0x28 (10000 ns), maxlat=0x19 (6250 ns) intpin=a, irq=9 powerspec 2 supports D0 D3 current D0 map[10]: type 4, range 32, base 00002000, size 8, enabled map[14]: type 1, range 64, base fb001000, size 12, enabled found-> vendor=0x9005, dev=0x00cf, revid=0x01 bus=1, slot=4, func=1 class=01-00-00, hdrtype=0x00, mfdev=1 cmdreg=0x0117, statreg=0x02b0, cachelnsz=8 (dwords) lattimer=0x48 (2160 ns), mingnt=0x28 (10000 ns), maxlat=0x19 (6250 ns) intpin=b, irq=11 powerspec 2 supports D0 D3 current D0 map[10]: type 4, range 32, base 00002400, size 8, enabled map[14]: type 1, range 32, base fb002000, size 12, enabled found-> vendor=0x1077, dev=0x2200, revid=0x05 bus=1, slot=10, func=0 class=01-00-00, hdrtype=0x00, mfdev=0 cmdreg=0x0117, statreg=0x02b0, cachelnsz=8 (dwords) lattimer=0x40 (1920 ns), mingnt=0x40 (16000 ns), maxlat=0x00 (0 ns) intpin=a, irq=9 powerspec 1 supports D0 D3 current D0 ahc0: port 0x1800-0x18ff mem 0xfb000000-0xfb000fff irq 9 at device 4.0 on pci1 ahc0: Defaulting to MEMIO off ahc0: Reading SEEPROM...done. ahc0: Manual LVD Termination ahc0: BIOS eeprom is present ahc0: Secondary High byte termination Enabled ahc0: Secondary Low byte termination Enabled ahc0: Primary Low Byte termination Enabled ahc0: Primary High Byte termination Enabled ahc0: Downloading Sequencer Program... 430 instructions downloaded ahc0: Features 0x1fef6, Bugs 0x40, Flags 0x485540 aic7899: Ultra160 Wide Channel A, SCSI Id=7, 32/253 SCBs ahc1: port 0x2000-0x20ff mem 0xfb001000-0xfb001fff irq 11 at device 4.1 on pci1 ahc1: Defaulting to MEMIO off ahc1: Reading SEEPROM...done. ahc1: Manual LVD Termination ahc1: BIOS eeprom is present ahc1: Secondary High byte termination Enabled ahc1: Secondary Low byte termination Enabled ahc1: Primary Low Byte termination Enabled ahc1: Primary High Byte termination Enabled ahc1: Downloading Sequencer Program... 430 instructions downloaded ahc1: Features 0x1fef6, Bugs 0x40, Flags 0x405540 aic7899: Ultra160 Wide Channel B, SCSI Id=7, 32/253 SCBs Qlogic ISP Driver, FreeBSD Version 5.9, Core Version 2.7 isp0: port 0x2400-0x24ff mem 0xfb002000-0xfb002fff irq 9 at device 10.0 on pci1 isp0: using I/O space register mapping isp0: Board Type 2200, Chip Revision 0x5, loaded F/W Revision 2.2.3 isp0: Installed in 64-Bit PCI slot isp0: Last F/W revision was 0.28757.8224 isp0: 980 max I/O commands supported isp0: NVRAM Port WWN 0x210000e08b03c6e2 sio0: irq maps: 0x1 0x11 0x1 0x1 sio0 port 0x3f8-0x3ff irq 4 on acpi0 sio0: type 16550A sio1: irq maps: 0x1 0x9 0x1 0x1 sio1 port 0x2f8-0x2ff irq 3 on acpi0 sio1: type 16550A fdc0: port 0x3f7,0x3f0-0x3f5 irq 6 drq 2 on acpi0 fdc0: FIFO enabled, 8 bytes threshold fd0: <1440-KB 3.5" drive> on fdc0 drive 0 Allocating major#251 to "devstat" atkbdc0: port 0x64,0x60 irq 1 on acpi0 atkbd0: flags 0x1 irq 1 on atkbdc0 atkbd: the current kbd controller command byte 0047 atkbd: keyboard ID 0x41ab (2) kbd0: atkbd0, AT 101/102 (2), config:0x1, flags:0x3d0000 psm0: unable to allocate IRQ psmcpnp0 irq 12 on acpi0 psm0: current command byte:0047 psm0: irq 12 on atkbdc0 psm0: model IntelliMouse Explorer, device ID 4-00, 5 buttons psm0: config:00000000, flags:00000000, packet size:4 psm0: syncmask:08, syncbits:08 acpi_ec0: port 0xca7,0xca6 on acpi0 atkbdc: atkbdc0 already exists; skipping it fdc: fdc0 already exists; skipping it sio: sio0 already exists; skipping it sio: sio1 already exists; skipping it Trying Read_Port at 203 Trying Read_Port at 243 Trying Read_Port at 283 Trying Read_Port at 2c3 Trying Read_Port at 303 Trying Read_Port at 343 Trying Read_Port at 383 Trying Read_Port at 3c3 sc: sc0 already exists; skipping it vga: vga0 already exists; skipping it isa_probe_children: disabling PnP devices isa_probe_children: probing non-PnP devices orm0: