Date: Fri, 19 Oct 2001 16:52:58 -0400 (EDT) From: Jeff Fellin <jkf@research.bell-labs.com> To: hackers@FreeBSD.org Subject: system hung with runnable processes Message-ID: <200110192052.QAA20502@aura.research.bell-labs.com>
next in thread | raw e-mail | index | archive | help
I didn't see anything like this in the archives, so I'm sending this to
the questions list and hackers list for assistance.
I am running FreeBSD 4.3 on a L440GX+ motherboard with dual PCI buses: 32/33
and 32/66 dual Pentium III @ 700MHz with 256KB L2 cache.
The system is running in Uniprocessor mode. Although running the
tests on FreeBSD 4.1 has not caused the problem.
My problem:
I have an application that reads from a SCSI bus, and forwards the
SCSI CDB's to another system over TCP. When running a large load the
system gets SCSI bus device reset's that the application acknowledges
and clears an error bit. After a period of time, in this example about
2.5 hours, the system stops processing any SCSI CDB's. In DDB the ps
output show 11 runnable process, p_wchan == 0, and curproc points to one
of the processes.
However, when checking the run queues via gdb, none of the runnable
processes is in a run queue. According to rtqueuebits, queuebits, and
idqueuebits, only queue[12] has any runnable processes. Examing the
proc structures for the runnable processes, their priority is 6, so they
should be in queue[6]. I cannot determine anything obvious in the process
scheduling code, but something is happening.
I am attaching the system dmesg output from boot to taking the system dump,
the ddb output on the serial console, and the output from gdb of the process'
stack trace and proc structure.
If anyone needs more information just ask and I'll try to get it for you.
Does anyone believe upgrading to FreeBSD 4.4 would resolve the problem?
======================================
/var/run/dmesg output
=====================================
Oct 15 11:15:54 nstg19 su: jkf to root on /dev/ttyp0
Oct 15 11:26:10 nstg19 su: fgu to root on /dev/ttyp1
Oct 15 11:38:54 nstg19 reboot: rebooted by fgu
Oct 15 11:38:54 nstg19 syslogd: exiting on signal 15
Oct 15 11:40:55 nstg19 /kernel: Waiting (max 60 seconds) for system process `bufdaemon' to stop...stopped
Oct 15 11:40:55 nstg19 /kernel: Waiting (max 60 seconds) for system process `syncer' to stop...stopped
Oct 15 11:40:55 nstg19 /kernel:
Oct 15 11:40:55 nstg19 /kernel: syncing disks...
Oct 15 11:40:55 nstg19 /kernel: done
Oct 15 11:40:55 nstg19 /kernel: Uptime: 2d20h1m57s
Oct 15 11:40:55 nstg19 /kernel: Rebooting...
Oct 15 11:40:55 nstg19 /kernel: Copyright (c) 1992-2001 The FreeBSD Project.
Oct 15 11:40:55 nstg19 /kernel: Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
Oct 15 11:40:55 nstg19 /kernel: The Regents of the University of California. All rights reserved.
Oct 15 11:40:55 nstg19 /kernel: FreeBSD 4.3-RELEASE #0: Mon Oct 15 11:35:39 EDT 2001
Oct 15 11:40:55 nstg19 /kernel: fgu@nstg19.research.bell-labs.com:/usr/src/sys/compile/NSTG19.FGU.UP
Oct 15 11:40:55 nstg19 /kernel: Timecounter "i8254" frequency 1193182 Hz
Oct 15 11:40:55 nstg19 /kernel: CPU: Pentium III/Pentium III Xeon/Celeron (999.53-MHz 686-class CPU)
Oct 15 11:40:55 nstg19 /kernel: Origin = "GenuineIntel" Id = 0x686 Stepping = 6
Oct 15 11:40:55 nstg19 /kernel: Features=0x383fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE>
Oct 15 11:40:55 nstg19 /kernel: real memory = 1073676288 (1048512K bytes)
Oct 15 11:40:55 nstg19 /kernel: avail memory = 1040621568 (1016232K bytes)
Oct 15 11:40:55 nstg19 /kernel: Preloaded elf kernel "kernel" at 0xc04ed000.
Oct 15 11:40:55 nstg19 /kernel: Pentium Pro MTRR support enabled
Oct 15 11:40:55 nstg19 /kernel: md0: Malloc disk
Oct 15 11:40:55 nstg19 /kernel: npx0: <math processor> on motherboard
Oct 15 11:40:55 nstg19 /kernel: npx0: INT 16 interface
Oct 15 11:40:55 nstg19 /kernel: pcib0: <ServerWorks host to PCI bridge> on motherboard
Oct 15 11:40:55 nstg19 /kernel: pci0: <PCI bus> on pcib0
Oct 15 11:40:55 nstg19 /kernel: nvram probe: type: 81166
Oct 15 11:40:55 nstg19 /kernel: nvram probe: type: 91166
Oct 15 11:40:55 nstg19 /kernel: pcib4: <PCI to PCI bridge (vendor=1166 device=0009)> at device 0.1 on pci0
Oct 15 11:40:55 nstg19 /kernel: pci1: <PCI bus> on pcib4
Oct 15 11:40:55 nstg19 /kernel: nvram probe: type: 474d1002
Oct 15 11:40:55 nstg19 /kernel: pci1: <ATI Mach64-GM graphics accelerator> at 0.0 irq 11
Oct 15 11:40:55 nstg19 /kernel: nvram probe: type: 61166
Oct 15 11:40:55 nstg19 /kernel: nvram probe: type: 61166
Oct 15 11:40:55 nstg19 /kernel: nvram probe: type: 109005
Oct 15 11:40:55 nstg19 /kernel: ahc0: <Adaptec 2940 Ultra2 SCSI adapter> port 0xde00-0xdeff mem 0xfeafa000-0xfeafafff irq 11 at device 3.0 on pci0
Oct 15 11:40:55 nstg19 /kernel: aic7890/91: Wide Channel A, SCSI Id=7, 32/255 SCBs
Oct 15 11:40:55 nstg19 /kernel: nvram probe: type: cf9005
Oct 15 11:40:55 nstg19 /kernel: ahc1: <Adaptec aic7899 Ultra160 SCSI adapter> port 0xd000-0xd0ff mem 0xfeafb000-0xfeafbfff irq 5 at device 5.0 on pci0
Oct 15 11:40:55 nstg19 /kernel: aic7899: Wide Channel A, SCSI Id=7, 32/255 SCBs
Oct 15 11:40:55 nstg19 /kernel: nvram probe: type: cf9005
Oct 15 11:40:55 nstg19 /kernel: ahc2: <Adaptec aic7899 Ultra160 SCSI adapter> port 0xd800-0xd8ff mem 0xfeafe000-0xfeafefff irq 10 at device 5.1 on pci0
Oct 15 11:40:55 nstg19 /kernel: aic7899: Wide Channel B, SCSI Id=7, 32/255 SCBs
Oct 15 11:40:55 nstg19 /kernel: fxp0: <Intel Pro 10/100B/100+ Ethernet> port 0xd400-0xd43f mem 0xfe900000-0xfe9fffff,0xfeaff000-0xfeafffff irq 9 at device 6.0 on pci0
Oct 15 11:40:55 nstg19 /kernel: fxp0: Ethernet address 00:30:48:10:7e:f3
Oct 15 11:40:55 nstg19 /kernel: nvram probe: type: 2001166
Oct 15 11:40:55 nstg19 /kernel: isab0: <ServerWorks IB6566 PCI to ISA bridge> at device 15.0 on pci0
Oct 15 11:40:55 nstg19 /kernel: isa0: <ISA bus> on isab0
Oct 15 11:40:55 nstg19 /kernel: nvram probe: type: 2111166
Oct 15 11:40:55 nstg19 /kernel: atapci0: <ServerWorks ROSB4 ATA33 controller> port 0xffa0-0xffaf at device 15.1 on pci0
Oct 15 11:40:55 nstg19 /kernel: ata0: at 0x1f0 irq 14 on atapci0
Oct 15 11:40:55 nstg19 /kernel: ata1: at 0x170 irq 15 on atapci0
Oct 15 11:40:55 nstg19 /kernel: nvram probe: type: 2201166
Oct 15 11:40:55 nstg19 /kernel: ohci0: <OHCI (generic) USB controller> mem 0xfeafd000-0xfeafdfff irq 10 at device 15.2 on pci0
Oct 15 11:40:55 nstg19 /kernel: usb0: OHCI version 1.0, legacy support
Oct 15 11:40:55 nstg19 /kernel: usb0: <OHCI (generic) USB controller> on ohci0
Oct 15 11:40:55 nstg19 /kernel: usb0: USB revision 1.0
Oct 15 11:40:55 nstg19 /kernel: uhub0: (unknown) OHCI root hub, class 9/0, rev 1.00/1.00, addr 1
Oct 15 11:40:55 nstg19 /kernel: uhub0: 4 ports with 4 removable, self powered
Oct 15 11:40:55 nstg19 /kernel: pcib1: <ServerWorks NB6635 3.0LE host to PCI bridge> on motherboard
Oct 15 11:40:55 nstg19 /kernel: pci2: <PCI bus> on pcib1
Oct 15 11:40:55 nstg19 /kernel: ti0: <Netgear GA620 1000baseSX Gigabit Ethernet> mem 0xfebf8000-0xfebfbfff irq 11 at device 1.0 on pci2
Oct 15 11:40:55 nstg19 /kernel: ti0: Ethernet address: 00:02:e3:00:19:1c
Oct 15 11:40:55 nstg19 /kernel: nvram probe: type: 54151332
Oct 15 11:40:55 nstg19 /kernel: nvram0: <Micro Memory 5415 NVRAM> mem 0xe0000000-0xefffffff,0xfebffc00-0xfebffc7f irq 11 at device 2.0 on pci2
Oct 15 11:40:55 nstg19 /kernel: nvram_attach: bus 2 slot 2 unit 0
Oct 15 11:40:55 nstg19 /kernel: nvram_attach: cmdmap 0xfebffc00 memmap: 0xe0000008
Oct 15 11:40:55 nstg19 /kernel: nvram_attach: regs 0xdb566c00 pregs: 0xfebffc00
Oct 15 11:40:55 nstg19 /kernel: nvram_attach: nvram 0xdb567000 pnvram: 0xe0000000
Oct 15 11:40:55 nstg19 /kernel: nvram0: <Micro Memory 5415 NVRAM> Rev-E
Oct 15 11:40:55 nstg19 /kernel: nvram0: conf regs
Oct 15 11:40:55 nstg19 /kernel: 0x00: 0x54151332 0x04: 0x4000117 0x08: 0xff000005, 0x0c: 0x4008
Oct 15 11:40:55 nstg19 /kernel: 0x10: 0xfebffc00 0x14: 0xe0000008 0x18: 0, 0x1c: 0
Oct 15 11:40:55 nstg19 /kernel: 0x20: 0 0x24: 0 0x28: 0, 0x2c: 0
Oct 15 11:40:55 nstg19 /kernel: 0x30: 0xfebfe000 0x34: 0 0x38: 0, 0x3c: 0x10b
Oct 15 11:40:55 nstg19 /kernel: 0x40: 0 0x44: 0 0x48: 0, 0x4c: 0
Oct 15 11:40:55 nstg19 /kernel: nvram0 cmd reg: 0x117
Oct 15 11:40:55 nstg19 /kernel: nvram0: memreg: 0xe0000008, 0xf0000000 memsize: 0x10000000
Oct 15 11:40:55 nstg19 /kernel: nvram0: regs 59, 73ab10, 0, deadbeef
Oct 15 11:40:55 nstg19 /kernel: nvram:0 Battery Status(0): 1-good 2-good 3-good 4-good
Oct 15 11:40:55 nstg19 /kernel: nvram0 CMOS: 39
Oct 15 11:40:55 nstg19 /kernel: nvram0 mem ctrl: 0
Oct 15 11:40:55 nstg19 /kernel: nvram0 Initialized
Oct 15 11:40:55 nstg19 /kernel: nvram0 CMOS: 39
Oct 15 11:40:55 nstg19 /kernel: nvram0 mem ctrl: 0
Oct 15 11:40:56 nstg19 /kernel: pcib2: <ServerWorks host to PCI bridge> on motherboard
Oct 15 11:40:56 nstg19 /kernel: pci3: <PCI bus> on pcib2
Oct 15 11:40:56 nstg19 /kernel: pcib3: <ServerWorks host to PCI bridge> on motherboard
Oct 15 11:40:56 nstg19 /kernel: pci4: <PCI bus> on pcib3
Oct 15 11:40:56 nstg19 /kernel: fdc0: <NEC 72065B or clone> at port 0x3f0-0x3f5,0x3f7 irq 6 drq 2 on isa0
Oct 15 11:40:56 nstg19 /kernel: fdc0: FIFO enabled, 8 bytes threshold
Oct 15 11:40:56 nstg19 /kernel: fd0: <1440-KB 3.5" drive> on fdc0 drive 0
Oct 15 11:40:56 nstg19 /kernel: atkbdc0: <Keyboard controller (i8042)> at port 0x60,0x64 on isa0
Oct 15 11:40:56 nstg19 /kernel: atkbd0: <AT Keyboard> flags 0x1 irq 1 on atkbdc0
Oct 15 11:40:56 nstg19 /kernel: kbd0 at atkbd0
Oct 15 11:40:56 nstg19 /kernel: psm0: <PS/2 Mouse> irq 12 on atkbdc0
Oct 15 11:40:56 nstg19 /kernel: psm0: model IntelliMouse, device ID 3
Oct 15 11:40:56 nstg19 /kernel: vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
Oct 15 11:40:56 nstg19 /kernel: sc0: <System console> at flags 0x100 on isa0
Oct 15 11:40:56 nstg19 /kernel: sc0: VGA <16 virtual consoles, flags=0x100>
Oct 15 11:40:56 nstg19 /kernel: sio0 at port 0x3f8-0x3ff irq 4 flags 0x10 on isa0
Oct 15 11:40:56 nstg19 /kernel: sio0: type 16550A, console
Oct 15 11:40:56 nstg19 /kernel: sio1 at port 0x2f8-0x2ff irq 3 on isa0
Oct 15 11:40:56 nstg19 /kernel: sio1: type 16550A
Oct 15 11:40:56 nstg19 /kernel: ppc0: <Parallel port> at port 0x378-0x37f irq 7 on isa0
Oct 15 11:40:56 nstg19 /kernel: ppc0: Generic chipset (ECP/PS2/NIBBLE) in COMPATIBLE mode
Oct 15 11:40:56 nstg19 /kernel: ppc0: FIFO with 16/16/8 bytes threshold
Oct 15 11:40:56 nstg19 /kernel: plip0: <PLIP network interface> on ppbus0
Oct 15 11:40:56 nstg19 /kernel: lpt0: <Printer> on ppbus0
Oct 15 11:40:56 nstg19 /kernel: lpt0: Interrupt-driven port
Oct 15 11:40:56 nstg19 /kernel: ppi0: <Parallel I/O> on ppbus0
Oct 15 11:40:56 nstg19 /kernel: acd0: CDROM <MATSHITA CR-177> at ata0-master using PIO4
Oct 15 11:40:56 nstg19 /kernel: xpt_config: xpt_create_path() failed for debug target 9:5:0, debugging disabled
Oct 15 11:40:56 nstg19 /kernel: Waiting 5 seconds for SCSI devices to settle
Oct 15 11:40:56 nstg19 /kernel: (targbh0:ahc0:0:-1:-1): Lun now enabled for target mode
Oct 15 11:40:56 nstg19 /kernel: (targbh1:ahc2:0:-1:-1): Lun now enabled for target mode
Oct 15 11:40:56 nstg19 /kernel: pass1 at ahc1 bus 0 target 6 lun 0
Oct 15 11:40:56 nstg19 /kernel: pass1: <SUPER GEM354 REV001 1.04> Fixed Processor SCSI-2 device
Oct 15 11:40:56 nstg19 /kernel: pass1: 3.300MB/s transfers
Oct 15 11:40:56 nstg19 /kernel: da0 at ahc1 bus 0 target 0 lun 0
Oct 15 11:40:56 nstg19 /kernel: da0: <IBM DDYS-T09170M S96H> Fixed Direct Access SCSI-3 device
Oct 15 11:40:56 nstg19 /kernel: da0: 160.000MB/s transfers (80.000MHz, offset 63, 16bit), Tagged Queueing Enabled
Oct 15 11:40:56 nstg19 /kernel: da0: 8748MB (17916240 512 byte sectors: 255H 63S/T 1115C)
Oct 15 11:40:56 nstg19 /kernel: Mounting root from ufs:/dev/da0s1a
Oct 15 11:40:56 nstg19 savecore: no core dump
Oct 15 11:40:56 nstg19 ntpd[195]: ntpd 4.0.99b Sat Apr 21 08:31:35 GMT 2001 (1)
Oct 15 11:40:56 nstg19 ntpd[195]: using kernel phase-lock loop 2040
Oct 15 11:40:56 nstg19 ntpd[195]: using kernel phase-lock loop 2041
Oct 15 11:40:57 nstg19 /kernel: ti0: gigabit link up
Oct 15 11:40:57 nstg19 lpd[248]: lpd startup: logging=0
Oct 15 11:45:32 nstg19 ntpd[195]: time reset 0.188260 s
Oct 15 11:45:32 nstg19 ntpd[195]: kernel pll status change 2041
Oct 15 11:49:25 nstg19 /kernel: Configuring Target Mode
Oct 15 11:49:25 nstg19 /kernel: (targda0:ahc0:0:0:0): Lun now enabled for target mode
Oct 15 14:11:32 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:32 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:32 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:32 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:32 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:32 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:32 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:32 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:32 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:32 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:32 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:32 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:32 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:32 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:32 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:32 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:32 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:32 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:32 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:32 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:32 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:32 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:32 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:32 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:32 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:32 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:32 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:32 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:32 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:32 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:32 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:32 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:32 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:32 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:32 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:32 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:32 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:32 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:33 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:33 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:33 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:33 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:33 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:33 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:33 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:33 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:33 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:33 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:33 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:33 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:33 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:33 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:33 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:33 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:33 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:33 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:33 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:33 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:33 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:33 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:33 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:33 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:33 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:33 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:33 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:33 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:33 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:33 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:33 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:33 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:33 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:33 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:33 nstg19 /kernel: ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
Oct 15 14:11:33 nstg19 /kernel: targda INOT error 76 sense 0 arg c
Oct 15 14:11:33 nstg19 /kernel: ahc0: Unexpected busfree while idle
Oct 15 14:11:33 nstg19 /kernel: SEQADDR == 0x1
Oct 15 14:11:33 nstg19 /kernel: Waiting (max 60 seconds) for system process `bufdaemon' to stop...stopped
Oct 15 14:11:33 nstg19 /kernel: Waiting (max 60 seconds) for system process `syncer' to stop...
Oct 15 14:14:12 nstg19 /kernel: stopped
Oct 15 14:14:12 nstg19 /kernel:
Oct 15 14:14:12 nstg19 /kernel: syncing disks... 15 14
Oct 15 14:14:12 nstg19 /kernel: done
Oct 15 14:14:12 nstg19 /kernel: pid 9944 (lhe), uid 47053: exited on signal 11
Oct 15 14:14:12 nstg19 /kernel: pid 10301 (lhe), uid 47053: exited on signal 11
Oct 15 14:14:12 nstg19 /kernel: pid 11751 (lhe), uid 47053: exited on signal 11
Oct 15 14:14:12 nstg19 /kernel: pid 188 (syslogd), uid 0: exited on signal 11
Oct 15 14:14:12 nstg19 /kernel: Uptime: 2h30m49s
Oct 15 14:14:12 nstg19 /kernel:
Oct 15 14:14:12 nstg19 /kernel: dumping to dev #da/0x20001, offset 128
Oct 15 14:14:12 nstg19 /kernel: dump 1023 1022 1021 1020 1019 1018 1017 1016 1015 1014 1013 1012 1011 1010 1009 1008 1007 1006 1005 1004 1003 1002 1001 1000 999 998 997 996 995 994 993 992 991 990 989 988 987 986 985 984 983 982 981 980 979 978 977 976 975 974 973 972 971 970 969 968 967 966 965 964 963 962 961 960 959 958 957 956 955 954 953 952 951 950 949 948 947 946 945 944 943 942 941 940 939 938 937 936 935 934 933 932 931 930 929 928 927 926 925 924 923 922 921 920 919 918 917 916 915 914 913 912 911 910 909 908 907 906 905 904 903 902 901 900 899 898 897 896 895 894 893 892 891 890 889 888 887 886 885 884 883 882 881 880 879 878 877 876 875 874 873 872 871 870 869 868 867 866 865 864 863 862 861 860 859 858 857 856 855 854 853 852 851 850 849 848 847 846 845 844 843 842 841 840 839 838 837 836 835 834 833 832 831 830 829 828 827 826 825 824 823 822 821 820 819 818 817 816 815 814 813 812 811 810 809 808 807 806 805 804 803 802 801 800 799 798 797 796 795 794 793 792 791 790 789 788 787 786 785 784 783 782 781 780 779 778 77
Oct 15 14:14:12 nstg19 /kernel: 5 774 773 772 771 770 769 768 767 766 765 764 763 762 761 760 759 758 757 756 755 754 753 752 751 750 749 748 747 746 745 744 743 742 741 740 739 738 737 736 735 734 733 732 731 730 729 728 727 726 725 724 723 722 721 720 719 718 717 716 715 714 713 712 711 710 709 708 707 706 705 704 703 702 701 700 699 698 697 696 695 694 693 692 691 690 689 688 687 686 685 684 683 682 681 680 679 678 677 676 675 674 673 672 671 670 669 668 667 666 665 664 663 662 661 660 659 658 657 656 655 654 653 652 651 650 649 648 647 646 645 644 643 642 641 640 639 638 637 636 635 634 633 632 631 630 629 628 627 626 625 624 623 622 621 620 619 618 617 616 615 614 613 612 611 610 609 608 607 606 605 604 603 602 601 600 599 598 597 596 595 594 593 592 591 590 589 588 587 586 585 584 583 582 581 580 579 578 577 576 575 574 573 572 571 570 569 568 567 566 565 564 563 562 561 560 559 558 557 556 555 554 553 552 551 550 549 548 547 546 545 544 543 542 541 540 539 538 537 536 535 534 533 532 531 530 529 528 527 526 525 524 523 522 5
Oct 15 14:14:12 nstg19 /kernel: 19 518 517 516 515 514 513 512 511 510 509 508 507 506 505 504 503 502 501 500 499 498 497 496 495 494 493 492 491 490 489 488 487 486 485 484 483 482 481 480 479 478 477 476 475 474 473 472 471 470 469 468 467 466 465 464 463 462 461 460 459 458 457 456 455 454 453 452 451 450 449 448 447 446 445 444 443 442 441 440 439 438 437 436 435 434 433 432 431 430 429 428 427 426 425 424 423 422 421 420 419 418 417 416 415 414 413 412 411 410 409 408 407 406 405 404 403 402 401 400 399 398 397 396 395 394 393 392 391 390 389 388 387 386 385 384 383 382 381 380 379 378 377 376 375 374 373 372 371 370 369 368 367 366 365 364 363 362 361 360 359 358 357 356 355 354 353 352 351 350 349 348 347 346 345 344 343 342 341 340 339 338 337 336 335 334 333 332 331 330 329 328 327 326 325 324 323 322 321 320 319 318 317 316 315 314 313 312 311 310 309 308 307 306 305 304 303 302 301 300 299 298 297 296 295 294 293 292 291 290 289 288 287 286 285 284 283 282 281 280 279 278 277 276 275 274 273 272 271 270 269 268 267 266
Oct 15 14:14:12 nstg19 /kernel: 263 262 261 260 259 258 257 256 255 254 253 252 251 250 249 248 247 246 245 244 243 242 241 240 239 238 237 236 235 234 233 232 231 230 229 228 227 226 225 224 223 222 221 220 219 218 217 216 215 214 213 212 211 210 209 208 207 206 205 204 203 202 201 200 199 198 197 196 195 194 193 192 191 190 189 188 187 186 185 184 183 182 181 180 179 178 177 176 175 174 173 172 171 170 169 168 167 166 165 164 163 162 161 160 159 158 157 156 155 154 153 152 151 150 149 148 147 146 145 144 143 142 141 140 139 138 137 136 135 134 133 132 131 130 129 128 127 126 125 124 123 122 121 120 119 118 117 116 115 114 113 112 111 110 109 108 107 106 105 104 103 102 101 100 99 98 97 96 95 94 93 92 91 90 89 88 87 86 85 84 83 82 81 80 79 78 77 76 75 74 73 72 71 70 69 68 67 66 65 64 63 62 61 60 59 58 57 56 55 54 53 52 51 50 49 48 47 46 45 44 43 42 41 40 39 38 37 36 35 34 33 32 31 30 29 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 0 succeeded
Oct 15 14:14:12 nstg19 /kernel: Automatic reboot in 15 seconds - press a key on the console to abort
Oct 15 14:14:12 nstg19 /kernel: Rebooting...
=====================================
ddb output on serial console
=====================================
Configuring Target Mode
(targda0:ahc0:0:0:0): Lun now enabled for target mode
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
ahc0: Bus Device Reset Received on A:7. 0 SCBs aborted
targda INOT error 76 sense 0 arg c
ahc0: Unexpected busfree while idle
SEQADDR == 0x1
Debugger("manual escape to debugger")
Stopped at Debugger+0x34: movb $0,in_Debugger.396
db> tr where
No such command
db> trace
Debugger(c03ec6a9) at Debugger+0x34
scgetc(c3e9e800,2,c3e97000,c04674c0,c3e97000) at scgetc+0x38e
sckbdevent(c04674c0,0,c3e9e800,c3e97000,c3e97000) at sckbdevent+0x1b9
atkbd_intr(c04674c0,0,f1ad4e74,c0321c4f,c04674c0) at atkbd_intr+0x22
atkbd_isa_intr(c04674c0,660b00,10,f1ad0010,c0330010) at atkbd_isa_intr+0x18
Xresume1() at Xresume1+0x2b
--- interrupt, eip = 0xc0294491, esp = 0xf1ad4e54, ebp = 0xf1ad4e74 ---
ti_rxeof(c3e97000,c3e92e20,400900,f1ad4ea0,c033bee5) at ti_rxeof+0x2bd
ti_intr(c3e97000,660a00,0,c0344e20,f1ad4f5c) at ti_intr+0x28
intr_mux(c2057160,400100,400010,c3e90010,10) at intr_mux+0x1d
Xresume11() at Xresume11+0x2b
--- interrupt, eip = 0xc0334024, esp = 0xf1ad4ee8, ebp = 0xf1ad4f5c ---
rtcintr(0,c3e90010,f1ad0010,c0320010,0) at rtcintr
doreti_swi() at doreti_swi+0xf
db> ps
pid proc addr uid ppid pgrp flag stat wmesg wchan cmd
1982 ecbb15e0 f1a14000 0 1 1 004084 3 nanslp c04561c0 perl
11751 f1aa5260 f1ba7000 47053 9366 9365 000086 3 nanslp c04561c0 lhe
11750 f1aa5400 f1ba5000 47053 9366 9365 000086 3 nanslp c04561c0 lhe
11749 f1aa55a0 f1ba3000 47053 9366 9365 000086 2 lhe
11748 f1aa5740 f1ba0000 47053 9366 9365 000086 2 lhe
10301 f1aa58e0 f1b9e000 47053 9366 9365 000086 3 nanslp c04561c0 lhe
10300 f1aa65e0 f1ad6000 47053 9366 9365 000086 3 nanslp c04561c0 lhe
10299 f1aa6100 f1af1000 47053 9366 9365 000086 2 lhe
10298 f1aa62a0 f1ae1000 47053 9366 9365 000086 2 lhe
9944 f1aa5c20 f1b24000 47053 9366 9365 000086 3 nanslp c04561c0 lhe
9943 f1aa6440 f1ada000 47053 9366 9365 000086 3 nanslp c04561c0 lhe
9942 f1aa5a80 f1b51000 47053 9366 9365 000086 2 lhe
9941 f1aa5dc0 f1b20000 47053 9366 9365 000086 2 lhe
9369 f1aa5f60 f1af4000 47053 9366 9365 000086 3 nanslp c04561c0 lhe
9368 f1aa6780 f1ad3000 47053 9366 9365 000006 2 lhe
9367 f1aa6920 f1ac1000 47053 9366 9365 2000086 3 pause c40d0000 lhe
9366 f1aa6ac0 f1abe000 47053 9365 9365 000086 3 poll c046fc54 lhe
9365 f1aa6c60 f1aae000 47053 1438 9365 005086 2 lhe
1438 f1aa6e00 f1aa8000 47053 1437 1438 004086 3 ttyin c205c030 sh
1437 ecbadea0 f1a9e000 0 243 1437 004084 3 select c046fc54 rlogind
308 ecbae040 f1a94000 0 1 1 004084 3 siodcd c4061c00 getty
307 ecbae1e0 f1a91000 0 1 307 004086 2 getty
306 ecbae380 f1a8e000 0 1 306 004086 3 ttyin c4063210 getty
305 ecbae520 f1a8b000 0 1 305 004086 3 ttyin c4060210 getty
304 ecbae6c0 f1a88000 0 1 304 004086 3 ttyin c4060310 getty
303 ecbae860 f1a85000 0 1 303 004086 3 ttyin c4060410 getty
302 ecbaeba0 f1a7e000 0 1 302 004086 3 ttyin c4061110 getty
301 ecbaea00 f1a82000 0 1 301 004086 3 ttyin c4082310 getty
300 ecbaed40 f1a7b000 0 1 300 004086 3 ttyin c4082610 getty
298 ecbb0c20 f1a32000 0 1 298 004086 2 getty
293 ecbb1440 f1a17000 65534 1 6 004186 3 nanslp c04561c0 rmonitor
254 ecbaeee0 f1a72000 0 1 254 000084 3 select c046fc54 usbd
251 ecbaf080 f1a6f000 0 1 251 000184 3 select c046fc54 sendmail
248 ecbaf220 f1a6b000 0 1 248 000084 3 select c046fc54 lpd
245 ecbaf3c0 f1a68000 0 1 245 000084 3 nanslp c04561c0 cron
243 ecbaf560 f1a61000 0 1 243 000084 3 select c046fc54 inetd
228 ecbafd80 f1a4f000 0 1 228 000084 3 select c046fc54 amd
223 ecbaf700 f1a5b000 0 1 218 000084 3 nfsidl c047460c nfsiod
222 ecbaf8a0 f1a58000 0 1 218 000084 3 nfsidl c0474608 nfsiod
221 ecbafa40 f1a55000 0 1 218 000084 3 nfsidl c0474604 nfsiod
220 ecbafbe0 f1a52000 0 1 218 000084 3 nfsidl c0474600 nfsiod
216 ecbaff20 f1a4c000 0 1 216 000084 3 select c046fc54 rpc.statd
213 ecbb00c0 f1a48000 0 208 208 000084 3 nfsd c3fbec00 nfsd
212 ecbb0260 f1a45000 0 208 208 000084 3 nfsd c3fb0a00 nfsd
211 ecbb0400 f1a42000 0 208 208 000084 3 nfsd c3f9f400 nfsd
210 ecbb05a0 f1a3e000 0 208 208 000084 3 nfsd c3fbea00 nfsd
208 ecbb0740 f1a3b000 0 1 208 000084 3 accept ebb691b6 nfsd
206 ecbb08e0 f1a38000 0 1 206 000084 3 select c046fc54 mountd
200 ecbb0a80 f1a35000 0 1 200 000084 3 select c046fc54 ypbind
197 ecbb0dc0 f1a2f000 1 1 197 000184 2 portmap
195 ecbb0f60 f1a2c000 0 1 195 000084 3 select c046fc54 ntpd
188 ecbb1100 f1a22000 0 1 188 000084 3 select c046fc54 syslogd
159 ecbb12a0 f1a1e000 0 1 159 000084 3 select c046fc54 dhclient
5 ecbb1780 ecbbe000 0 0 0 000204 3 syncer c046fbe8 syncer
4 ecbb1920 ecbbc000 0 0 0 100204 3 psleep c0456340 bufdaemon
3 ecbb1ac0 ecbba000 0 0 0 000204 3 psleep c04653e0 vmdaemon
2 ecbb1c60 ecbb8000 0 0 0 100204 3 psleep c0440f78 pagedaemon
1 ecbb1e00 ecbb6000 0 0 1 004284 3 wait ecbb1e00 init
0 c046efc0 c050d000 0 0 0 000204 3 sched c046efc0 swapper
db> call boot(0x100)
Waiting (max 60 seconds) for system process `bufdaemon' to stop...
FreeBSD/i386 s(nstg19.researcht.bell-labs.com) o(ttyd0)
logpin: ped
Waiting (max 60 seconds) for system process `syncer' to stop...stopped
syncing disks... 15 14
done
Uptime: 2h30m49s
dumping to dev #da/0x20001, offset 128
=====================================
process information from gdb
=====================================
To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-hackers" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?200110192052.QAA20502>