From owner-freebsd-current Wed May 15 10: 1:36 2002 Delivered-To: freebsd-current@freebsd.org Received: from bunrab.catwhisker.org (adsl-63-193-123-122.dsl.snfc21.pacbell.net [63.193.123.122]) by hub.freebsd.org (Postfix) with ESMTP id 1709437B40B for ; Wed, 15 May 2002 09:58:51 -0700 (PDT) Received: from bunrab.catwhisker.org (localhost [127.0.0.1]) by bunrab.catwhisker.org (8.12.3/8.12.3) with ESMTP id g4FGwovF087353 for ; Wed, 15 May 2002 09:58:50 -0700 (PDT) (envelope-from david@bunrab.catwhisker.org) Received: (from david@localhost) by bunrab.catwhisker.org (8.12.3/8.12.3/Submit) id g4FGwopm087352 for current@freebsd.org; Wed, 15 May 2002 09:58:50 -0700 (PDT) Date: Wed, 15 May 2002 09:58:50 -0700 (PDT) From: David Wolfskill Message-Id: <200205151658.g4FGwopm087352@bunrab.catwhisker.org> To: current@freebsd.org Subject: Turbulence in today's -CURRENT ride: loader hangs (vs. loads) 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 ... and then I got a panic. Hmmm.... More particulars: The events being described occur on my SMP "build machine" (the laptop is still working on building today's -CURRENT as I type). I've been tracking -CURRENT daily on this machine for some time; each day, I build the day's -CURRENT while running the most recent -CURRENT I already built (nearly always, yesterday's -CURRENT). Also, this machiine uses only a serial console; it has no monitor or keyboard. Anyway, after the build & install (& mergemaster) for today, I rebooted: syncing disks... 28 27 26 25 24 23 22 21 20 19 18 17 16 15 14 13 12 11 10 9 8 7 6 5 4 3 2 1 done Uptime: 3h12m34s Rebooting... cpu_reset called on cpu#1 cpu_reset: Stopping other CPUs cpu_reset: Restarting BSP cpu_reset_proxy: Stopped CPU 1 Console: serial port BIOS drive A: is disk0 BIOS drive C: is disk1 BIOS 639kB/523200kB available memory FreeBSD/i386 bootstrap loader, Revision 1.1 (root@freebeast.catwhisker.org, Wed May 15 07:33:39 PDT 2002) Loading /boot/defaults/loader.conf /boot/kernel/kernel text=0x217bc8 data=0x34d74+0x7078c / Hit [Enter] to boot immediately, or any other key for command prompt. Booting [/boot/kernel/kernel]... /boot/kernel/acpi.ko text=0x2b67c data=0x16e4+0x6e0 / which would be fairly normal, except that the "spinner" stopped spinning. Around that point, I got a little suspicous, and tried again -- this time, requesting a single-user boot. Same thing. So I tried again, and managed to interrupt the system before it loaded the loader: /boot/kernel/acpi.ko text=0x2b67c data=0x16e4+0x6e0 >> FreeBSD/i386 BOOT Default: 0:ad(0,a)/boot/loader boot: 0:ad(0,a)/boot/loader.old Console: serial port BIOS drive A: is disk0 BIOS drive C: is disk1 BIOS 639kB/523200kB available memory FreeBSD/i386 bootstrap loader, Revision 1.1 (root@freebeast.catwhisker.org, Tue May 14 06:52:45 PDT 2002) Loading /boot/defaults/loader.conf /boot/kernel/kernel text=0x217bc8 data=0x34d74+0x7078c syms=[0x4+0x35470+0x4+0x408ed] \ Hit [Enter] to boot immediately, or any other key for command prompt. Booting [/boot/kernel/kernel] in 9 seconds... Type '?' for a list of commands, 'help' for more detailed help. OK boot -s /boot/kernel/acpi.ko text=0x2b67c data=0x16e4+0x6e0 syms=[0x4+0x4f10+0x4+0x6728] SMAP type=01 base=00000000 00000000 len=00000000 0009fc00 SMAP type=01 base=00000000 0009fc00 len=00000000 00000400 SMAP type=02 base=00000000 000f0000 len=00000000 00010000 SMAP type=02 base=00000000 fec00000 len=00000000 01400000 SMAP type=01 base=00000000 00100000 len=00000000 1fef0000 SMAP type=03 base=00000000 1fff3000 len=00000000 0000d000 SMAP type=04 base=00000000 1fff0000 len=00000000 00003000 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 #2: Wed May 15 08:47:19 PDT 2002 root@freebeast.catwhisker.org:/common/S4/obj/usr/src/sys/FREEBEAST Preloaded elf kernel "/boot/kernel/kernel" at 0xc046f000. Preloaded elf module "/boot/kernel/acpi.ko" at 0xc046f0b4. Calibrating clock(s) ... TSC clock: 876475449 Hz, i8254 clock: 1193296 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 CPU: Pentium III/Pentium III Xeon/Celeron (876.40-MHz 686-class CPU) Origin = "GenuineIntel" Id = 0x68a Stepping = 10 Features=0x383fbff real memory = 536805376 (524224K bytes) Physical memory chunk(s): 0x00001000 - 0x0009efff, 647168 bytes (158 pages) 0x00499000 - 0x1ffe7fff, 531951616 bytes (129871 pages) avail memory = 517246976 (505124K bytes) Programming 24 pins in IOAPIC #0 IOAPIC #0 intpin 2 -> irq 0 SMP: CPU0 apic_initialize(): lint0: 0x00000700 lint1: 0x00010400 TPR: 0x00000010 SVR: 0x000001ff FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs cpu0 (BSP): apic id: 0, version: 0x00040011, at 0xfee00000 cpu1 (AP): apic id: 1, version: 0x00040011, at 0xfee00000 io0 (APIC): apic id: 2, version: 0x00178011, at 0xfec00000 bios32: Found BIOS32 Service Directory header at 0xc00faf20 bios32: Entry = 0xfb390 (c00fb390) Rev = 0 Len = 1 pcibios: PCI BIOS entry at 0xf0000+0xb3c0 pnpbios: Found PnP BIOS data at 0xc00fbde0 pnpbios: Entry = f0000:be10 Rev = 1.0 Other BIOS signatures found: null: random: mem: Pentium Pro MTRR support enabled SMP: CPU0 bsp_apic_configure(): lint0: 0x00010700 lint1: 0x00000400 TPR: 0x00000010 SVR: 0x000001ff pci_open(1): mode 1 addr port (0x0cf8) is 0x80000060 pci_open(1a): mode1res=0x80000000 (0x80000000) pci_cfgcheck: device 0 [class=060000] [hdr=00] is there (id=30911106) Using $PIR table, 8 entries at 0xc00fde30 npx0: on motherboard npx0: INT 16 interface acpi0: on motherboard acpi0: power button is handled as a fixed feature programming model. 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 0x4008-0x400b on acpi0 acpi_cpu0: on acpi0 acpi_cpu1: on acpi0 acpi_tz0: on acpi0 acpi_button0: on acpi0 acpi_pcib0: port 0x5000-0x500f,0x4080-0x40ff,0x4000-0x407f,0xcf8-0xcff on acpi0 pci0: physical bus=0 map[10]: type 3, range 32, base d9000000, size 23, enabled found-> vendor=0x1106, dev=0x3091, revid=0x01 bus=0, slot=0, func=0 class=06-00-00, hdrtype=0x00, mfdev=0 powerspec 2 supports D0 D3 current D0 found-> vendor=0x1106, dev=0xb091, revid=0x00 bus=0, slot=1, func=0 class=06-04-00, hdrtype=0x01, mfdev=0 IOAPIC #0 intpin 11 -> irq 2 Freeing (NOT implemented) redirected PCI irq 11. map[10]: type 4, range 32, base 0000c000, size 7, enabled map[14]: type 1, range 32, base d9800000, size 7, enabled found-> vendor=0x10ec, dev=0x8129, revid=0x00 bus=0, slot=9, func=0 class=02-00-00, hdrtype=0x00, mfdev=0 intpin=a, irq=2 IOAPIC #0 intpin 12 -> irq 5 Freeing (NOT implemented) redirected PCI irq 12. map[10]: type 4, range 32, base 0000c400, size 8, enabled found-> vendor=0x13f6, dev=0x0111, revid=0x10 bus=0, slot=14, func=0 class=04-01-00, hdrtype=0x00, mfdev=0 intpin=a, irq=5 powerspec 2 supports D0 D1 D2 D3 current D0 map[10]: type 4, range 32, base 0000c800, size 3, enabled map[14]: type 4, range 32, base 0000cc00, size 2, enabled map[18]: type 4, range 32, base 0000d000, size 3, enabled map[1c]: type 4, range 32, base 0000d400, size 2, enabled map[20]: type 4, range 32, base 0000d800, size 4, enabled found-> vendor=0x1095, dev=0x0649, revid=0x02 bus=0, slot=15, func=0 class=01-04-00, hdrtype=0x00, mfdev=0 intpin=a, irq=10 powerspec 2 supports D0 D1 D2 D3 current D0 found-> vendor=0x1106, dev=0x3074, revid=0x00 bus=0, slot=17, func=0 class=06-01-00, hdrtype=0x00, mfdev=1 powerspec 2 supports D0 D3 current D0 map[20]: type 4, range 32, base 0000dc00, size 4, enabled found-> vendor=0x1106, dev=0x0571, revid=0x06 bus=0, slot=17, func=1 class=01-01-8a, hdrtype=0x00, mfdev=0 powerspec 2 supports D0 D3 current D0 pci0: on acpi_pcib0 pcib1: at device 1.0 on pci0 pcib1: secondary bus 1 pcib1: subordinate bus 1 pcib1: I/O decode 0xf000-0xfff pcib1: memory decode 0xd0000000-0xd7ffffff pcib1: prefetched decode 0xfff00000-0xfffff pci1: physical bus=1 map[10]: type 1, range 32, base d0000000, size 26, enabled found-> vendor=0x5333, dev=0x8a13, revid=0x02 bus=1, slot=0, func=0 class=03-00-00, hdrtype=0x00, mfdev=0 powerspec 1 supports D0 D3 current D0 pci1: on pcib1 pci1: at device 0.0 (no driver attached) rl0: port 0xc000-0xc07f mem 0xd9800000-0xd980007f irq 2 at device 9.0 on pci0 lock order reversal 1st 0xd7c269a4 rl0 (network driver) @ /usr/src/sys/pci/if_rl.c:855 2nd 0xc032b2c0 allproc (allproc) @ /usr/src/sys/kern/kern_fork.c:309 rl0: Ethernet address: 00:80:c6:f9:08:58 miibus0: on rl0 rlphy0: on miibus0 rlphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto bpf: rl0 attached pci0: at device 14.0 (no driver attached) atapci0: port 0xd800-0xd80f,0xd400-0xd403,0xd000-0xd007,0xcc00-0xcc03,0xc800-0xc807 irq 10 at device 15.0 on pci0 ata2: iobase=0xc800 altiobase=0xcc02 bmaddr=0xd800 ata2: mask=03 ostat0=7f ostat2=7f ata2-master: ATAPI 7f 7f ata2-slave: ATAPI 7f 7f ata2: mask=03 stat0=7f stat1=7f ata2-master: ATA 7f 7f ata2-slave: ATA 7f 7f ata2: devices=00 ata2: at 0xc800 on atapci0 ata3: iobase=0xd000 altiobase=0xd402 bmaddr=0xd808 ata3: mask=03 ostat0=7f ostat2=7f ata3-master: ATAPI 7f 7f ata3-slave: ATAPI 7f 7f ata3: mask=03 stat0=7f stat1=7f ata3-master: ATA 7f 7f ata3-slave: ATA 7f 7f ata3: devices=00 ata3: at 0xd000 on atapci0 isab0: at device 17.0 on pci0 isa0: on isab0 atapci1: port 0xdc00-0xdc0f at device 17.1 on pci0 ata0: iobase=0x01f0 altiobase=0x03f6 bmaddr=0xdc00 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 atapci1 ata1: iobase=0x0170 altiobase=0x0376 bmaddr=0xdc08 ata1: mask=03 ostat0=50 ostat2=01 ata1-master: ATAPI 14 eb ata1-slave: ATAPI 00 00 ata1: mask=03 stat0=00 stat1=00 ata1-slave: ATA 04 a5 ata1: devices=06 ata1: at 0x170 irq 15 on atapci1 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 sio0: irq maps: 0x41 0x51 0x41 0x41 sio0 port 0x3f8-0x3ff irq 4 on acpi0 sio0: type 16550A, console sio1: irq maps: 0x41 0x49 0x41 0x41 sio1 port 0x2f8-0x2ff irq 3 on acpi0 sio1: type 16550A unknown: not probed (disabled) ppc0: using extended I/O port range ppc0: SPP ppc0 port 0xf78-0xf7b,0xb78-0xb7b,0x778-0x77b,0x378-0x37f irq 7 on acpi0 ppc0: Generic chipset (NIBBLE-only) in COMPATIBLE mode plip0: on ppbus0 bpf: lp0 attached lpt0: on ppbus0 lpt0: Interrupt-driven port ppi0: on ppbus0 unknown: not probed (disabled) unknown: not probed (disabled) unknown: not probed (disabled) unknown: not probed (disabled) unknown: not probed (disabled) unknown: not probed (disabled) unknown: not probed (disabled) unknown: not probed (disabled) unknown: not probed (disabled) unknown: not probed (disabled) unknown: not probed (disabled) ata: ata0 already exists; skipping it ata: ata1 already exists; skipping it fdc: fdc0 already exists; skipping it ppc: ppc0 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: