[Bug 14394] cdrecord/k3b/nautilus problem

[Richard Kleeman]

Public bug report changed:
https://launchpad.net/malone/bugs/14394

Comment:
Tried again with kernel 2.6.15-12 and still fails. Interestingly however
the kernel error messages have changed somewhat

( Protocol violation in Message-in phase.  Attempting to abort.
[4296204.578000] (scsi0:A:4:0): Unexpected busfree in Message-in phase, 1 SCBs aborted, PRGMCNT == 0x198)

Full messages:

[4296204.362000] (scsi0:A:4:0): No or incomplete CDB sent to device.
[4296204.362000] scsi0: Issued Channel A Bus Reset. 1 SCBs aborted
[4296204.578000] (scsi0:A:4:0): No or incomplete CDB sent to device.
[4296204.578000] (scsi0:A:4:0): Protocol violation in Message-in phase.  Attempt
ing to abort.
[4296204.578000] (scsi0:A:4:0): Unexpected busfree in Message-in phase, 1 SCBs a
borted, PRGMCNT == 0x198
[4296204.578000] >>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<<
[4296204.578000] scsi0: Dumping Card State at program address 0x196 Mode 0x11
[4296204.578000] Card was paused
[4296204.578000] HS_MAILBOX[0x0] INTCTL[0x80]:(SWTMINTMASK)
[4296204.578000] SEQINTSTAT[0x0] SAVED_MODE[0x11]
[4296204.578000] DFFSTAT[0x13]:(CURRFIFO_NONE|FIFO0FREE)
[4296204.578000] SCSISIGI[0x0]:(P_DATAOUT) SCSIPHASE[0x0]
[4296204.578000] SCSIBUS[0x0] LASTPHASE[0xe0]:(P_MESGIN)
[4296204.578000] SCSISEQ0[0x0] SCSISEQ1[0x12]:(ENAUTOATNP|ENRSELI)
[4296204.578000] SEQCTL0[0x0] SEQINTCTL[0x0] SEQ_FLAGS[0x40]:(NO_CDB_SENT)
[4296204.578000] SEQ_FLAGS2[0x0] SSTAT0[0x0] SSTAT1[0x8]:(BUSFREE)
[4296204.578000] SSTAT2[0xc0]:(BUSFREE_DFF1) SSTAT3[0x0]
[4296204.578000] PERRDIAG[0xc0]:(HIPERR|HIZERO) SIMODE1[0xac]:(ENSCSIPERR|ENBUSF
REE|ENSCSIRST|ENSELTIMO)
[4296204.578000] LQISTAT0[0x0] LQISTAT1[0x0] LQISTAT2[0x0]
[4296204.578000] LQOSTAT0[0x0] LQOSTAT1[0x0] LQOSTAT2[0x0]
[richard at dual ~]$ more kernelmess
[4296204.362000] (scsi0:A:4:0): No or incomplete CDB sent to device.
[4296204.362000] scsi0: Issued Channel A Bus Reset. 1 SCBs aborted
[4296204.578000] (scsi0:A:4:0): No or incomplete CDB sent to device.
[4296204.578000] (scsi0:A:4:0): Protocol violation in Message-in phase.  Attempting to abort.
[4296204.578000] (scsi0:A:4:0): Unexpected busfree in Message-in phase, 1 SCBs aborted, PRGMCNT == 0x198
[4296204.578000] >>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<<
[4296204.578000] scsi0: Dumping Card State at program address 0x196 Mode 0x11
[4296204.578000] Card was paused
[4296204.578000] HS_MAILBOX[0x0] INTCTL[0x80]:(SWTMINTMASK)
[4296204.578000] SEQINTSTAT[0x0] SAVED_MODE[0x11]
[4296204.578000] DFFSTAT[0x13]:(CURRFIFO_NONE|FIFO0FREE)
[4296204.578000] SCSISIGI[0x0]:(P_DATAOUT) SCSIPHASE[0x0]
[4296204.578000] SCSIBUS[0x0] LASTPHASE[0xe0]:(P_MESGIN)
[4296204.578000] SCSISEQ0[0x0] SCSISEQ1[0x12]:(ENAUTOATNP|ENRSELI)
[4296204.578000] SEQCTL0[0x0] SEQINTCTL[0x0] SEQ_FLAGS[0x40]:(NO_CDB_SENT)
[4296204.578000] SEQ_FLAGS2[0x0] SSTAT0[0x0] SSTAT1[0x8]:(BUSFREE)
[4296204.578000] SSTAT2[0xc0]:(BUSFREE_DFF1) SSTAT3[0x0]
[4296204.578000] PERRDIAG[0xc0]:(HIPERR|HIZERO) SIMODE1[0xac]:(ENSCSIPERR|ENBUSFREE|ENSCSIRST|ENSELTIMO)
[4296204.578000] LQISTAT0[0x0] LQISTAT1[0x0] LQISTAT2[0x0]
[4296204.578000] LQOSTAT0[0x0] LQOSTAT1[0x0] LQOSTAT2[0x0]
[4296204.578000]
[4296204.578000] SCB Count = 4 CMDS_PENDING = 1 LASTSCB 0xffff CURRSCB 0x3 NEXTSCB 0x0
[4296204.578000] qinstart = 60139 qinfifonext = 60139
[4296204.578000] QINFIFO:
[4296204.578000] WAITING_TID_QUEUES:
[4296204.578000] Pending list:
[4296204.578000] Total 0
[4296204.578000] Kernel Free SCB list: 3 2 1 0
[4296204.578000] Sequencer Complete DMA-inprog list:
[4296204.578000] Sequencer Complete list:
[4296204.578000] Sequencer DMA-Up and Complete list:
[4296204.578000]
[4296204.578000] scsi0: FIFO0 Free, LONGJMP == 0x80ff, SCB 0x0
[4296204.578000] SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG4TSTAT|ENCFG4ISTAT|ENCFG4DATA|ENSAVEPTRS)
[4296204.578000] SEQINTSRC[0x0] DFCNTRL[0x0] DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL)
[4296204.578000] SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x0]
[4296204.578000] DFFSXFRCTL[0x0] SOFFCNT[0x0] MDFFSTAT[0x5]:(FIFOFREE|DLZERO)
[4296204.578000] SHADDR = 0x00, SHCNT = 0x0 HADDR = 0x00, HCNT = 0x0
[4296204.578000] CCSGCTL[0x10]:(SG_CACHE_AVAIL)
[4296204.578000] scsi0: FIFO1 Active, LONGJMP == 0x8063, SCB 0x3
[4296204.578000] SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG4TSTAT|ENCFG4ISTAT|ENCFG4DATA|ENSAVEPTRS)
[4296204.578000] SEQINTSRC[0x0] DFCNTRL[0x4]:(DIRECTION)
[4296204.578000] DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL)
[4296204.578000] SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x0]
[4296204.578000] DFFSXFRCTL[0x0] SOFFCNT[0x0] MDFFSTAT[0xc]:(DLZERO|SHVALID)
[4296204.578000] SHADDR = 0x0a, SHCNT = 0x2 HADDR = 0x00, HCNT = 0x0
[4296204.578000] CCSGCTL[0x10]:(SG_CACHE_AVAIL)
[4296204.578000] LQIN: 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
[4296204.578000] scsi0: LQISTATE = 0x0, LQOSTATE = 0x0, OPTIONMODE = 0x52
[4296204.578000] scsi0: OS_SPACE_CNT = 0x20 MAXCMDCNT = 0x0
[4296204.578000] SIMODE0[0xc]:(ENOVERRUN|ENIOERR)
[4296204.578000] CCSCBCTL[0x4]:(CCSCBDIR)
[4296204.578000] scsi0: REG0 == 0x3, SINDEX = 0x103, DINDEX = 0xe1
[4296204.578000] scsi0: SCBPTR == 0x3, SCB_NEXT == 0xffc0, SCB_NEXT2 == 0xffca
[4296204.578000] CDB 4a 1 0 0 10 0
[4296204.578000] STACK: 0xc9 0x0 0x0 0x0 0x0 0x0 0x0 0x0
[4296204.578000] <<<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>>
[4296204.580000] (scsi0:A:4:0): No or incomplete CDB sent to device.
[4296204.580000] scsi0: Issued Channel A Bus Reset. 1 SCBs aborted
[4296204.591000] sr 0:0:4:0: SCSI error: return code = 0x70000