One more big please on my promise ...

Mon Aug 16 22:06:25 PDT 2004

Dear listmembers,
I simply cannot catch up with this - so maybe someone can tell who understands better than I do.
Please take a look into the following boot.msg
To stop the driver flood the screen with "Have seen Data Phase" - messages I added a counter into it and call the printf only every 32nd access. This somewhat works.

Therefore it seems to me as if there are exactly 128 errors coming from the device.

The bad thing about it:
I set DV:{0} into linuxrc in the initial ramdisk. I have a (maybe so called ...) U160 device. And I can get it only to a speed of 3.3MByte / second using the aic7xxx driver, what is *really* frustrating.

Anything I can do? Anything to force the driver to talk at an appropriate speed with this specific device knowing that it propably behaves the wrong way during DV? Anything to understand what is happening here?

Many thanks for any inputs,
take care

Dieter Jurzitza

/var/log/boot.msg:
5>RAMDISK: Compressed image found at block 0
<4>Freeing initrd memory: 937k freed
<4>VFS: Mounted root (ext2 filesystem).
<6>sym53c8xx: at PCI bus 0, device 3, function 0
<6>sym53c8xx: 53c875 detected
<6>sym53c875-0: rev 0x26 on pci bus 0 device 3 function 0 irq 4,7e0
<6>sym53c875-0: ID 7, Fast-20, Parity Checking
<6>scsi0 : sym53c8xx-1.7.3c-20010512
<4>  Vendor: SCANNER   Model:                   Rev: 2.03
<4>  Type:   Scanner                            ANSI SCSI revision: 01 CCS
<4>  Vendor: YAMAHA    Model: CRW4416S          Rev: 1.0e
<4>  Type:   CD-ROM                             ANSI SCSI revision: 02
<4>Attached scsi CD-ROM sr0 at scsi0, channel 0, id 6, lun 0
<6>sym53c875-0-<6,*>: FAST-10 SCSI 8.0 MB/s (125.0 ns, offset 16)
<4>sr0: scsi3-mmc drive: 16x/16x writer cd/rw xa/form2 cdda tray
<6>Uniform CD-ROM driver Revision: 3.12
<4>Unimplemented SPARC system call 188
<4>dv[0] = 0
<4>ahc_pci:1:2:0: Reading SEEPROM...done.
<4>ahc_pci:1:2:0: BIOS eeprom is present
<4>ahc_pci:1:2:0: Secondary High byte termination Enabled
<4>ahc_pci:1:2:0: Secondary Low byte termination Enabled
<4>ahc_pci:1:2:0: Primary Low Byte termination Enabled
<4>ahc_pci:1:2:0: Primary High Byte termination Enabled
<4>ahc_pci:1:2:0: hardware scb 64 bytes; kernel scb 112 bytes; ahc_dma 8 bytes
<4>ahc_pci:1:2:0: Downloading Sequencer Program... 423 instructions downloaded
<4>ahc_pci:1:2:0: Features 0x1def6, Bugs 0x40, Flags 0x28485560
<4>scsi1: Waking DV thread
<4>Launching DV Thread
<6>scsi1 : Adaptec AIC7XXX EISA/VLB/PCI SCSI HBA DRIVER, Rev 6.3.9
<4>        <Adaptec 29160 Ultra160 SCSI adapter>
<4>        aic7892: Ultra160 Wide Channel A, SCSI Id=7, 32/253 SCBs
<4>
<4>scsi1: Beginning Domain Validation
<4>scsi1:A:0:0: Performing DV
<4>scsi1:2401: Going from state 0 to state 1
<4>scsi1:A:0:0: Sending INQ
<4>(scsi1:A:0): 3.300MB/s transfers
<4>(scsi1:A:0:0): Saw Selection Timeout for SCB 0xf
<4>scsi1: Selection Timeout on A:0. 0 SCBs aborted
<4>scsi1:0:0: Command completed, status= 0x80000
<4>scsi1:A:0:0: Entering ahc_linux_dv_transition, state= 1, status= 0x20005, cm\
d->result= 0x80000
<4>scsi1:2595: Going from state 1 to state 0
<4>scsi1:A:0:0: Failed DV inquiry, skipping
<4>scsi1:A:1:0: Performing DV
<4>scsi1:2401: Going from state 0 to state 1
<4>scsi1:A:1:0: Sending INQ
<4>(scsi1:A:1): 3.300MB/s transfers
<4>(scsi1:A:1:0): Saw Selection Timeout for SCB 0xe
<4>scsi1: Selection Timeout on A:1. 0 SCBs aborted
<4>scsi1:0:1: Command completed, status= 0x80000
<4>scsi1:A:1:0: Entering ahc_linux_dv_transition, state= 1, status= 0x20005, cm\
d->result= 0x80000
<4>scsi1:2595: Going from state 1 to state 0
<4>scsi1:A:1:0: Failed DV inquiry, skipping
<4>scsi1:A:2:0: Performing DV
<4>scsi1:2401: Going from state 0 to state 1
<4>scsi1:A:2:0: Sending INQ
<4>(scsi1:A:2): 3.300MB/s transfers
<4>(scsi1:A:2:0): Saw Selection Timeout for SCB 0xf
<4>scsi1: Selection Timeout on A:2. 0 SCBs aborted
<4>scsi1:0:2: Command completed, status= 0x80000
<4>scsi1:A:2:0: Entering ahc_linux_dv_transition, state= 1, status= 0x20005, cm\
d->result= 0x80000
<4>scsi1:2595: Going from state 1 to state 0
<4>scsi1:A:2:0: Failed DV inquiry, skipping
<4>scsi1:A:3:0: Performing DV
<4>scsi1:2401: Going from state 0 to state 1
<4>scsi1:A:3:0: Sending INQ
<4>(scsi1:A:3): 3.300MB/s transfers
<4>scsi1:0:3: Command completed, status= 0x10000
<4>scsi1:A:3:0: Entering ahc_linux_dv_transition, state= 1, status= 0x0, cmd->result= 0x10000
<4>scsi1:2576: Going from state 1 to state 2
<4>scsi1:A:3:0: Sending INQ
<4>scsi1:0:3: Command completed, status= 0x10000
<4>scsi1:A:3:0: Entering ahc_linux_dv_transition, state= 2, status= 0x0, cmd->result= 0x10000
<4>scsi1:2576: Going from state 2 to state 3
<4>scsi1:A:3:0: Sending INQ
<4>scsi1:0:3: Command completed, status= 0x10000
<4>scsi1:A:3:0: Entering ahc_linux_dv_transition, state= 3, status= 0x0, cmd->result= 0x10000
<4>scsi1:2623: Going from state 3 to state 4
<4>scsi1:A:3:0: Sending TUR
<4>(scsi1:A:3:0): SCB 15: requests Check Status
<4>(scsi1:A:3:0): Sending Sense
<4>(scsi1:A:3:0): No. 1. data overrun detected in Data-in phase.  Tag == 0xf.
<4>(scsi1:A:3:0): Have seen Data Phase.  Length = 0.  NumSGs = 1.
<4>sg[0] - Addr 0x0c7fec1c0 : Length 32
<4>(scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): 
No. 33. data overrun detected in Data-in phase.  Tag == 0xf.
<4>(scsi1:A:3:0): Have seen Data Phase.  Length = 0.  NumSGs = 1.
<4>sg[0] - Addr 0x0c7fec1c0 : Length 32
<4>(scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0):
No. 65. data overrun detected in Data-in phase.  Tag == 0xf.
<4>(scsi1:A:3:0): Have seen Data Phase.  Length = 0.  NumSGs = 1.
<4>sg[0] - Addr 0x0c7fec1c0 : Length 32
<4>(scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): (scsi1:A:3:0): 
Handled Residual of 0 bytes
<4>scsi1:0:3: Command completed, status= 0xf0002
<4>scsi1:A:3:0: Entering ahc_linux_dv_transition, state= 4, status= 0x14005, cmd->result= 0xf0002
<4>scsi1:2769: Going from state 4 to state 4
<4>scsi1:A:3:0: Trying to fallback
<4>cur_speed= 3300, wide_speed= 6600, narrow_speed= 3300, fallback_speed= 3300
<4>scsi1:2776: Going from state 4 to state 0
<4>scsi1:A:4:0: Performing DV
<4>scsi1:2401: Going from state 0 to state 1
<4>scsi1:A:4:0: Sending INQ
<4>(scsi1:A:4): 3.300MB/s transfers
<4>scsi1:0:4: Command completed, status= 0x10000
<4>scsi1:A:4:0: Entering ahc_linux_dv_transition, state= 1, status= 0x0, cmd->result= 0x10000
<4>scsi1:2576: Going from state 1 to state 2
<4>scsi1:A:4:0: Sending INQ
<4>scsi1:0:4: Command completed, status= 0x10000
<4>scsi1:A:4:0: Entering ahc_linux_dv_transition, state= 2, status= 0x0, cmd->result= 0x10000
<4>scsi1:2576: Going from state 2 to state 3
<4>scsi1:A:4:0: Sending INQ
<4>scsi1:0:4: Command completed, status= 0x10000
<4>scsi1:A:4:0: Entering ahc_linux_dv_transition, state= 3, status= 0x0, cmd->result= 0x10000
<4>scsi1:2623: Going from state 3 to state 4
<4>scsi1:A:4:0: Sending TUR
<4>(scsi1:A:4:0): SCB 15: requests Check Status
<4>(scsi1:A:4:0): Sending Sense
<4>(scsi1:A:4:0): Handled Sense Residual of 10 bytes
<4>Copied 22 bytes of sense data:
<4>0x70 0x0 0x6 0x0 0x0 0x0 0x0 0xe 0x0 0x0 0x0 0x0 0x29 0x0 0x0 0x0 
<4>0x0 0x0 0x0 0x0 0x0 0x0 
<4>scsi1:0:4: Command completed, status= 0x80a0002
<4>scsi1:A:4:0: Entering ahc_linux_dv_transition, state= 4, status= 0x10905, cmd->result= 0x80a0002
<4>scsi1:2769: Going from state 4 to state 4
<4>scsi1:A:4:0: Sending TUR
<4>(scsi1:A:4:0): SCB 14: requests Check Status
<4>(scsi1:A:4:0): Sending Sense
<4>(scsi1:A:4:0): Handled Sense Residual of 10 bytes
<4>Copied 22 bytes of sense data:
<4>0x70 0x0 0x2 0x0 0x0 0x0 0x0 0xe 0x0 0x0 0x0 0x0 0x3a 0x0 0x0 0x0 
<4>0x0 0x0 0x0 0x0 0x0 0x0 
<4>scsi1:0:4: Command completed, status= 0x80a0002
<4>scsi1:A:4:0: Entering ahc_linux_dv_transition, state= 4, status= 0x0, cmd->result= 0x80a0002
<4>scsi1:2760: Going from state 4 to state 0
<4>scsi1:A:5:0: Performing DV
<4>scsi1:2401: Going from state 0 to state 1
<4>scsi1:A:5:0: Sending INQ
<4>(scsi1:A:5): 3.300MB/s transfers
<4>(scsi1:A:5:0): Saw Selection Timeout for SCB 0xf
<4>scsi1: Selection Timeout on A:5. 0 SCBs aborted
<4>scsi1:0:5: Command completed, status= 0x80000
<4>scsi1:A:5:0: Entering ahc_linux_dv_transition, state= 1, status= 0x20005, cmd->result= 0x80000
<4>scsi1:2595: Going from state 1 to state 0
<4>scsi1:A:5:0: Failed DV inquiry, skipping
<4>scsi1:A:6:0: Performing DV
<4>scsi1:2401: Going from state 0 to state 1
<4>scsi1:A:6:0: Sending INQ
<4>(scsi1:A:6): 3.300MB/s transfers
<4>(scsi1:A:6:0): Saw Selection Timeout for SCB 0xe
<4>scsi1: Selection Timeout on A:6. 0 SCBs aborted
<4>scsi1:0:6: Command completed, status= 0x80000
<4>scsi1:A:6:0: Entering ahc_linux_dv_transition, state= 1, status= 0x20005, cmd->result= 0x80000
<4>scsi1:2595: Going from state 1 to state 0
<4>scsi1:A:6:0: Failed DV inquiry, skipping
<4>scsi1:A:8:0: Performing DV
<4>scsi1:2401: Going from state 0 to state 1
<4>scsi1:A:8:0: Sending INQ
<4>(scsi1:A:8): 3.300MB/s transfers
<4>(scsi1:A:8:0): Saw Selection Timeout for SCB 0xf
<4>scsi1: Selection Timeout on A:8. 0 SCBs aborted
<4>scsi1:0:8: Command completed, status= 0x80000
<4>scsi1:A:8:0: Entering ahc_linux_dv_transition, state= 1, status= 0x20005, cmd->result= 0x80000
<4>scsi1:2595: Going from state 1 to state 0
<4>scsi1:A:8:0: Failed DV inquiry, skipping
<4>scsi1:A:9:0: Performing DV
<4>scsi1:2401: Going from state 0 to state 1
<4>scsi1:A:9:0: Sending INQ
<4>(scsi1:A:9): 3.300MB/s transfers
<4>(scsi1:A:9:0): Saw Selection Timeout for SCB 0xe
<4>scsi1: Selection Timeout on A:9. 0 SCBs aborted
<4>scsi1:0:9: Command completed, status= 0x80000
<4>scsi1:A:9:0: Entering ahc_linux_dv_transition, state= 1, status= 0x20005, cmd->result= 0x80000
<4>scsi1:2595: Going from state 1 to state 0
<4>scsi1:A:9:0: Failed DV inquiry, skipping
<4>scsi1:A:10:0: Performing DV
<4>scsi1:2401: Going from state 0 to state 1
<4>scsi1:A:10:0: Sending INQ
<4>(scsi1:A:10): 3.300MB/s transfers
<4>(scsi1:A:10:0): Saw Selection Timeout for SCB 0xf
<4>scsi1: Selection Timeout on A:10. 0 SCBs aborted
<4>scsi1:0:10: Command completed, status= 0x80000
<4>scsi1:A:10:0: Entering ahc_linux_dv_transition, state= 1, status= 0x20005, cmd->result= 0x80000
<4>scsi1:2595: Going from state 1 to state 0
<4>scsi1:A:10:0: Failed DV inquiry, skipping
<4>scsi1:A:11:0: Performing DV
<4>scsi1:2401: Going from state 0 to state 1
<4>scsi1:A:11:0: Sending INQ
<4>(scsi1:A:11): 3.300MB/s transfers
Kernel logging (ksyslog) stopped.
Kernel log daemon terminating.

