From owner-freebsd-current Thu Oct 31 13:15:46 2002 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 9EB0B37B404 for ; Thu, 31 Oct 2002 13:15:32 -0800 (PST) Received: from ns.altadena.net (ns.altadena.net [206.126.144.2]) by mx1.FreeBSD.org (Postfix) with ESMTP id 3A5B843E8A for ; Thu, 31 Oct 2002 13:15:31 -0800 (PST) (envelope-from pete@ns.altadena.net) Received: from ns.altadena.net (localhost [127.0.0.1]) by ns.altadena.net (8.12.6/8.12.3) with ESMTP id g9VLFKM8038453 for ; Thu, 31 Oct 2002 13:15:20 -0800 (PST) (envelope-from pete@ns.altadena.net) Received: (from pete@localhost) by ns.altadena.net (8.12.6/8.12.3/Submit) id g9VLFKkK038452 for current@freebsd.org; Thu, 31 Oct 2002 13:15:20 -0800 (PST) (envelope-from pete) From: Pete Carah Message-Id: <200210312115.g9VLFKkK038452@ns.altadena.net> Subject: Boot-time hang after init, still (VAIO R505ES again) To: current@freebsd.org Date: Thu, 31 Oct 2002 13:15:20 -0800 (PST) X-Mailer: ELM [version 2.4ME+ PL68 (25)] MIME-Version: 1.0 Content-Type: text/plain; charset=iso8859-1 Content-Transfer-Encoding: 7bit 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 CVSUP as of last night about 10PM PST: Well, phk's stuff now appears to work right (even finds the memory stick most of the time) and the bad malloc in acpi is fixed. This seems to leave irq issues (still!!) PSM0 still can't allocate irq even though 12 isn't used anywhere else. on the init hang, 3 processes are normally in RUNQ (one more on this boot, but I've never seen this before); irq9, pagezero (I presume this is the idle loop so should be in run) and init. If I can get some pointers on what further to do with kdb, I'd be glad to do so. It somehow looks like irq9 process is never finishing; with 4 or 5 devices hung off one irq does the dispatch software work right? (and why do irq 10 and 11 not get used?; I thought routed interrupts weren't stuck with the 16-irq 2x8259 limitation either (I know apic ones aren't).) -- Pete ---------------------------------------------------------------------------- SMAP type=01 base=00000000 00000000 len=00000000 0009e000 SMAP type=02 base=00000000 0009e000 len=00000000 00002000 SMAP type=02 base=00000000 000d8000 len=00000000 00008000 SMAP type=02 base=00000000 000e4000 len=00000000 0001c000 SMAP type=01 base=00000000 00100000 len=00000000 1fbf0000 SMAP type=03 base=00000000 1fcf0000 len=00000000 0000c000 SMAP type=04 base=00000000 1fcfc000 len=00000000 00004000 SMAP type=01 base=00000000 1fd00000 len=00000000 00180000 SMAP type=02 base=00000000 1fe80000 len=00000000 00100000 SMAP type=02 base=00000000 1ff80000 len=00000000 00080000 SMAP type=02 base=00000000 ff800000 len=00000000 00400000 SMAP type=02 base=00000000 fffffc00 len=00000000 00000400 Copyright (c) 1992-2002 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 #19: Thu Oct 31 08:08:53 PST 2002 pete@port2.altadena.net:/d/obj-s/current/usr/src/sys/PORT2 Preloaded elf kernel "/boot/kernel/kernel" at 0xc04e7000. Preloaded elf module "/boot/kernel/firewire.ko" at 0xc04e70a8. Preloaded elf module "/boot/kernel/acpi.ko" at 0xc04e7158. Calibrating clock(s) ... TSC clock: 795410300 Hz, i8254 clock: 1193194 Hz CLK_USE_I8254_CALIBRATION not specified - using default frequency Timecounter "i8254" frequency 1193182 Hz CLK_USE_TSC_CALIBRATION not specified - using old calibration method Timecounter "TSC" frequency 795407830 Hz CPU: Pentium III/Pentium III Xeon/Celeron (795.41-MHz 686-class CPU) Origin = "GenuineIntel" Id = 0x6b1 Stepping = 1 Features=0x383f9ff real memory = 535298048 (522752K bytes) Physical memory chunk(s): 0x00001000 - 0x0009dfff, 643072 bytes (157 pages) 0x0050e000 - 0x1fceffff, 528359424 bytes (128994 pages) 0x1fd00000 - 0x1fe77fff, 1540096 bytes (376 pages) avail memory = 513654784 (501616K bytes) bios32: Found BIOS32 Service Directory header at 0xc00f6ba0 bios32: Entry = 0xfd871 (c00fd871) Rev = 0 Len = 1 pcibios: PCI BIOS entry at 0xfd870+0x13a pnpbios: Found PnP BIOS data at 0xc00f6bd0 pnpbios: Entry = f0000:880c Rev = 1.0 Other BIOS signatures found: Initializing GEOMetry subsystem null: mem: Pentium Pro MTRR support enabled random: npx0: on motherboard npx0: INT 16 interface acpi0: on motherboard pci_open(1): mode 1 addr port (0x0cf8) is 0x8000f904 pci_open(1a): mode1res=0x80000000 (0x80000000) pci_cfgcheck: device 0 [class=060000] [hdr=00] is there (id=35758086) Using $PIR table, 9 entries at 0xc00fdf30 PCI-Only Interrupts: none Location Bus Device Pin Link IRQs embedded 2 5 A 0x69 3 embedded 2 8 A 0x68 9 embedded 0 29 A 0x60 9 embedded 0 29 B 0x63 9 embedded 0 2 A 0x60 9 embedded 0 1 A 0x60 9 ACPI timer looks GOOD min = 2, max = 3, width = 2 ACPI timer looks GOOD min = 2, max = 3, width = 2 ACPI timer looks GOOD min = 2, max = 3, width = 2 ACPI timer looks GOOD min = 2, max = 3, width = 2 ACPI timer looks GOOD min = 2, max = 3, width = 2 ACPI timer looks GOOD min = 2, max = 3, width = 2 ACPI timer looks GOOD min = 2, max = 3, width = 2 ACPI timer looks GOOD min = 2, max = 3, width = 2 ACPI timer looks GOOD min = 2, max = 3, width = 2 ACPI timer looks GOOD min = 2, max = 3, width = 2 Timecounter "ACPI-fast" frequency 3579545 Hz acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1008-0x100b on acpi0 acpi_cpu0: on acpi0 acpi_tz0: on acpi0 acpi_lid0: on acpi0 acpi_button0: on acpi0 pcib0: port 0xcf8-0xcff on acpi0 ---- initial configuration ------------------------ \_SB_.LNKA irq 9: [ 9] low,level,sharable 0.1.0 \_SB_.LNKD irq 9: [ 9] low,level,sharable 0.1.3 \_SB_.LNKA irq 9: [ 9] low,level,sharable 0.2.0 \_SB_.LNKA irq 9: [ 9] low,level,sharable 0.29.0 \_SB_.LNKD irq 9: [ 9] low,level,sharable 0.29.1 ---- before setting priority for links ------------ ---- before fixup boot-disabled links ------------- ---- after fixup boot-disabled links -------------- ---- arbitrated configuration --------------------- \_SB_.LNKA irq 9: [ 9] low,level,sharable 0.1.0 \_SB_.LNKD irq 9: [ 9] low,level,sharable 0.1.3 \_SB_.LNKA irq 9: [ 9] low,level,sharable 0.2.0 \_SB_.LNKA irq 9: [ 9] low,level,sharable 0.29.0 \_SB_.LNKD irq 9: [ 9] low,level,sharable 0.29.1 pci0: on pcib0 pci0: physical bus=0 map[10]: type 3, range 32, base 00000000, size 0, enabled found-> vendor=0x8086, dev=0x3575, revid=0x04 bus=0, slot=0, func=0 class=06-00-00, hdrtype=0x00, mfdev=0 cmdreg=0x0106, statreg=0x2010, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) map[10]: type 3, range 32, base e8000000, size 27, enabled map[14]: type 1, range 32, base e0000000, size 19, enabled found-> vendor=0x8086, dev=0x3577, revid=0x04 bus=0, slot=2, func=0 class=03-00-00, hdrtype=0x00, mfdev=1 cmdreg=0x0007, statreg=0x0090, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=a, irq=9 powerspec 1 supports D0 D1 D3 current D0 map[10]: type 3, range 32, base f0000000, size 27, enabled map[14]: type 1, range 32, base e0080000, size 19, enabled found-> vendor=0x8086, dev=0x3577, revid=0x00 bus=0, slot=2, func=1 class=03-80-00, hdrtype=0x00, mfdev=1 cmdreg=0x0007, statreg=0x0090, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x09 (2250 ns) powerspec 1 supports D0 D1 D3 current D0 map[20]: type 4, range 32, base 00001800, size 5, enabled found-> vendor=0x8086, dev=0x2482, revid=0x02 bus=0, slot=29, func=0 class=0c-03-00, hdrtype=0x00, mfdev=1 cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=a, irq=9 map[20]: type 4, range 32, base 00001820, size 5, enabled found-> vendor=0x8086, dev=0x2484, revid=0x02 bus=0, slot=29, func=1 class=0c-03-00, hdrtype=0x00, mfdev=0 cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=b, irq=9 map[20]: type 4, range 32, base 00001840, size 5, enabled found-> vendor=0x8086, dev=0x2487, revid=0x02 bus=0, slot=29, func=2 class=0c-03-00, hdrtype=0x00, mfdev=0 cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=c, irq=255 found-> vendor=0x8086, dev=0x2448, revid=0x42 bus=0, slot=30, func=0 class=06-04-00, hdrtype=0x01, mfdev=0 cmdreg=0x0007, statreg=0x0080, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x04 (1000 ns), maxlat=0x00 (0 ns) found-> vendor=0x8086, dev=0x248c, revid=0x02 bus=0, slot=31, func=0 class=06-01-00, hdrtype=0x00, mfdev=1 cmdreg=0x000f, statreg=0x0280, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) map[10]: type 4, range 32, base 000001f0, size 3, enabled map[14]: type 4, range 32, base 000003f4, size 2, enabled 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 00001860, size 4, enabled map[24]: type 1, range 32, base e0100000, size 10, memory disabled found-> vendor=0x8086, dev=0x248a, revid=0x02 bus=0, slot=31, func=1 class=01-01-8a, hdrtype=0x00, mfdev=0 cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=a, irq=255 map[20]: type 4, range 32, base 00001880, size 5, enabled found-> vendor=0x8086, dev=0x2483, revid=0x02 bus=0, slot=31, func=3 class=0c-05-00, hdrtype=0x00, mfdev=0 cmdreg=0x0001, statreg=0x0280, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=b, irq=255 map[10]: type 4, range 32, base 00001c00, size 8, port disabled map[14]: type 4, range 32, base 000018c0, size 6, enabled found-> vendor=0x8086, dev=0x2485, revid=0x02 bus=0, slot=31, func=5 class=04-01-00, hdrtype=0x00, mfdev=0 cmdreg=0x0000, statreg=0x0280, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=b, irq=9 map[10]: type 4, range 32, base 00002400, size 8, port disabled map[14]: type 4, range 32, base 00002000, size 7, enabled found-> vendor=0x8086, dev=0x2486, revid=0x02 bus=0, slot=31, func=6 class=07-03-00, hdrtype=0x00, mfdev=0 cmdreg=0x0000, statreg=0x0280, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=b, irq=255 agp0: mem 0xe0000000-0xe007ffff,0xe8000000-0xefffffff irq 9 at device 2.0 on pci0 agp0: detected 892k stolen memory agp0: aperture size is 128M pci0: at device 2.1 (no driver attached) uhci0: port 0x1800-0x181f irq 9 at device 29.0 on pci0 usb0: on uhci0 usb0: USB revision 1.0 uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 uhub0: 2 ports with 2 removable, self powered uhci1: port 0x1820-0x183f irq 9 at device 29.1 on pci0 usb1: on uhci1 usb1: USB revision 1.0 uhub1: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 uhub1: 2 ports with 2 removable, self powered uhci2: port 0x1840-0x185f at device 29.2 on pci0 pcib0: matched entry for 0.29.INTC (source \_SB_.LNKC) pcib0: possible interrupts: 9 pcib0: slot 29 INTC routed to irq 9 via \_SB_.LNKC usb2: on uhci2 usb2: USB revision 1.0 uhub2: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 uhub2: 2 ports with 2 removable, self powered umass0: Sony USB Memory Stick Slot, rev 1.10/1.09, addr 2 umass0:0:0:-1: Attached to scbus0 as device 0 pcib1: at device 30.0 on pci0 pcib1: secondary bus 2 pcib1: subordinate bus 2 pcib1: I/O decode 0x3000-0x3fff pcib1: memory decode 0xe0200000-0xe02fffff pcib1: prefetched decode 0xfff00000-0xfffff ---- initial configuration ------------------------ \_SB_.LNKC irq 9: [ 9] low,level,sharable 2.2.0 \_SB_.LNKE irq 9: [ 9] low,level,sharable 2.8.0 \_SB_.LNKD irq 9: [ 9] low,level,sharable 2.11.0 \_SB_.LNKA irq 9: [ 9] low,level,sharable 2.11.1 ---- before setting priority for links ------------ ---- before fixup boot-disabled links ------------- ---- after fixup boot-disabled links -------------- ---- arbitrated configuration --------------------- \_SB_.LNKC irq 9: [ 9] low,level,sharable 2.2.0 \_SB_.LNKE irq 9: [ 9] low,level,sharable 2.8.0 \_SB_.LNKD irq 9: [ 9] low,level,sharable 2.11.0 \_SB_.LNKA irq 9: [ 9] low,level,sharable 2.11.1 pci2: on pcib1 pci2: physical bus=2 map[10]: type 1, range 32, base e0205000, size 11, enabled map[14]: type 1, range 32, base e0200000, size 14, enabled found-> vendor=0x104c, dev=0x8023, revid=0x00 bus=2, slot=2, func=0 class=0c-00-10, hdrtype=0x00, mfdev=0 cmdreg=0x0006, statreg=0x0210, cachelnsz=8 (dwords) lattimer=0x40 (1920 ns), mingnt=0x03 (750 ns), maxlat=0x04 (1000 ns) intpin=a, irq=255 powerspec 2 supports D0 D1 D2 D3 current D0 found-> vendor=0x1180, dev=0x0475, revid=0x80 bus=2, slot=5, func=0 class=06-07-00, hdrtype=0x02, mfdev=0 cmdreg=0x0007, statreg=0x0210, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=a, irq=3 powerspec 2 supports D0 D1 D2 D3 current D0 map[10]: type 1, range 32, base e0204000, size 12, enabled map[14]: type 4, range 32, base 00003000, size 6, enabled found-> vendor=0x8086, dev=0x1031, revid=0x42 bus=2, slot=8, func=0 class=02-00-00, hdrtype=0x00, mfdev=0 cmdreg=0x0007, statreg=0x0290, cachelnsz=8 (dwords) lattimer=0x42 (1980 ns), mingnt=0x08 (2000 ns), maxlat=0x38 (14000 ns) intpin=a, irq=9 powerspec 2 supports D0 D1 D2 D3 current D0 found-> vendor=0x104c, dev=0xac50, revid=0x01 bus=2, slot=11, func=0 class=06-07-00, hdrtype=0x02, mfdev=0 cmdreg=0x0000, statreg=0x0210, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x40 (16000 ns), maxlat=0x03 (750 ns) intpin=a, irq=255 powerspec 1 supports D0 D1 D2 D3 current D0 XXXfw: vendid=104c, dev=8023 fwohci0: <1394 Open Host Controller Interface> mem 0xe0200000-0xe0203fff,0xe0205000-0xe02057ff at device 2.0 on pci2 fwohci0: Invalid irq 255 fwohci0: Please switch PNP-OS to 'No' in BIOS device_probe_and_attach: fwohci0 attach returned 6 cbb0: irq 3 at device 5.0 on pci2 pcib1: device cbb0 requested decoded memory range 0xe0200000-0xe02fffff cardbus0: on cbb0 pccard0: <16-bit PCCard bus> on cbb0 fxp0: port 0x3000-0x303f mem 0xe0204000-0xe0204fff irq 9 at device 8.0 on pci2 pcib1: device fxp0 requested decoded memory range 0xe0204000-0xe0204fff fxp0: using memory space register mapping fxp0: Ethernet address 08:00:46:4e:96:17 fxp0: PCI IDs: 8086 1031 104d 8100 0042 fxp0: Dynamic Standby mode is disabled inphy0: on miibus0 inphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto bpf: fxp0 attached cbb1: at device 11.0 on pci2 pcib1: device cbb1 requested decoded memory range 0xe0200000-0xe02fffff cardbus1: on cbb1 pccard1: <16-bit PCCard bus> on cbb1 pcib1: matched entry for 2.11.INTA (source \_SB_.LNKD) pcib1: slot 11 INTA is routed to irq 9 isab0: at device 31.0 on pci0 isa0: on isab0 atapci0: port 0x1860-0x186f,0x374-0x377,0x170-0x177,0x3f4-0x3f7,0x1f0-0x1f7 mem 0xe0100000-0xe01003ff at device 31.1 on pci0 ata0: iobase=0x01f0 altiobase=0x03f6 bmaddr=0x1860 ata0: mask=03 ostat0=50 ostat2=00 ata0-master: ATAPI 00 00 ata0-slave: ATAPI 00 00 ata0: mask=03 stat0=50 stat1=00 ata0-master: ATA 01 a5 ata0: devices=01 ata0: at 0x1f0 irq 14 on atapci0 ata1: iobase=0x0170 altiobase=0x0376 bmaddr=0x1868 ata1: mask=03 ostat0=00 ostat2=00 ata1-master: ATAPI 00 00 ata1-slave: ATAPI 00 00 ata1: mask=03 stat0=00 stat1=00 ata1: devices=00 ata1: at 0x170 irq 15 on atapci0 pci0: at device 31.3 (no driver attached) pci0: at device 31.5 (no driver attached) pci0: at device 31.6 (no driver attached) 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) kbdc: RESET_KBD return code:00fa kbdc: RESET_KBD status:00aa kbd0 at atkbd0 kbd0: atkbd0, AT 101/102 (2), config:0x1, flags:0x1d0000 psm0: unable to allocate IRQ acpi_ec0: port 0x66,0x62 on acpi0 sio0: irq maps: 0x201 0x211 0x201 0x201 sio0 port 0x3f8-0x3ff irq 4 on acpi0 sio0: type 16550A, console unknown: not probed (disabled) unknown: not probed (disabled) ppc0: using extended I/O port range ppc0: ECP SPP ECP+EPP SPP ppc0 port 0x778-0x77f,0x378-0x37f irq 7 drq 3 on acpi0 ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode ppc0: FIFO with 16/16/15 bytes threshold plip0: on ppbus0 bpf: lp0 attached lpt0: on ppbus0 lpt0: Interrupt-driven port ppi0: on ppbus0 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 acpi_cmbat0: on acpi0 acpi_acad0: on acpi0 unknown: not probed (disabled) unknown: not probed (disabled) ata: ata0 already exists; skipping it ata: ata1 already exists; skipping it atkbdc: atkbdc0 already exists; skipping it fdc: fdc0 already exists; skipping it ppc: ppc0 already exists; skipping it sio: sio0 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: