Date: Mon, 5 Jul 1999 17:54:12 +0100 (IST) From: Alan.Judge@indigo.ie To: FreeBSD-gnats-submit@freebsd.org Cc: Alan.Judge@indigo.ie Subject: kern/12521: repeatable SMP hang running lmbench in 3.2-STABLE Message-ID: <199907051654.RAA23115@relay05.indigo.ie>
next in thread | raw e-mail | index | archive | help
>Number: 12521
>Category: kern
>Synopsis: lmbench-1.1's context switching test hangs SMP
>Confidential: no
>Severity: critical
>Priority: medium
>Responsible: freebsd-bugs
>State: open
>Quarter:
>Keywords:
>Date-Required:
>Class: sw-bug
>Submitter-Id: current-users
>Arrival-Date: Mon Jul 5 10:00:01 PDT 1999
>Closed-Date:
>Last-Modified:
>Originator: Alan Judge
>Release: FreeBSD 3.2-STABLE i386
>Organization:
Indigo
>Environment:
Running 3.2-STABLE (updated 2nd July) on a new Dell 4350 test box.
(Dual processor 500Mhz P-III, 1GB RAM.)
>Description:
While testing and benchmarking, discovered that lmbench-1.1
repeatably hangs an SMP kernel. Symptoms are that all user processes
freeze. System still reponds to TCP connects, but no response
after that. So, I'd guess it's stuck with interrupts masked.
Interestingly if I boot exactly the same kernel image on
a smaller Dell 2300 (Dual 400Mhz P-II, 256MB), I can't get it to crash,
so it might be speed, size, or processor related.
If I build a non-SMP kernel and run the same tests, all works fine.
dmesg:
Jul 2 14:38:28 relay05 /kernel: Copyright (c) 1992-1999 FreeBSD Inc.
Jul 2 14:38:28 relay05 /kernel: Copyright (c) 1982, 1986, 1989, 1991, 1993
Jul 2 14:38:28 relay05 /kernel: The Regents of the University of California. All rights reserved.
Jul 2 14:38:28 relay05 /kernel: FreeBSD 3.2-STABLE #0: Fri Jul 2 13:44:05 IST 1999
Jul 2 14:38:28 relay05 /kernel: judgea@quango.indigo.ie:/f2/FreeBSD/stable/src/sys/compile/RELAY05.SMP
Jul 2 14:38:28 relay05 /kernel: Calibrating clock(s) ... TSC clock: 497434859 Hz, i8254 clock: 1193186 Hz
Jul 2 14:38:28 relay05 /kernel: CLK_USE_I8254_CALIBRATION not specified - using default frequency
Jul 2 14:38:28 relay05 /kernel: Timecounter "i8254" frequency 1193182 Hz
Jul 2 14:38:28 relay05 /kernel: CLK_USE_TSC_CALIBRATION not specified - using old calibration method
Jul 2 14:38:28 relay05 /kernel: CPU: Pentium III (686-class CPU)
Jul 2 14:38:28 relay05 /kernel: Origin = "GenuineIntel" Id = 0x672 Stepping=2
Jul 2 14:38:28 relay05 /kernel: Features=0x383fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,<b24>,<b25>>
Jul 2 14:38:28 relay05 /kernel: real memory = 1073741824 (1048576K bytes)
Jul 2 14:38:28 relay05 /kernel: Physical memory chunk(s):
Jul 2 14:38:28 relay05 /kernel: 0x00001000 - 0x0009efff, 647168 bytes (158 pages)
Jul 2 14:38:28 relay05 /kernel: 0x00315000 - 0x3ffd7fff, 1070346240 bytes (261315 pages)
Jul 2 14:38:28 relay05 /kernel: avail memory = 1042087936 (1017664K bytes)
Jul 2 14:38:28 relay05 /kernel: Programming 24 pins in IOAPIC #0
Jul 2 14:38:28 relay05 /kernel: SMP: CPU0 apic_initialize():
Jul 2 14:38:28 relay05 /kernel: lint0: 0x00000700 lint1: 0x00010400 TPR: 0x00000010 SVR: 0x000001ff
Jul 2 14:38:28 relay05 /kernel: FreeBSD/SMP: Multiprocessor motherboard
Jul 2 14:38:28 relay05 /kernel: cpu0 (BSP): apic id: 1, version: 0x00040011, at 0xfee00000
Jul 2 14:38:28 relay05 /kernel: cpu1 (AP): apic id: 0, version: 0x00040011, at 0xfee00000
Jul 2 14:38:28 relay05 /kernel: io0 (APIC): apic id: 2, version: 0x00170011, at 0xfec00000
Jul 2 14:38:28 relay05 /kernel: Found BIOS32 Service Directory header at 0xc00ffe80
Jul 2 14:38:28 relay05 /kernel: Entry = 0xffe90 (0xc00ffe90) Rev = 0 Len = 1
Jul 2 14:38:28 relay05 /kernel: PCI BIOS entry at 0xcc0e
Jul 2 14:38:28 relay05 /kernel: SMIBIOS header at 0xc00fb210
Jul 2 14:38:28 relay05 /kernel: Version 2.2
Jul 2 14:38:28 relay05 /kernel: Table at 0xfb230, 55 entries, 1908 bytes, largest entry 330 bytes
Jul 2 14:38:28 relay05 /kernel: DMI header at 0xc00fb220
Jul 2 14:38:28 relay05 /kernel: Version 2.2
Jul 2 14:38:28 relay05 /kernel: Table at 0xfb230, 55 entries, 1908 bytes
Jul 2 14:38:28 relay05 /kernel: Other BIOS signatures found:
Jul 2 14:38:28 relay05 /kernel: ACPI: 00000000
Jul 2 14:38:28 relay05 /kernel: $PnP: 000fe2d0
Jul 2 14:38:28 relay05 /kernel: Preloaded elf kernel "kernel" at 0xc02f9000.
Jul 2 14:38:28 relay05 /kernel: Preloaded elf module "splash_bmp.ko" at 0xc02f909c.
Jul 2 14:38:28 relay05 /kernel: Preloaded elf module "vesa.ko" at 0xc02f9140.
Jul 2 14:38:28 relay05 /kernel: Preloaded splash_image_data "/boot/splash.bmp" at 0xc02f91dc.
Jul 2 14:38:28 relay05 /kernel: VESA: information block
Jul 2 14:38:29 relay05 /kernel: 56 45 53 41 00 02 00 01 00 01 00 00 00 00 22 00
Jul 2 14:38:29 relay05 /kernel: 00 01 20 00 00 01 0b 01 00 01 21 01 00 01 2a 01
Jul 2 14:38:29 relay05 /kernel: 00 01 00 01 01 01 10 01 11 01 12 01 03 01 13 01
Jul 2 14:38:29 relay05 /kernel: 14 01 15 01 05 01 16 01 17 01 18 01 07 01 19 01
Jul 2 14:38:29 relay05 /kernel: VESA: 3 mode(s) found
Jul 2 14:38:29 relay05 /kernel: VESA: v2.0, 2048k memory, flags:0x0, mode table:0xc02e70da (1000022)
Jul 2 14:38:29 relay05 /kernel: VESA: ATI MACH64
Jul 2 14:38:29 relay05 /kernel: VESA: ATI Technologies Inc.
Jul 2 14:38:29 relay05 /kernel: VESA: MACH64GT
Jul 2 14:38:29 relay05 /kernel: VESA: 01.00
Jul 2 14:38:29 relay05 /kernel: vinum: loaded
Jul 2 14:38:29 relay05 /kernel: splash: image@0xc02e8024, size:64534
Jul 2 14:38:29 relay05 /kernel: bmp_start(): splash_mode:28
Jul 2 14:38:29 relay05 /kernel: splash: image decoder found: splash_bmp
Jul 2 14:38:29 relay05 /kernel: SMP: CPU0 bsp_apic_configure():
Jul 2 14:38:29 relay05 /kernel: lint0: 0x00010700 lint1: 0x00000400 TPR: 0x00000010 SVR: 0x000001ff
Jul 2 14:38:29 relay05 /kernel: pci_open(1): mode 1 addr port (0x0cf8) is 0x80010014
Jul 2 14:38:29 relay05 /kernel: pci_open(1a): mode1res=0x80000000 (0x80000000)
Jul 2 14:38:29 relay05 /kernel: pci_cfgcheck: device 0 [class=060000] [hdr=00] is there (id=71a08086)
Jul 2 14:38:29 relay05 /kernel: Probing for devices on PCI bus 0:
Jul 2 14:38:29 relay05 /kernel: found-> vendor=0x8086, dev=0x71a0, revid=0x00
Jul 2 14:38:29 relay05 /kernel: class=06-00-00, hdrtype=0x00, mfdev=0
Jul 2 14:38:29 relay05 /kernel: subordinatebus=0 secondarybus=0
Jul 2 14:38:29 relay05 /kernel: map[0]: type 3, range 32, base f0000000, size 26
Jul 2 14:38:29 relay05 /kernel: chip0: <Intel 82443GX host to PCI bridge> rev 0x00 on pci0.0.0
Jul 2 14:38:29 relay05 /kernel: found-> vendor=0x8086, dev=0x71a1, revid=0x00
Jul 2 14:38:29 relay05 /kernel: class=06-04-00, hdrtype=0x01, mfdev=0
Jul 2 14:38:29 relay05 /kernel: subordinatebus=1 secondarybus=1
Jul 2 14:38:29 relay05 /kernel: chip1: <Intel 82443GX host to AGP bridge> rev 0x00 on pci0.1.0
Jul 2 14:38:29 relay05 /kernel: found-> vendor=0x1011, dev=0x0024, revid=0x03
Jul 2 14:38:29 relay05 /kernel: class=06-04-00, hdrtype=0x01, mfdev=0
Jul 2 14:38:29 relay05 /kernel: subordinatebus=2 secondarybus=2
Jul 2 14:38:29 relay05 /kernel: chip2: <PCI to PCI bridge (vendor=1011 device=0024)> rev 0x03 on pci0.2.0
Jul 2 14:38:29 relay05 /kernel: found-> vendor=0x8086, dev=0x7110, revid=0x02
Jul 2 14:38:29 relay05 /kernel: class=06-01-00, hdrtype=0x00, mfdev=1
Jul 2 14:38:29 relay05 /kernel: subordinatebus=0 secondarybus=0
Jul 2 14:38:29 relay05 /kernel: chip3: <Intel 82371AB PCI to ISA bridge> rev 0x02 on pci0.7.0
Jul 2 14:38:29 relay05 /kernel: found-> vendor=0x8086, dev=0x7111, revid=0x01
Jul 2 14:38:29 relay05 /kernel: class=01-01-80, hdrtype=0x00, mfdev=0
Jul 2 14:38:29 relay05 /kernel: subordinatebus=0 secondarybus=0
Jul 2 14:38:29 relay05 /kernel: map[0]: type 4, range 32, base 0000ffa0, size 4
Jul 2 14:38:29 relay05 /kernel: Freeing (NOT implemented) redirected ISA irq 0.
Jul 2 14:38:29 relay05 /kernel: found-> vendor=0x8086, dev=0x7112, revid=0x01
Jul 2 14:38:29 relay05 /kernel: class=0c-03-00, hdrtype=0x00, mfdev=0
Jul 2 14:38:29 relay05 /kernel: subordinatebus=0 secondarybus=0
Jul 2 14:38:29 relay05 /kernel: intpin=d, irq=2
Jul 2 14:38:29 relay05 /kernel: map[0]: type 4, range 32, base 00000000, size 5
Jul 2 14:38:29 relay05 /kernel: found-> vendor=0x8086, dev=0x7113, revid=0x02
Jul 2 14:38:29 relay05 /kernel: class=06-80-00, hdrtype=0x00, mfdev=0
Jul 2 14:38:29 relay05 /kernel: subordinatebus=0 secondarybus=0
Jul 2 14:38:29 relay05 /kernel: chip4: <Intel 82371AB Power management controller> rev 0x02 on pci0.7.3
Jul 2 14:38:29 relay05 /kernel: Freeing (NOT implemented) redirected PCI irq 14.
Jul 2 14:38:29 relay05 /kernel: found-> vendor=0x8086, dev=0x1229, revid=0x05
Jul 2 14:38:29 relay05 /kernel: class=02-00-00, hdrtype=0x00, mfdev=0
Jul 2 14:38:29 relay05 /kernel: subordinatebus=0 secondarybus=0
Jul 2 14:38:30 relay05 /kernel: intpin=a, irq=20
Jul 2 14:38:30 relay05 /kernel: map[0]: type 3, range 32, base f7001000, size 12
Jul 2 14:38:30 relay05 /kernel: map[1]: type 4, range 32, base 0000dce0, size 5
Jul 2 14:38:30 relay05 /kernel: map[2]: type 1, range 32, base fe100000, size 20
Jul 2 14:38:30 relay05 /kernel: fxp0: <Intel EtherExpress Pro 10/100B Ethernet> rev 0x05 int a irq 20 on pci0.8.0
Jul 2 14:38:30 relay05 /kernel: fxp0: Ethernet address 00:90:27:7a:2e:ca
Jul 2 14:38:30 relay05 /kernel: bpf: fxp0 attached
Jul 2 14:38:30 relay05 /kernel: Freeing (NOT implemented) redirected PCI irq 11.
Jul 2 14:38:30 relay05 /kernel: found-> vendor=0x8086, dev=0x1229, revid=0x05
Jul 2 14:38:30 relay05 /kernel: class=02-00-00, hdrtype=0x00, mfdev=0
Jul 2 14:38:30 relay05 /kernel: subordinatebus=0 secondarybus=0
Jul 2 14:38:30 relay05 /kernel: intpin=a, irq=21
Jul 2 14:38:30 relay05 /kernel: map[0]: type 3, range 32, base f7000000, size 12
Jul 2 14:38:30 relay05 /kernel: map[1]: type 4, range 32, base 0000dcc0, size 5
Jul 2 14:38:30 relay05 /kernel: map[2]: type 1, range 32, base fe000000, size 20
Jul 2 14:38:30 relay05 /kernel: fxp1: <Intel EtherExpress Pro 10/100B Ethernet> rev 0x05 int a irq 21 on pci0.10.0
Jul 2 14:38:30 relay05 /kernel: fxp1: Ethernet address 00:90:27:7a:2d:c4
Jul 2 14:38:30 relay05 /kernel: bpf: fxp1 attached
Jul 2 14:38:30 relay05 /kernel: Probing for devices on PCI bus 1:
Jul 2 14:38:30 relay05 /kernel: found-> vendor=0x1002, dev=0x4744, revid=0x5c
Jul 2 14:38:30 relay05 /kernel: class=03-00-00, hdrtype=0x00, mfdev=0
Jul 2 14:38:30 relay05 /kernel: subordinatebus=0 secondarybus=0
Jul 2 14:38:30 relay05 /kernel: map[0]: type 1, range 32, base fc000000, size 24
Jul 2 14:38:30 relay05 /kernel: map[1]: type 4, range 32, base 0000fc00, size 8
Jul 2 14:38:30 relay05 /kernel: map[2]: type 1, range 32, base fbfff000, size 12
Jul 2 14:38:30 relay05 /kernel: vga0: <ATI model 4744 graphics accelerator> rev 0x5c on pci1.0.0
Jul 2 14:38:30 relay05 /kernel: Probing for devices on PCI bus 2:
Jul 2 14:38:30 relay05 /kernel: Freeing (NOT implemented) redirected PCI irq 10.
Jul 2 14:38:30 relay05 /kernel: found-> vendor=0x9005, dev=0x001f, revid=0x00
Jul 2 14:38:30 relay05 /kernel: class=01-00-00, hdrtype=0x00, mfdev=0
Jul 2 14:38:30 relay05 /kernel: subordinatebus=0 secondarybus=0
Jul 2 14:38:30 relay05 /kernel: intpin=a, irq=16
Jul 2 14:38:30 relay05 /kernel: map[0]: type 4, range 32, base 0000ec00, size 8
Jul 2 14:38:30 relay05 /kernel: map[1]: type 1, range 64, base f9fff000, size 12
Jul 2 14:38:30 relay05 /kernel: map[2]: type 0, range 0, base 00000000, size 0
Jul 2 14:38:30 relay05 /kernel: ahc0: <Adaptec aic7890/91 Ultra2 SCSI adapter> rev 0x00 int a irq 16 on pci2.4.0
Jul 2 14:38:30 relay05 /kernel: ahc0: Reading SEEPROM...done.
Jul 2 14:38:30 relay05 /kernel: ahc0: BIOS eeprom is present
Jul 2 14:38:30 relay05 /kernel: ahc0: Secondary High byte termination Enabled
Jul 2 14:38:30 relay05 /kernel: ahc0: Secondary Low byte termination Enabled
Jul 2 14:38:30 relay05 /kernel: ahc0: Primary Low Byte termination Enabled
Jul 2 14:38:30 relay05 /kernel: ahc0: Primary High Byte termination Enabled
Jul 2 14:38:30 relay05 /kernel: ahc0: aic7890/91 Wide Channel A, SCSI Id=7, 16/255 SCBs
Jul 2 14:38:30 relay05 /kernel: ahc0: Downloading Sequencer Program... 393 instructions downloaded
Jul 2 14:38:30 relay05 /kernel: Freeing (NOT implemented) redirected PCI irq 10.
Jul 2 14:38:30 relay05 /kernel: found-> vendor=0x9004, dev=0x6078, revid=0x03
Jul 2 14:38:30 relay05 /kernel: class=01-00-00, hdrtype=0x00, mfdev=0
Jul 2 14:38:30 relay05 /kernel: subordinatebus=0 secondarybus=0
Jul 2 14:38:30 relay05 /kernel: intpin=a, irq=16
Jul 2 14:38:30 relay05 /kernel: map[0]: type 4, range 32, base 0000e800, size 8
Jul 2 14:38:30 relay05 /kernel: map[1]: type 1, range 32, base f9ffe000, size 12
Jul 2 14:38:30 relay05 /kernel: ahc1: <Adaptec aic7860 SCSI adapter> rev 0x03 int a irq 16 on pci2.6.0
Jul 2 14:38:30 relay05 /kernel: using shared irq16.
Jul 2 14:38:31 relay05 /kernel: ahc1: Reading SEEPROM...done.
Jul 2 14:38:31 relay05 /kernel: ahc1: aic7860 Single Channel A, SCSI Id=7, 3/255 SCBs
Jul 2 14:38:31 relay05 /kernel: ahc1: Downloading Sequencer Program... 409 instructions downloaded
Jul 2 14:38:31 relay05 /kernel: Probing for devices on the ISA bus:
Jul 2 14:38:31 relay05 /kernel: atkbd: the current kbd controller command byte 0065
Jul 2 14:38:31 relay05 /kernel: atkbd: keyboard ID 0x41ab (2)
Jul 2 14:38:31 relay05 /kernel: kbdc: RESET_KBD return code:00fa
Jul 2 14:38:31 relay05 /kernel: kbdc: RESET_KBD status:00aa
Jul 2 14:38:31 relay05 /kernel: sc0 on isa
Jul 2 14:38:31 relay05 /kernel: sc0: fb0 kbd0
Jul 2 14:38:31 relay05 /kernel: sc0: VGA color <16 virtual consoles, flags=0x0>
Jul 2 14:38:31 relay05 /kernel: atkbdc0 at 0x60-0x6f on motherboard
Jul 2 14:38:31 relay05 /kernel: atkbd0 irq 1 on isa
Jul 2 14:38:31 relay05 /kernel: kbd0: atkbd0, AT 101/102 (2), config:0x0, flags:0x3d0000
Jul 2 14:38:31 relay05 /kernel: psm0: current command byte:0065
Jul 2 14:38:31 relay05 /kernel: kbdc: TEST_AUX_PORT status:0000
Jul 2 14:38:31 relay05 /kernel: kbdc: RESET_AUX return code:00fa
Jul 2 14:38:31 relay05 /kernel: kbdc: RESET_AUX status:00aa
Jul 2 14:38:31 relay05 /kernel: kbdc: RESET_AUX ID:0000
Jul 2 14:38:31 relay05 /kernel: psm: status 00 02 64
Jul 2 14:38:31 relay05 /kernel: psm: status 00 00 64
Jul 2 14:38:31 relay05 /kernel: psm: status 00 03 64
Jul 2 14:38:31 relay05 /kernel: psm: status 00 03 64
Jul 2 14:38:31 relay05 /kernel: psm: status 10 00 64
Jul 2 14:38:31 relay05 /kernel: psm: data 00 00 00
Jul 2 14:38:31 relay05 /kernel: psm: data 00 00 00
Jul 2 14:38:31 relay05 /kernel: psm: status 00 02 64
Jul 2 14:38:31 relay05 /kernel: psm0 irq 12 on isa
Jul 2 14:38:31 relay05 /kernel: psm0: model Generic PS/2 mouse, device ID 0, 2 buttons
Jul 2 14:38:31 relay05 /kernel: psm0: config:00000000, flags:00000000, packet size:3
Jul 2 14:38:31 relay05 /kernel: psm0: syncmask:c0, syncbits:00
Jul 2 14:38:31 relay05 /kernel: sio0: irq maps: 0x1 0x11 0x1 0x1
Jul 2 14:38:31 relay05 /kernel: sio0 at 0x3f8-0x3ff irq 4 flags 0x10 on isa
Jul 2 14:38:31 relay05 /kernel: sio0: type 16550A
Jul 2 14:38:31 relay05 /kernel: sio1: irq maps: 0x1 0x9 0x1 0x1
Jul 2 14:38:31 relay05 /kernel: sio1 at 0x2f8-0x2ff irq 3 on isa
Jul 2 14:38:31 relay05 /kernel: sio1: type 16550A
Jul 2 14:38:31 relay05 /kernel: sio2: disabled, not probed.
Jul 2 14:38:31 relay05 /kernel: sio3: disabled, not probed.
Jul 2 14:38:31 relay05 /kernel: fdc0 at 0x3f0-0x3f7 irq 6 drq 2 on isa
Jul 2 14:38:31 relay05 /kernel: fdc0: FIFO enabled, 8 bytes threshold
Jul 2 14:38:31 relay05 /kernel: fd0: 1.44MB 3.5in
Jul 2 14:38:31 relay05 /kernel: ppc: parallel port found at 0x378
Jul 2 14:38:31 relay05 /kernel: PC873xx probe at 0x2e got unknown ID 0x0
Jul 2 14:38:31 relay05 /kernel: ppc0: ECP SPP ECP+EPP SPP
Jul 2 14:38:31 relay05 /kernel: ppc0 at 0x378 irq 7 on isa
Jul 2 14:38:31 relay05 /kernel: ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode
Jul 2 14:38:31 relay05 /kernel: ppc0: FIFO with 16/16/8 bytes threshold
Jul 2 14:38:31 relay05 /kernel: ppi0: <generic parallel i/o> on ppbus 0
Jul 2 14:38:31 relay05 /kernel: plip: irq 7
Jul 2 14:38:31 relay05 /kernel: plip0: <PLIP network interface> on ppbus 0
Jul 2 14:38:31 relay05 /kernel: bpf: lp0 attached
Jul 2 14:38:31 relay05 /kernel: vga0 at 0x3b0-0x3df maddr 0xa0000 msize 131072 on isa
Jul 2 14:38:31 relay05 /kernel: VESA: v2.0, 2048k memory, flags:0x0, mode table:0xc02e70da (1000022)
Jul 2 14:38:31 relay05 /kernel: VESA: ATI MACH64
Jul 2 14:38:31 relay05 /kernel: VESA: ATI Technologies Inc.
Jul 2 14:38:31 relay05 /kernel: VESA: MACH64GT
Jul 2 14:38:32 relay05 /kernel: VESA: 01.00
Jul 2 14:38:32 relay05 /kernel: npx0 on motherboard
Jul 2 14:38:32 relay05 /kernel: npx0: INT 16 interface
Jul 2 14:38:32 relay05 /kernel: imasks: bio c8000040, tty c730109a, net c730109a
Jul 2 14:38:32 relay05 /kernel: SMP: enabled INTs: 1, 3, 4, 6, 7, 12, 16, 20, 21, apic_imen: 0x00ceef25
Jul 2 14:38:32 relay05 /kernel: BIOS Geometries:
Jul 2 14:38:32 relay05 /kernel: 0:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
Jul 2 14:38:32 relay05 /kernel: 1:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
Jul 2 14:38:32 relay05 /kernel: 2:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
Jul 2 14:38:32 relay05 /kernel: 3:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
Jul 2 14:38:32 relay05 /kernel: 4:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
Jul 2 14:38:32 relay05 /kernel: 5:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
Jul 2 14:38:32 relay05 /kernel: 6:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
Jul 2 14:38:32 relay05 /kernel: 7:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
Jul 2 14:38:32 relay05 /kernel: 0 accounted for
Jul 2 14:38:32 relay05 /kernel: Device configuration finished.
Jul 2 14:38:32 relay05 /kernel: APIC_IO: Testing 8254 interrupt delivery
Jul 2 14:38:32 relay05 /kernel: APIC_IO: routing 8254 via pin 2
Jul 2 14:38:32 relay05 /kernel: ccd0-3: Concatenated disk drivers
Jul 2 14:38:32 relay05 /kernel: bpf: lo0 attached
Jul 2 14:38:32 relay05 /kernel: bpf: ppp0 attached
Jul 2 14:38:32 relay05 /kernel: new masks: bio c8000040, tty c730109a, net c730109a
Jul 2 14:38:32 relay05 /kernel: bpf: sl0 attached
Jul 2 14:38:32 relay05 /kernel: bpf: tun0 attached
Jul 2 14:38:32 relay05 /kernel: Waiting 2 seconds for SCSI devices to settle
Jul 2 14:38:32 relay05 /kernel: (noperiph:ahc0:0:-1:-1): SCSI bus reset delivered. 0 SCBs aborted.
Jul 2 14:38:32 relay05 /kernel: (noperiph:ahc1:0:-1:-1): SCSI bus reset delivered. 0 SCBs aborted.
Jul 2 14:38:32 relay05 /kernel: SMP: AP CPU #1 Launched!
Jul 2 14:38:32 relay05 /kernel: SMP: CPU1 apic_initialize():
Jul 2 14:38:32 relay05 /kernel: lint0: 0x00010700 lint1: 0x00010400 TPR: 0x00000010 SVR: 0x000001ff
Jul 2 14:38:32 relay05 /kernel: ahc1: Selection Timeout on A:0. 1 SCBs aborted
Jul 2 14:38:32 relay05 /kernel: ahc0: Selection Timeout on A:2. 1 SCBs aborted
Jul 2 14:38:32 relay05 /kernel: ahc0: Selection Timeout on A:3. 1 SCBs aborted
Jul 2 14:38:32 relay05 /kernel: ahc1: Selection Timeout on A:1. 1 SCBs aborted
Jul 2 14:38:32 relay05 /kernel: ahc0: Selection Timeout on A:4. 1 SCBs aborted
Jul 2 14:38:32 relay05 /kernel: ahc1: Selection Timeout on A:2. 1 SCBs aborted
Jul 2 14:38:32 relay05 /kernel: ahc0: Selection Timeout on A:5. 1 SCBs aborted
Jul 2 14:38:32 relay05 /kernel: ahc1: Selection Timeout on A:3. 1 SCBs aborted
Jul 2 14:38:32 relay05 /kernel: ahc0: Selection Timeout on A:8. 1 SCBs aborted
Jul 2 14:38:32 relay05 /kernel: ahc1: Selection Timeout on A:4. 1 SCBs aborted
Jul 2 14:38:32 relay05 /kernel: ahc0: Selection Timeout on A:9. 1 SCBs aborted
Jul 2 14:38:32 relay05 /kernel: ahc1: Selection Timeout on A:6. 1 SCBs aborted
Jul 2 14:38:33 relay05 /kernel: (probe20:ahc1:0:5:0): INQUIRY. CDB: 12 1 80 0 ff 0
Jul 2 14:38:33 relay05 /kernel: (probe20:ahc1:0:5:0): ILLEGAL REQUEST asc:24,0
Jul 2 14:38:33 relay05 /kernel: (probe20:ahc1:0:5:0): Invalid field in CDB
Jul 2 14:38:33 relay05 /kernel: ahc1: target 5 synchronous at 20.0MHz, offset = 0xf
Jul 2 14:38:33 relay05 /kernel: ahc0: Selection Timeout on A:10. 1 SCBs aborted
Jul 2 14:38:33 relay05 /kernel: ahc0: Selection Timeout on A:11. 1 SCBs aborted
Jul 2 14:38:33 relay05 /kernel: ahc0: Selection Timeout on A:12. 1 SCBs aborted
Jul 2 14:38:33 relay05 /kernel: ahc0: Selection Timeout on A:13. 1 SCBs aborted
Jul 2 14:38:33 relay05 /kernel: ahc0: Selection Timeout on A:14. 1 SCBs aborted
Jul 2 14:38:33 relay05 /kernel: ahc0: Selection Timeout on A:15. 1 SCBs aborted
Jul 2 14:38:33 relay05 /kernel: (probe6:ahc0:0:6:0): INQUIRY. CDB: 12 1 80 0 ff 0
Jul 2 14:38:33 relay05 /kernel: (probe6:ahc0:0:6:0): ILLEGAL REQUEST asc:24,0
Jul 2 14:38:33 relay05 /kernel: (probe6:ahc0:0:6:0): Invalid field in CDB
Jul 2 14:38:33 relay05 /kernel: ahc0: target 1 using 16bit transfers
Jul 2 14:38:33 relay05 /kernel: ahc0: target 1 synchronous at 40.0MHz, offset = 0xf
Jul 2 14:38:33 relay05 /kernel: ahc0: target 0 using 16bit transfers
Jul 2 14:38:33 relay05 /kernel: ahc0: target 0 synchronous at 40.0MHz, offset = 0xf
Jul 2 14:38:33 relay05 /kernel: pass0 at ahc0 bus 0 target 0 lun 0
Jul 2 14:38:33 relay05 /kernel: pass0: <SEAGATE ST39102LC 0004> Fixed Direct Access SCSI-2 device
Jul 2 14:38:33 relay05 /kernel: pass0: Serial Number LJP21594000019450AT4
Jul 2 14:38:33 relay05 /kernel: pass0: 80.000MB/s transfers (40.000MHz, offset 15, 16bit), Tagged Queueing Enabled
Jul 2 14:38:33 relay05 /kernel: pass1 at ahc0 bus 0 target 1 lun 0
Jul 2 14:38:33 relay05 /kernel: pass1: <SEAGATE ST39102LC 0004> Fixed Direct Access SCSI-2 device
Jul 2 14:38:33 relay05 /kernel: pass1: Serial Number LJP22215000019450CL9
Jul 2 14:38:33 relay05 /kernel: pass1: 80.000MB/s transfers (40.000MHz, offset 15, 16bit), Tagged Queueing Enabled
Jul 2 14:38:33 relay05 /kernel: pass2 at ahc0 bus 0 target 6 lun 0
Jul 2 14:38:33 relay05 /kernel: pass2: <DELL 1x3 U2W SCSI BP 5.13> Fixed Processor SCSI-2 device
Jul 2 14:38:33 relay05 /kernel: pass2: 3.300MB/s transfers
Jul 2 14:38:33 relay05 /kernel: pass3 at ahc1 bus 0 target 5 lun 0
Jul 2 14:38:33 relay05 /kernel: pass3: <NEC CD-ROM DRIVE:466 1.06> Removable CD-ROM SCSI-2 device
Jul 2 14:38:33 relay05 /kernel: pass3: 20.000MB/s transfers (20.000MHz, offset 15)
Jul 2 14:38:33 relay05 /kernel: pass2 at ahc0 bus 0 target 6 lun 0
Jul 2 14:38:33 relay05 /kernel: pass2: <DELL 1x3 U2W SCSI BP 5.13> Fixed Processor SCSI-2 device
Jul 2 14:38:33 relay05 /kernel: pass2: 3.300MB/s transfers
Jul 2 14:38:33 relay05 /kernel: da0 at ahc0 bus 0 target 0 lun 0
Jul 2 14:38:33 relay05 /kernel: da0: <SEAGATE ST39102LC 0004> Fixed Direct Access SCSI-2 device
Jul 2 14:38:33 relay05 /kernel: da0: Serial Number LJP21594000019450AT4
Jul 2 14:38:33 relay05 /kernel: da0: 80.000MB/s transfers (40.000MHz, offset 15, 16bit), Tagged Queueing Enabled
Jul 2 14:38:33 relay05 /kernel: da0: 8683MB (17783240 512 byte sectors: 255H 63S/T 1106C)
Jul 2 14:38:33 relay05 /kernel: da1 at ahc0 bus 0 target 1 lun 0
Jul 2 14:38:33 relay05 /kernel: da1: <SEAGATE ST39102LC 0004> Fixed Direct Access SCSI-2 device
Jul 2 14:38:34 relay05 /kernel: da1: Serial Number LJP22215000019450CL9
Jul 2 14:38:34 relay05 /kernel: da1: 80.000MB/s transfers (40.000MHz, offset 15, 16bit), Tagged Queueing Enabled
Jul 2 14:38:34 relay05 /kernel: da1: 8683MB (17783240 512 byte sectors: 255H 63S/T 1106C)
Jul 2 14:38:34 relay05 /kernel: Considering FFS root f/s.
Jul 2 14:38:34 relay05 /kernel: changing root device to da0s1a
Jul 2 14:38:34 relay05 /kernel: da0s1: type 0xa5, start 63, end = 17767889, size 17767827 : OK
Jul 2 14:38:34 relay05 /kernel: WARNING: / was not properly dismounted
Jul 2 14:38:34 relay05 /kernel: start_init: trying /sbin/init
Jul 2 14:38:34 relay05 /kernel: da1s1: type 0xa5, start 63, end = 17767889, size 17767827 : OK
Jul 2 14:38:34 relay05 /kernel: ffs_mountfs: superblock updated for soft updates
Jul 2 14:38:34 relay05 last message repeated 3 times
Jul 2 14:38:34 relay05 savecore: reboot after panic: from debugger
Jul 2 14:38:34 relay05 savecore: writing core to /var/crash/vmcore.1
Jul 2 14:38:45 relay05 /kernel: (da1:ahc0:0:1:0): tagged openings now 64
Jul 2 14:39:09 relay05 /kernel: (cd0:ahc1:0:5:0): READ CD RECORDED CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0
Jul 2 14:39:09 relay05 /kernel: (cd0:ahc1:0:5:0): NOT READY asc:3a,0
Jul 2 14:39:09 relay05 /kernel: (cd0:ahc1:0:5:0): Medium not present
Jul 2 14:39:09 relay05 /kernel: cd0 at ahc1 bus 0 target 5 lun 0
Jul 2 14:39:09 relay05 /kernel: cd0: <NEC CD-ROM DRIVE:466 1.06> Removable CD-ROM SCSI-2 device
Jul 2 14:39:09 relay05 /kernel: cd0: 20.000MB/s transfers (20.000MHz, offset 15)
Jul 2 14:39:09 relay05 /kernel: cd0: Attempt to query device size failed: NOT READY, Medium not present
Jul 2 14:40:19 relay05 savecore: writing kernel to /var/crash/kernel.1
kernel config:
machine "i386"
cpu "I686_CPU"
ident RELAYSMP
maxusers 512
options INCLUDE_CONFIG_FILE
options "NO_F00F_HACK"
options USER_LDT
options "MD5"
options "VM86"
options DDB
options DDB_UNATTENDED
options PERFMON
options "ICMP_BANDLIM"
options SOFTUPDATES
options "MSGBUF_SIZE=163840"
pseudo-device snp 3
pseudo-device ccd 4
pseudo-device vinum
#options MATH_EMULATE #Support for x87 emulation
options INET #InterNETworking
options FFS #Berkeley Fast Filesystem
options FFS_ROOT #FFS usable as root device [keep this!]
#options MFS #Memory Filesystem
#options MFS_ROOT #MFS usable as root device, "MFS" req'ed
options NFS #Network Filesystem
#options NFS_ROOT #NFS usable as root device, "NFS" req'ed
options MSDOSFS #MSDOS Filesystem
options "CD9660" #ISO 9660 Filesystem
options "CD9660_ROOT" #CD-ROM usable as root. "CD9660" req'ed
options PROCFS #Process filesystem
options "COMPAT_43" #Compatible with BSD 4.3 [KEEP THIS!]
options SCSI_DELAY=2000
options UCONSOLE #Allow users to grab the console
options FAILSAFE #Be conservative
options USERCONFIG #boot -c editor
options VISUAL_USERCONFIG #visual boot -c editor
config kernel root on wd0
# To make an SMP kernel, the next two are needed
options SMP # Symmetric MultiProcessor Kernel
options APIC_IO # Symmetric (APIC) I/O
# Optionally these may need tweaked, (defaults shown):
#options NCPU=2 # number of CPUs
#options NBUS=4 # number of busses
#options NAPIC=1 # number of IO APICs
#options NINTR=24 # number of INTs
controller isa0
controller eisa0
controller pci0
controller fdc0 at isa? port "IO_FD1" bio irq 6 drq 2
disk fd0 at fdc0 drive 0
disk fd1 at fdc0 drive 1
#options "CMD640" # work around CMD640 chip deficiency
#controller wdc0 at isa? port "IO_WD1" bio irq 14
#disk wd0 at wdc0 drive 0
#disk wd1 at wdc0 drive 1
#controller wdc1 at isa? port "IO_WD2" bio irq 15
#disk wd2 at wdc1 drive 0
#disk wd3 at wdc1 drive 1
#options ATAPI #Enable ATAPI support for IDE bus
#options ATAPI_STATIC #Don't do it as an LKM
#device acd0 #IDE CD-ROM
#device wfd0 #IDE Floppy (e.g. LS-120)
# A single entry for any of these controllers (ncr, ahb, ahc) is
# sufficient for any number of installed devices.
#controller ncr0
#controller ahb0
controller ahc0
#controller isp0
# This controller offers a number of configuration options, too many to
# document here - see the LINT file in this directory and look up the
# dpt0 entry there for much fuller documentation on this.
#controller dpt0
#controller adv0 at isa? port ? cam irq ?
#controller adw0
#controller bt0 at isa? port ? cam irq ?
#controller aha0 at isa? port ? cam irq ?
#controller aic0 at isa? port 0x340 bio irq 11
controller scbus0
device da0
device sa0
device pass0
device cd0 #Only need one of these, the code dynamically grows
#device wt0 at isa? port 0x300 bio irq 5 drq 1
#device mcd0 at isa? port 0x300 bio irq 10
#controller matcd0 at isa? port 0x230 bio
#device scd0 at isa? port 0x230 bio
# atkbdc0 controlls both the keyboard and the PS/2 mouse
controller atkbdc0 at isa? port IO_KBD tty
device atkbd0 at isa? tty irq 1
device psm0 at isa? tty irq 12
device vga0 at isa? port ? conflicts
# splash screen/screen saver
pseudo-device splash
# syscons is the default console driver, resembling an SCO console
device sc0 at isa? tty
# Enable this and PCVT_FREEBSD for pcvt vt220 compatible console driver
#device vt0 at isa? tty
#options XSERVER # support for X server
#options FAT_CURSOR # start with block cursor
# If you have a ThinkPAD, uncomment this along with the rest of the PCVT lines
#options PCVT_SCANSET=2 # IBM keyboards are non-std
device npx0 at isa? port IO_NPX irq 13
#
# Laptop support (see LINT for more options)
#
#device apm0 at isa? disable flags 0x31 # Advanced Power Management
# PCCARD (PCMCIA) support
#controller card0
#device pcic0 at card?
#device pcic1 at card?
device sio0 at isa? port "IO_COM1" flags 0x10 tty irq 4
device sio1 at isa? port "IO_COM2" tty irq 3
device sio2 at isa? disable port "IO_COM3" tty irq 5
device sio3 at isa? disable port "IO_COM4" tty irq 9
# Parallel port
device ppc0 at isa? port? net irq 7
controller ppbus0
device nlpt0 at ppbus?
device plip0 at ppbus?
device ppi0 at ppbus?
#controller vpo0 at ppbus?
# Order is important here due to intrusive probes, do *not* alphabetize
# this list of network interfaces until the probes have been fixed.
# Right now it appears that the ie0 must be probed before ep0. See
# revision 1.20 of this file.
#device ax0
#device de0
device fxp0
#device mx0
#device pn0
#device rl0
#device tl0
#device tx0
#device vr0
#device vx0
#device wb0
#device xl0
#device ed0 at isa? port 0x280 net irq 10 iomem 0xd8000
#device ie0 at isa? port 0x300 net irq 10 iomem 0xd0000
#device ep0 at isa? port 0x300 net irq 10
#device ex0 at isa? port? net irq?
#device fe0 at isa? port 0x300 net irq ?
#device le0 at isa? port 0x300 net irq 5 iomem 0xd0000
#device lnc0 at isa? port 0x280 net irq 10 drq 0
#device ze0 at isa? port 0x300 net irq 10 iomem 0xd8000
#device zp0 at isa? port 0x300 net irq 10 iomem 0xd8000
#device cs0 at isa? port 0x300 net irq ?
pseudo-device loop
pseudo-device ether
pseudo-device sl 1
pseudo-device ppp 1
pseudo-device tun 1
pseudo-device pty 64
pseudo-device gzip # Exec gzipped a.out's
# KTRACE enables the system-call tracing facility ktrace(2).
# This adds 4 KB bloat to your kernel, and slightly increases
# the costs of each syscall.
options KTRACE #kernel tracing
# This provides support for System V shared memory and message queues.
#
options SYSVSHM
options SYSVMSG
# The `bpfilter' pseudo-device enables the Berkeley Packet Filter. Be
# aware of the legal and administrative consequences of enabling this
# option. The number of devices determines the maximum number of
# simultaneous BPF clients programs runnable.
pseudo-device bpfilter 4 #Berkeley packet filter
# USB support
#controller uhci0
#controller ohci0
#controller usb0
#
# for the moment we have to specify the priorities of the device
# drivers explicitly by the ordering in the list below. This will
# be changed in the future.
#
#device ums0
#device ukbd0
#device ulpt0
#device uhub0
#device ucom0
#device umodem0
#device hid0
#device ugen0
#
#options USB_DEBUG
#options USBVERBOSE
>How-To-Repeat:
Install package lmbench-1.1.
cd /usr/local/lib/lmbench
make
SMP crashes every time during the context switch time testing
(which runs lots of processes).
I have a debugging kernel and dump. After
breaking out to DDB and forcing a crash and dump, backtrace shows:
(kgdb) bt
#0 boot (howto=256) at ../../kern/kern_shutdown.c:285
#1 0xc015b981 in panic (fmt=0xc02416dc "from debugger")
at ../../kern/kern_shutdown.c:446
#2 0xc012cdbd in db_panic (addr=-1071550809, have_addr=0, count=-1,
modif=0xd0860da4 "") at ../../ddb/db_command.c:432
#3 0xc012cd5d in db_command (last_cmdp=0xc0261220, cmd_table=0xc0261080,
aux_cmd_tablep=0xc0275ad8) at ../../ddb/db_command.c:332
#4 0xc012ce22 in db_command_loop () at ../../ddb/db_command.c:454
#5 0xc012f173 in db_trap (type=3, code=0) at ../../ddb/db_trap.c:71
#6 0xc0216c49 in kdb_trap (type=3, code=0, regs=0xd0860e98)
at ../../i386/i386/db_interface.c:157
#7 0xc0228094 in trap (frame={tf_es = -1071448048, tf_ds = -1071054832,
tf_edi = -1071608052, tf_esi = 134, tf_ebp = -796520740,
tf_isp = -796520768, tf_ebx = 0, tf_edx = -1071297289,
tf_ecx = 16777217, tf_eax = 38, tf_trapno = 3, tf_err = 0,
tf_eip = -1071550809, tf_cs = 8, tf_eflags = 582, tf_esp = -1071297305,
tf_ss = -1071309451}) at ../../i386/i386/trap.c:548
#8 0xc0216ea7 in Debugger (msg=0xc0251d75 "manual escape to debugger")
at ../../i386/i386/db_interface.c:317
#9 0xc021167c in scgetc (kbd=0xc028c3bc, flags=2)
at ../../dev/syscons/syscons.c:3716
#10 0xc020c3fc in sckbdevent (thiskbd=0xc028c3bc, event=0, arg=0x0)
at ../../dev/syscons/syscons.c:818
#11 0xc0208dd7 in atkbd_intr (kbd=0xc028c3bc, arg=0x0)
at ../../dev/kbd/atkbd.c:561
#12 0xc022d0b0 in atkbd_isa_intr (unit=0) at ../../i386/isa/atkbd_isa.c:84
#13 0xc0223444 in MPgetlock ()
#14 0x8049067 in ?? ()
#15 0x8048731 in ?? ()
Not much of use here and I don't know much about the SMP stuff from
a debugging point of view. I still have the core, so I can run any
commands people might consider useful. (I'm out of the
office Tues-Friday, but will help as much as I can after
that.)
>Fix:
Unknown
>Release-Note:
>Audit-Trail:
>Unformatted:
To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-bugs" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?199907051654.RAA23115>