From owner-freebsd-current Sun Jan 9 4:49:27 2000 Delivered-To: freebsd-current@freebsd.org Received: from relativity.student.utwente.nl (wit389306.student.utwente.nl [130.89.234.166]) by hub.freebsd.org (Postfix) with ESMTP id 15CBF15137 for ; Sun, 9 Jan 2000 04:49:18 -0800 (PST) (envelope-from djb@Wit389306.student.utwente.nl) Received: by relativity.student.utwente.nl (Postfix, from userid 1001) id 75F851DA7; Sun, 9 Jan 2000 13:49:16 +0100 (CET) Date: Sun, 9 Jan 2000 13:49:16 +0100 From: "Dave J. Boers" To: freebsd-current@freebsd.org Subject: Strange SCSI related system hang Message-ID: <20000109134916.A382@relativity.student.utwente.nl> Reply-To: djb@relativity.student.utwente.nl Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii X-Mailer: Mutt 1.0i Sender: owner-freebsd-current@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.ORG Hi all, This morning I had a very strange (at least I've never seen it before) SCSI related system hang. The system simply stopped responding at 9:30:03 am this morning. I found it in this state at 13:20. It had been hanging _hard_. No response to console, serial terminal or network. After a hard reset the system came back online normally and is working normally again. Note that the machine had an uptime of 4 days, 14 hours before the problem occured and it never happened before. Could this be a hardware problem? ====> uname -a: FreeBSD relativity.student.utwente.nl 4.0-CURRENT FreeBSD 4.0-CURRENT #0: Thu Dec 30 21:42:21 CET 1999 root@relativity.student.utwente.nl:/usr/src/sys/compile/RELATIVITY3 i386 ====> Here's the relevant system log messages: Note that all these messages occur at xx:30:00 or xx:30:01. That's probably related to a cron job which runs every half hour and copies some files (about 20 Mb) from my IDE disk to my SCSI disk. When the system is idle, there's usually no other SCSI activity. Jan 9 04:30:01 relativity /kernel: Unexpected busfree. LASTPHASE == 0xa0 Jan 9 04:30:01 relativity /kernel: SEQADDR == 0x151 Jan 9 04:30:01 relativity /kernel: Unexpected busfree. LASTPHASE == 0xa0 Jan 9 04:30:01 relativity /kernel: SEQADDR == 0x151 Jan 9 04:30:01 relativity /kernel: Unexpected busfree. LASTPHASE == 0xa0 Jan 9 04:30:01 relativity /kernel: SEQADDR == 0x151 Jan 9 04:30:01 relativity /kernel: Unexpected busfree. LASTPHASE == 0xa0 Jan 9 04:30:01 relativity /kernel: SEQADDR == 0x151 Jan 9 04:30:01 relativity /kernel: Unexpected busfree. LASTPHASE == 0xa0 Jan 9 04:30:01 relativity /kernel: SEQADDR == 0x151 Jan 9 04:30:01 relativity /kernel: ahc0:A:0: no active SCB for reconnecting target - issuing BUS DEVICE RESET Jan 9 06:30:00 relativity /kernel: Unexpected busfree. LASTPHASE == 0xa0 Jan 9 06:30:00 relativity /kernel: SEQADDR == 0x151 Jan 9 07:30:00 relativity /kernel: Unexpected busfree. LASTPHASE == 0xa0 Jan 9 07:30:00 relativity /kernel: SEQADDR == 0x151 Jan 9 07:30:00 relativity /kernel: Unexpected busfree. LASTPHASE == 0xa0 Jan 9 07:30:00 relativity /kernel: SEQADDR == 0x151 Jan 9 07:30:01 relativity /kernel: Unexpected busfree. LASTPHASE == 0xa0 Jan 9 07:30:01 relativity /kernel: SEQADDR == 0x151 Jan 9 07:30:01 relativity /kernel: Unexpected busfree. LASTPHASE == 0xa0 Jan 9 07:30:01 relativity /kernel: SEQADDR == 0x151 Jan 9 07:30:01 relativity /kernel: ahc0:A:0: no active SCB for reconnecting target - issuing BUS DEVICE RESET Jan 9 07:30:01 relativity /kernel: SAVED_TCL == 0x0, ARG_1 == 0x9, SEQ_FLAGS == 0x0 Jan 9 07:30:01 relativity /kernel: ahc0: Bus Device Reset on A:0. 1 SCBs aborted Jan 9 08:30:01 relativity /kernel: Unexpected busfree. LASTPHASE == 0xa0 Jan 9 08:30:01 relativity /kernel: SEQADDR == 0x151 Jan 9 08:30:01 relativity /kernel: Unexpected busfree. LASTPHASE == 0xa0 Jan 9 08:30:01 relativity /kernel: SEQADDR == 0x151 Jan 9 09:30:03 relativity /kernel: (da0:ahc0:0:0:0): Invalidating pack ====> Here's my complete dmesg output: Copyright (c) 1992-1999 The FreeBSD Project. Copyright (c) 1982, 1986, 1989, 1991, 1993 The Regents of the University of California. All rights reserved. FreeBSD 4.0-CURRENT #0: Thu Dec 30 21:42:21 CET 1999 root@relativity.student.utwente.nl:/usr/src/sys/compile/RELATIVITY3 Timecounter "i8254" frequency 1193182 Hz CPU: Pentium II/Celeron (450.00-MHz 686-class CPU) Origin = "GenuineIntel" Id = 0x665 Stepping = 5 Features=0x183fbff real memory = 134217728 (131072K bytes) avail memory = 127238144 (124256K bytes) Programming 24 pins in IOAPIC #0 FreeBSD/SMP: Multiprocessor motherboard cpu0 (BSP): apic id: 0, version: 0x00040011, at 0xfee00000 cpu1 (AP): apic id: 1, version: 0x00040011, at 0xfee00000 io0 (APIC): apic id: 2, version: 0x00170011, at 0xfec00000 Preloaded elf kernel "kernel" at 0xc02d2000. Preloaded elf module "vesa.ko" at 0xc02d209c. VESA: v3.0, 7936k memory, flags:0x1, mode table:0xc02cf102 (1000022) VESA: NVidia Pentium Pro MTRR support enabled npx0: on motherboard npx0: INT 16 interface pcib0: on motherboard pci0: on pcib0 pcib1: at device 1.0 on pci0 pci1: on pcib1 vga-pci0: irq 16 at device 0.0 on pci1 isab0: at device 7.0 on pci0 isa0: on isab0 ata-pci0: at device 7.1 on pci0 ata-pci0: Busmastering DMA supported pci0: Intel 82371AB/EB (PIIX4) USB controller (vendor=0x8086, dev=0x7112) at 7.2 intpm0: at device 7.3 on pci0 intpm0: I/O mapped 5000 intpm0: intr IRQ 9 enabled revision 0 smbus0: on intsmb0 smb0: on smbus0 intpm0: PM I/O mapped 4000 ed0: irq 19 at device 9.0 on pci0 ed0: address 00:20:18:2d:d5:2b, type NE2000 (16 bit) ahc0: irq 18 at device 11.0 on pci0 ahc0: aic7860 Single Channel A, SCSI Id=7, 3/255 SCBs pci0: unknown card (vendor=0x10b7, dev=0x9055) at 13.0 irq 17 ata-pci1: irq 18 at device 19.0 on pci0 ata-pci1: Busmastering DMA supported ata2 at 0xb000 irq 11 on ata-pci1 ata-pci2: irq 18 at device 19.1 on pci0 ata-pci2: Busmastering DMA supported fdc0: at port 0x3f0-0x3f7 irq 6 drq 2 on isa0 fdc0: FIFO enabled, 8 bytes threshold fd0: <1440-KB 3.5" drive> on fdc0 drive 0 atkbdc0: at port 0x60-0x6f on isa0 atkbd0: irq 1 on atkbdc0 psm0: irq 12 on atkbdc0 psm0: model Generic PS/2 mouse, device ID 0 vga0: at port 0x3b0-0x3df iomem 0xa0000-0xbffff on isa0 sc0: on isa0 sc0: VGA <16 virtual consoles, flags=0x200> sio0 at port 0x3f8-0x3ff irq 4 flags 0x10 on isa0 sio0: type 16550A sio1 at port 0x2f8-0x2ff irq 3 on isa0 sio1: type 16550A sio2: not probed (disabled) sio3: not probed (disabled) ppc0 at port 0x378-0x37f irq 7 flags 0x40 on isa0 ppc0: Generic chipset (NIBBLE-only) in COMPATIBLE mode ppb0: IEEE1284 device found /NIBBLE Probing for PnP devices on ppbus0: ppbus0: HP ENHANCED PCL5,PJL plip0: on ppbus 0 lpt0: on ppbus 0 lpt0: Interrupt-driven port ppi0: on ppbus 0 sbc0: at port 0x220-0x22f,0x330-0x331,0x388-0x38b irq 5 drq 1,5 on isa0 sbc0: setting card to irq 5, drq 1, 5 pcm0: on sbc0 unknown0: at port 0x168-0x16f,0x36e-0x36f irq 10 on isa0 unknown1: at port 0x100 on isa0 unknown2: at port 0x200-0x207 on isa0 unknown: can't assign resources unknown3: at port 0-0xf,0x81-0x83,0x87,0x89-0x8b,0x8f-0x91,0xc0-0xdf drq 4 on isa0 unknown4: at port 0x40-0x43 irq 0 on isa0 unknown5: at port 0x70-0x71 irq 8 on isa0 unknown: can't assign resources unknown: can't assign resources unknown6: at port 0xf0-0xff irq 13 on isa0 unknown7: at iomem 0-0x9ffff,0xfffe0000-0xffffffff,0xfec00000-0xfec0ffff,0xfee00000-0xfee0ffff,0x100000-0x7ffffff on isa0 unknown8: at iomem 0xf0000-0xf3fff,0xf4000-0xf7fff,0xf8000-0xfffff,0xce800-0xcffff on isa0 unknown: can't assign resources unknown: can't assign resources unknown9: at port 0x208-0x20f on isa0 unknown: can't assign resources unknown: can't assign resources unknown: can't assign resources unknown: can't assign resources APIC_IO: Testing 8254 interrupt delivery APIC_IO: routing 8254 via pin 2 IP packet filtering initialized, divert enabled, rule-based forwarding disabled, logging limited to 100 packets/entry by default SMP: AP CPU #1 Launched! ad0: ATA-4 disk at ata2 as master ad0: 17206MB (35239680 sectors), 34960 cyls, 16 heads, 63 S/T, 512 B/S ad0: 16 secs/int, 32 depth queue, UDMA66 Waiting 5 seconds for SCSI devices to settle sa0 at ahc0 bus 0 target 4 lun 0 sa0: Removable Sequential Access SCSI-2 device sa0: 3.300MB/s transfers Mounting root from ufs:/dev/ad0s1a cd1 at ahc0 bus 0 target 2 lun 0 cd1: Removable CD-ROM SCSI-2 device cd1: 10.000MB/s transfers (10.000MHz, offset 8) cd1: cd present [309750 x 2048 byte records] cd0 at ahc0 bus 0 target 1 lun 0 cd0: Removable CD-ROM SCSI-2 device cd0: 10.000MB/s transfers (10.000MHz, offset 8) cd0: Attempt to query device size failed: NOT READY, Medium not present WARNING: / was not properly dismounted da0 at ahc0 bus 0 target 0 lun 0 da0: Fixed Direct Access SCSI-2 device da0: 20.000MB/s transfers (20.000MHz, offset 15) da0: 4157MB (8515173 512 byte sectors: 255H 63S/T 530C) da1 at ahc0 bus 0 target 5 lun 0 da1: Removable Direct Access SCSI-2 device da1: 3.300MB/s transfers da1: 96MB (196608 512 byte sectors: 64H 32S/T 96C) pci0: Intel 82371AB/EB (PIIX4) USB controller (vendor=0x8086, dev=0x7112) at 7.2 xl0: <3Com 3c905B-TX Fast Etherlink XL> irq 17 at device 13.0 on pci0 xl0: Ethernet address: 00:10:5a:65:33:80 miibus0: on xl0 xlphy0: <3Com internal media interface> on miibus0 xlphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto If you need any more information, please let me know. I can also provide an account on the machine. Regards, Dave Boers. -- djb@relativity.student.utwente.nl To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-current" in the body of the message