Problems with ABIT BH6 and AHA-2940UW
Mattias Sandgren
sagge at acc.umu.se
Thu Dec 10 06:44:55 PST 1998
Hello to everyone,
I just recently upgraded from a 430TX/P166 system to a 440BX/300A system
which is not overclocked and uses an Abit BH6 motherboard. After the upgrade I
discovered a problem with the bus being reset over and over but that is not so
troublesome in that I know how to avoid it. However, the problem is very
strange as I have the same hardware except of course the power supply, CPU and
motherboard. The physical SCSI bus itself doesn't seem to be the problem as
I've tried every conceivable combination there is to hook it up. Before the
upgrade I was running kernel 2.0.34 and when the problem showed up after the
upgrade I tried 2.0.36 (aic7xxx 5.1.4) but there is no difference.
This is /proc/scsi/scsi:
Attached devices:
Host: scsi0 Channel: 00 Id: 00 Lun: 00
Vendor: QUANTUM Model: FIREBALL ST4.3S Rev: 0F0C
Type: Direct-Access ANSI SCSI revision: 02
Host: scsi0 Channel: 00 Id: 01 Lun: 00
Vendor: IBMAS400 Model: DFHSS2W Rev: 1111
Type: Direct-Access ANSI SCSI revision: 02
Host: scsi0 Channel: 00 Id: 02 Lun: 00
Vendor: PIONEER Model: CD-ROM DR-U10X Rev: 1.07
Type: CD-ROM ANSI SCSI revision: 02
Host: scsi0 Channel: 00 Id: 03 Lun: 00
Vendor: YAMAHA Model: CRW4260 Rev: 1.0h
Type: CD-ROM ANSI SCSI revision: 02
The Fireball ST, Pioneer CD-ROM and the Yamaha CD-R are connected to the
narrow internal connector and I've tried alternating which device terminates
this end of the bus and the order of the devices. The IBM DFHS terminates the
wide internal end of the bus and the controller termination is not set to
auto-termination but even if it is it won't make any difference.
Now, what causes the problem? I use cdrdao to copy audio CD's and in order
to write audio data to CD you have to:
a) read the audio data as a wav-files.
b) create a Table of contents file.
Reading the audio data with cdda2wav works without a problem and the Pioneer
extracts audio at 10x (1.5MB/s). When cdrdao creates the toc-file with the
Pioneer CD-ROM I get 'Unexpected busfree' in the /var/log/syslog and all
commands on the bus timeout whereafter the bus resets and the events repeat
unless I was quick to CTRL-C cdrdao. From /var/log/syslog:
Dec 1 14:55:07 lobo kernel: (scsi0:0:2:-1) Unexpected busfree, LASTPHASE = 0x80, SEQADDR = 0x11a
Dec 1 14:55:31 lobo kernel: scsi : aborting command due to timeout : pid 62999, scsi0, channel 0, id 1, lun 0 Read (10) 00 00 41 13 6b 00 00 40 00
and so on... until
Dec 1 14:56:09 lobo kernel: SCSI host 0 abort (pid 62969) timed out - resetting
Dec 1 14:56:09 lobo kernel: SCSI bus is being reset for host 0 channel 0.
Lowering the busspeed doesn't help,
neither does running everything async. There seems to be a device
incompatibility problem. I've tried to find firmware updates for the Pioneer
CD-ROM but that seems more than difficult.
I've attached parts of my /var/log/syslog to this mail and also the output
from /proc/scsi/aic7xxx/0.
--
Mattias Sandgren
Umeå, Sweden
mailto:sandgren at cryogen.com
http://www.cryogen.com/sandgren
-------------- next part --------------
Dec 1 14:52:10 lobo kernel: scsi : aborting command due to timeout : pid 60349, scsi0, channel 0, id 2, lun 0 UNKNOWN(0x45) 00 00 02 f4 d5 00 00 01 00
Dec 1 14:55:07 lobo kernel: (scsi0:0:2:-1) Unexpected busfree, LASTPHASE = 0x80, SEQADDR = 0x11a
Dec 1 14:55:31 lobo kernel: scsi : aborting command due to timeout : pid 62999, scsi0, channel 0, id 1, lun 0 Read (10) 00 00 41 13 6b 00 00 40 00
Dec 1 14:55:31 lobo kernel: scsi : aborting command due to timeout : pid 62998, scsi0, channel 0, id 0, lun 0 Read (10) 00 00 7f 18 08 00 00 58 00
Dec 1 14:55:33 lobo kernel: scsi : aborting command due to timeout : pid 63000, scsi0, channel 0, id 1, lun 0 Write (6) 19 8c 68 04 00
Dec 1 14:55:38 lobo kernel: scsi : aborting command due to timeout : pid 63001, scsi0, channel 0, id 1, lun 0 Write (10) 00 00 40 2a 0b 00 00 08 00
Dec 1 14:55:51 lobo kernel: scsi : aborting command due to timeout : pid 62999, scsi0, channel 0, id 1, lun 0 Read (10) 00 00 41 13 6b 00 00 40 00
Dec 1 14:55:51 lobo kernel: scsi : aborting command due to timeout : pid 62998, scsi0, channel 0, id 0, lun 0 Read (10) 00 00 7f 18 08 00 00 58 00
Dec 1 14:55:53 lobo kernel: scsi : aborting command due to timeout : pid 63000, scsi0, channel 0, id 1, lun 0 Write (6) 19 8c 68 04 00
Dec 1 14:55:58 lobo kernel: scsi : aborting command due to timeout : pid 63001, scsi0, channel 0, id 1, lun 0 Write (10) 00 00 40 2a 0b 00 00 08 00
Dec 1 14:55:59 lobo kernel: scsi : aborting command due to timeout : pid 63002, scsi0, channel 0, id 1, lun 0 Read (6) 00 02 3f 02 00
Dec 1 14:56:06 lobo kernel: scsi : aborting command due to timeout : pid 63003, scsi0, channel 0, id 1, lun 0 Read (6) 00 14 69 08 00
Dec 1 14:56:07 lobo kernel: scsi : aborting command due to timeout : pid 62969, scsi0, channel 0, id 2, lun 0 UNKNOWN(0x42) 00 40 01 00 00 00 00 30 00
Dec 1 14:56:09 lobo kernel: SCSI host 0 abort (pid 62969) timed out - resetting
Dec 1 14:56:09 lobo kernel: SCSI bus is being reset for host 0 channel 0.
Dec 1 14:56:12 lobo kernel: (scsi0:0:0:0) Synchronous at 20.0MHz, offset 15.
Dec 1 14:56:12 lobo kernel: (scsi0:0:1:0) Using wide (16 bit) transfers.
Dec 1 14:56:12 lobo kernel: (scsi0:0:1:0) Synchronous at 10.0MHz, offset 8.
Dec 1 14:56:12 lobo kernel: (scsi0:0:2:0) Synchronous at 10.0MHz, offset 15.
Dec 1 14:56:35 lobo kernel: scsi : aborting command due to timeout : pid 63495, scsi0, channel 0, id 1, lun 0 Read (10) 00 00 41 16 d3 00 00 58 00
Dec 1 14:56:35 lobo kernel: scsi : aborting command due to timeout : pid 63494, scsi0, channel 0, id 0, lun 0 Read (10) 00 00 7f 1b 68 00 00 40 00
Dec 1 14:56:37 lobo kernel: scsi : aborting command due to timeout : pid 63496, scsi0, channel 0, id 1, lun 0 Read (6) 00 40 99 02 00
Dec 1 14:56:41 lobo kernel: scsi : aborting command due to timeout : pid 63497, scsi0, channel 0, id 1, lun 0 Read (6) 08 6f 5a 02 00
Dec 1 14:56:55 lobo kernel: scsi : aborting command due to timeout : pid 63495, scsi0, channel 0, id 1, lun 0 Read (10) 00 00 41 16 d3 00 00 58 00
Dec 1 14:56:55 lobo kernel: scsi : aborting command due to timeout : pid 63498, scsi0, channel 0, id 1, lun 0 Read (6) 1a 29 3c 02 00
Dec 1 14:56:55 lobo kernel: scsi : aborting command due to timeout : pid 63494, scsi0, channel 0, id 0, lun 0 Read (10) 00 00 7f 1b 68 00 00 40 00
Dec 1 14:56:57 lobo kernel: scsi : aborting command due to timeout : pid 63496, scsi0, channel 0, id 1, lun 0 Read (6) 00 40 99 02 00
Dec 1 14:57:01 lobo kernel: scsi : aborting command due to timeout : pid 63497, scsi0, channel 0, id 1, lun 0 Read (6) 08 6f 5a 02 00
Dec 1 14:57:13 lobo kernel: scsi : aborting command due to timeout : pid 63366, scsi0, channel 0, id 2, lun 0 UNKNOWN(0x45) 00 00 03 6a a2 00 00 01 00
Dec 1 14:57:14 lobo kernel: SCSI host 0 abort (pid 63366) timed out - resetting
Dec 1 14:57:14 lobo kernel: SCSI bus is being reset for host 0 channel 0.
Dec 1 14:57:17 lobo kernel: (scsi0:0:0:0) Synchronous at 20.0MHz, offset 15.
Dec 1 14:57:17 lobo kernel: (scsi0:0:1:0) Using wide (16 bit) transfers.
Dec 1 14:57:17 lobo kernel: (scsi0:0:1:0) Synchronous at 10.0MHz, offset 8.
Dec 1 14:57:17 lobo kernel: (scsi0:0:2:0) Synchronous at 10.0MHz, offset 15.
Dec 1 14:59:14 lobo kernel: (scsi0:0:2:-1) Unexpected busfree, LASTPHASE = 0x80, SEQADDR = 0x151
Dec 1 14:59:18 lobo kernel: (scsi0:0:2:-1) Unexpected busfree, LASTPHASE = 0x80, SEQADDR = 0x151
Dec 1 15:00:08 lobo kernel: scsi : aborting command due to timeout : pid 64036, scsi0, channel 0, id 1, lun 0 Write (6) 1a b2 80 02 00
Dec 1 15:00:08 lobo kernel: scsi : aborting command due to timeout : pid 64037, scsi0, channel 0, id 1, lun 0 Write (6) 1a b2 8e 02 00
Dec 1 15:00:08 lobo kernel: scsi : aborting command due to timeout : pid 64038, scsi0, channel 0, id 1, lun 0 Write (6) 1a b2 9a 02 00
Dec 1 15:00:13 lobo kernel: scsi : aborting command due to timeout : pid 64039, scsi0, channel 0, id 1, lun 0 Write (6) 19 8c 68 04 00
Dec 1 15:00:24 lobo kernel: scsi : aborting command due to timeout : pid 64035, scsi0, channel 0, id 2, lun 0 UNKNOWN(0x42) 00 40 01 00 00 00 00 30 00
Dec 1 15:00:25 lobo kernel: SCSI host 0 abort (pid 64035) timed out - resetting
Dec 1 15:00:25 lobo kernel: SCSI bus is being reset for host 0 channel 0.
Dec 1 15:00:28 lobo kernel: (scsi0:0:1:0) Using wide (16 bit) transfers.
Dec 1 15:00:28 lobo kernel: (scsi0:0:1:0) Synchronous at 10.0MHz, offset 8.
Dec 1 15:00:28 lobo kernel: (scsi0:0:2:0) Synchronous at 10.0MHz, offset 15.
Dec 1 15:00:36 lobo kernel: (scsi0:0:2:-1) Unexpected busfree, LASTPHASE = 0x80, SEQADDR = 0x151
Dec 1 15:00:41 lobo kernel: (scsi0:0:2:-1) Unexpected busfree, LASTPHASE = 0x80, SEQADDR = 0x151
Dec 1 15:00:44 lobo kernel: (scsi0:0:0:0) Synchronous at 20.0MHz, offset 15.
Dec 1 15:00:46 lobo kernel: (scsi0:0:2:-1) Unexpected busfree, LASTPHASE = 0x80, SEQADDR = 0x151
Dec 1 15:01:22 lobo last message repeated 4 times
Dec 1 15:02:29 lobo kernel: scsi : aborting command due to timeout : pid 64656, scsi0, channel 0, id 2, lun 0 UNKNOWN(0x45) 00 00 01 94 d9 00 00 01 00
Dec 1 15:02:40 lobo kernel: (scsi0:0:2:-1) Unexpected busfree, LASTPHASE = 0x80, SEQADDR = 0x151
Dec 1 15:02:51 lobo kernel: (scsi0:0:2:-1) Unexpected busfree, LASTPHASE = 0x80, SEQADDR = 0x151
Dec 1 15:03:20 lobo kernel: scsi : aborting command due to timeout : pid 65008, scsi0, channel 0, id 1, lun 0 Read (6) 16 ce 5c 02 00
Dec 1 15:03:24 lobo kernel: scsi : aborting command due to timeout : pid 65009, scsi0, channel 0, id 1, lun 0 Write (6) 1a b2 84 02 00
Dec 1 15:03:24 lobo kernel: scsi : aborting command due to timeout : pid 65010, scsi0, channel 0, id 1, lun 0 Write (6) 1a b2 90 02 00
Dec 1 15:03:24 lobo kernel: scsi : aborting command due to timeout : pid 65011, scsi0, channel 0, id 1, lun 0 Write (6) 1a b2 9e 02 00
Dec 1 15:03:29 lobo kernel: scsi : aborting command due to timeout : pid 65012, scsi0, channel 0, id 1, lun 0 Write (6) 00 40 71 02 00
Dec 1 15:03:29 lobo kernel: scsi : aborting command due to timeout : pid 65013, scsi0, channel 0, id 1, lun 0 Write (6) 19 8c 68 02 00
Dec 1 15:03:34 lobo kernel: scsi : aborting command due to timeout : pid 65014, scsi0, channel 0, id 1, lun 0 Write (6) 1a b2 92 02 00
Dec 1 15:03:40 lobo kernel: scsi : aborting command due to timeout : pid 65008, scsi0, channel 0, id 1, lun 0 Read (6) 16 ce 5c 02 00
Dec 1 15:03:44 lobo kernel: scsi : aborting command due to timeout : pid 65009, scsi0, channel 0, id 1, lun 0 Write (6) 1a b2 84 02 00
Dec 1 15:03:44 lobo kernel: scsi : aborting command due to timeout : pid 65010, scsi0, channel 0, id 1, lun 0 Write (6) 1a b2 90 02 00
Dec 1 15:03:44 lobo kernel: scsi : aborting command due to timeout : pid 65011, scsi0, channel 0, id 1, lun 0 Write (6) 1a b2 9e 02 00
Dec 1 15:03:49 lobo kernel: scsi : aborting command due to timeout : pid 65012, scsi0, channel 0, id 1, lun 0 Write (6) 00 40 71 02 00
Dec 1 15:03:49 lobo kernel: scsi : aborting command due to timeout : pid 65013, scsi0, channel 0, id 1, lun 0 Write (6) 19 8c 68 02 00
Dec 1 15:03:54 lobo kernel: scsi : aborting command due to timeout : pid 65014, scsi0, channel 0, id 1, lun 0 Write (6) 1a b2 92 02 00
Dec 1 15:03:56 lobo kernel: scsi : aborting command due to timeout : pid 65007, scsi0, channel 0, id 2, lun 0 UNKNOWN(0x42) 00 40 01 00 00 00 00 30 00
Dec 1 15:03:57 lobo kernel: SCSI host 0 abort (pid 65007) timed out - resetting
Dec 1 15:03:57 lobo kernel: SCSI bus is being reset for host 0 channel 0.
Dec 1 15:04:00 lobo kernel: (scsi0:0:1:0) Using wide (16 bit) transfers.
Dec 1 15:04:00 lobo kernel: (scsi0:0:1:0) Synchronous at 10.0MHz, offset 8.
Dec 1 15:04:00 lobo kernel: (scsi0:0:2:0) Synchronous at 10.0MHz, offset 15.
Dec 1 15:04:24 lobo kernel: scsi : aborting command due to timeout : pid 65261, scsi0, channel 0, id 1, lun 0 Read (6) 16 cc 66 02 00
Dec 1 15:04:44 lobo kernel: scsi : aborting command due to timeout : pid 65261, scsi0, channel 0, id 1, lun 0 Read (6) 16 cc 66 02 00
Dec 1 15:05:02 lobo kernel: scsi : aborting command due to timeout : pid 65260, scsi0, channel 0, id 2, lun 0 UNKNOWN(0x45) 00 00 03 18 a8 00 00 01 00
Dec 1 15:05:03 lobo kernel: SCSI host 0 abort (pid 65260) timed out - resetting
Dec 1 15:05:03 lobo kernel: SCSI bus is being reset for host 0 channel 0.
Dec 1 15:05:06 lobo kernel: (scsi0:0:1:0) Using wide (16 bit) transfers.
Dec 1 15:05:06 lobo kernel: (scsi0:0:1:0) Synchronous at 10.0MHz, offset 8.
Dec 1 15:05:06 lobo kernel: (scsi0:0:2:0) Synchronous at 10.0MHz, offset 15.
Dec 1 15:05:07 lobo kernel: (scsi0:0:0:0) Synchronous at 20.0MHz, offset 15.
Dec 1 15:13:47 lobo init: Switching to runlevel: 6
Dec 1 15:13:49 lobo kernel: Kernel logging (proc) stopped.
Dec 1 15:13:49 lobo syslogd: exiting on signal 15
-------------- next part --------------
Adaptec AIC7xxx driver version: 5.1.4/3.2.4
Compile Options:
AIC7XXX_RESET_DELAY : 5
AIC7XXX_TAGGED_QUEUEING: Adapter Support Enabled
Check below to see which
devices use tagged queueing
AIC7XXX_PAGE_ENABLE : Enabled (This is no longer an option)
AIC7XXX_PROC_STATS : Enabled
Adapter Configuration:
SCSI Adapter: Adaptec AHA-294X Ultra SCSI host adapter
Ultra Wide Controller
PCI MMAPed I/O Base: 0xea101000
Adapter SEEPROM Config: SEEPROM found and used.
Adaptec SCSI BIOS: Enabled
IRQ: 11
SCBs: Active 0, Max Active 22,
Allocated 45, HW 16, Page 255
Interrupts: 3996
BIOS Control Word: 0x1894
Adapter Control Word: 0x005a
Extended Translation: Enabled
Disconnect Enable Flags: 0x000f
Ultra Enable Flags: 0x0001
Tag Queue Enable Flags: 0x0003
Ordered Queue Tag Flags: 0x0003
Default Tag Queue Depth: 8
Tagged Queue By Device array for aic7xxx host instance 0:
{8,8,255,255,255,255,255,255,255,255,255,255,255,255,255,255}
Actual queue depth per device for aic7xxx host instance 0:
{8,8,1,1,1,1,1,1,1,1,1,1,1,1,1,1}
Statistics:
(scsi0:0:0:0)
Device using Narrow/Sync transfers at
20.0 MByte/sec, offset 15
Device Negotiation Settings
Period Offset Bus Width
User 012 015 0
Goal 012 015 0
Current 012 015 0
Total transfers 231 (210 read;21 written)
blks(512) rd=1128; blks(512) wr=52
< 512 512-1K 1-2K 2-4K 4-8K 8-16K 16-32K 32-64K 64-128K >128K
Reads: 0 10 162 5 21 5 3 4 0 0
Writes: 0 0 16 5 0 0 0 0 0 0
(scsi0:0:1:0)
Device using Wide/Sync transfers at
20.0 MByte/sec, offset 8
Device Negotiation Settings
Period Offset Bus Width
User 012 008 1
Goal 012 008 1
Current 025 008 1
Total transfers 3670 (2897 read;773 written)
blks(512) rd=38907; blks(512) wr=21138
< 512 512-1K 1-2K 2-4K 4-8K 8-16K 16-32K 32-64K 64-128K >128K
Reads: 0 1 983 165 747 868 22 33 78 0
Writes: 0 0 492 150 27 23 1 3 77 0
More information about the aic7xxx
mailing list