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