Bug 12521

Summary:	lmbench-1.1's context switching test hangs SMP
Product:	Base System	Reporter:	Alan.Judge <Alan.Judge>
Component:	kern	Assignee:	freebsd-bugs (Nobody) <bugs>
Status:	Closed FIXED
Severity:	Affects Only Me	CC:	Alan.Judge
Priority:	Normal
Version:	3.2-STABLE
Hardware:	Any
OS:	Any

Description Alan.Judge 1999-07-05 18:00:01 UTC

	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

Fix: 

Unknown
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.)

Comment 1 eagle 1999-08-09 02:26:12 UTC

This appears to be >1g related i havent been able to trigger this
problem on any of my smp machines.
all less than 512 meg

Robg

Comment 2 Alan.Judge 1999-08-11 13:45:21 UTC

Robert> This appears to be >1g related i havent been able to trigger this
Robert> problem on any of my smp machines.
Robert> all less than 512 meg

Yep, it might be a 1Gb problem.  It's also possible that it's related
to the newly announced P-III SMP bug from Intel.  As soon as Dell
release a new BIOS, I'll try it again.
--
Alan

Comment 3 dd freebsd_committer

2001-09-11 12:23:12 UTC

State Changed
From-To: open->closed

CLosed at orignator's request.