/proc/scsi/aic7xxx/1:

Adaptec AIC7xxx driver version: 6.3.9
Adaptec 29160 Ultra160 SCSI adapter
aic7892: Ultra160 Wide Channel A, SCSI Id=7, 32/253 SCBs
Allocated SCBs: 16, SG List Length: 64

Serial EEPROM:
0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 
0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 0xc33a 
0x08f4 0x7c5d 0x2807 0x0010 0x0300 0xffff 0xffff 0xffff 
0xffff 0xffff 0xffff 0xffff 0xffff 0xffff 0x0250 0xe64f 

Target 0 Negotiation Settings
        User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit)
Target 1 Negotiation Settings
        User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit)
Target 2 Negotiation Settings
        User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit)
Target 3 Negotiation Settings
        User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit)
        Goal: 3.300MB/s transfers
        Curr: 3.300MB/s transfers
        Channel A Target 3 Lun 0 Settings
                Commands Queued 8547
                Commands Active 0
                Command Openings 1
                Max Tagged Openings 1
                Device Queue Frozen Count 0
Target 4 Negotiation Settings
        User: 160.000MB/s transfers (80.000MHz DT, offset 127, 16bit)
        Goal: 3.300MB/s transfers
        Curr: 3.300MB/s transfers
        Channel A Target 4 Lun 0 Settings
                Commands Queued 6
                Commands Active 0
                Command Openings 1
                Max Tagged Openings 0
                Device Queue Frozen Count 0
        Channel A Target 4 Lun 1 Settings
                Commands Queued 0
                Commands Active 0
                Command Openings 1

-- 
________________________________________________

HARMAN BECKER AUTOMOTIVE SYSTEMS

Dr.-Ing. Dieter Jurzitza
Manager Hardware Systems
         ESI

Industriegebiet Ittersbach
Becker-Göring Str. 16
D-76307 Karlsbad / Germany

Phone: +49 (0)7248 71-1577
Fax:   +49 (0)7248 71-1216
eMail: DJurzitza at harmanbecker.com
Internet: http://www.becker.de

*******************************************
Diese E-Mail enthaelt vertrauliche und/oder rechtlich geschuetzte Informationen. Wenn Sie nicht der richtige Adressat sind oder diese E-Mail irrtuemlich erhalten haben, informieren Sie bitte sofort den Absender und loeschen Sie diese Mail. Das unerlaubte Kopieren sowie die unbefugte Weitergabe dieser Mail ist nicht gestattet.

This e-mail may contain confidential and/or privileged information. If you are not the intended recipient (or have received this e-mail in error) please notify the sender immediately and delete this e-mail. Any unauthorized copying, disclosure or distribution of the contents in this e-mail is strictly forbidden.
*******************************************