Message token BadDrive not found

This is from a Mk2 Raspberry Pi running OS 5.23 (12-Jan-16) and a 2016 ZeroPain module.

Starting with two SCSI hardiscs and two SCSI pen drives connected all is well. If yet another pen, either SCSI or Fat32FS, is connected an error with pain will occur on a subsequent filer operation and all the drive icons disappear from the icon bar. Otherwise the machine and Filer operations appear to continue to work.

It was originally thought that this was a power supply issue but that turned out to be not so.

With a low vector ROM the error is :-

16 Jan 07:47:41 000 00000AC2: Error from SCSI Filer: Message token BadDrive not found

Having forgotten what the maximum number of drives permitted is this may be down to excess, but if so then there does seem to be a bug in the way that is handled.

The log below is a good example of when the last run app to start line has nothing to do with the problem, which in this case was run at startup.

*fx0
RISC OS 5.23 (12 Jan 2016)

16 Jan 07:20:28 000 80000002: Error from (unknown): Internal error: abort on data transfer at &FC14C7B8

*where
Address &FC14C7B8 is at offset &00000560 in module 'MessageTrans'


Time: Sat Jan 16 07:20:28 2016
Location: Offset 0000134c in module FileCore
Current Wimp task: SCSI Filer
Last app to start: BASIC -quit "SCSI::HardDisc4.$.Work.Office.!BirthDays.!RunImage"

R0  = 204c6000 R1  = 00000005 R2  = a0000000 R3  = 203a44b8
R4  = 00000200 R5  = 00000000 R6  = 00000000 R7  = 2033cbb8
R8  = 300fa900 R9  = fc0293a0 R10 = fc231444 R11 = fa207d08
R12 = 00000000 R13 = fa207ce4 R14 = fc226b74 R15 = fc226c18
DFAR = 00000050  Mode SVC32  Flags nzCv if   PSR = 20000113

fc226bd0 : e31e0008 : TST     R14,#8
fc226bd4 : 0a000002 : BEQ     &FC226BE4
fc226bd8 : eb000012 : BL      &FC226C28
fc226bdc : 71a03005 : MOVVC   R3,R5
fc226be0 : 73a00000 : MOVVC   R0,#0
fc226be4 : ebfffc2e : BL      &FC225CA4
fc226be8 : 6a000003 : BVS     &FC226BFC
fc226bec : e5d30036 : LDRB    R0,[R3,#54]
fc226bf0 : e3300008 : TEQ     R0,#8
fc226bf4 : 0affffdf : BEQ     &FC226B78
fc226bf8 : e8bd8071 : LDMIA   R13!,{R0,R4-R6,PC}
fc226bfc : e28dd004 : ADD     R13,R13,#4
fc226c00 : e8bd8070 : LDMIA   R13!,{R4-R6,PC}
fc226c04 : e52de004 : STR     R14,[R13,#-4]!
fc226c08 : e3510004 : CMP     R1,#4
fc226c0c : 35dce051 : LDRCCB  R14,[R12,#81]
fc226c10 * 25dce050 * LDRCSB  R14,[R12,#80]
fc226c14 : 228ee004 : ADDCS   R14,R14,#4
fc226c18 : e151000e : CMP     R1,R14
fc226c1c : 23a000ac : MOVCS   R0,#&AC            ; ="¨"
fc226c20 : 2328f209 : MSRCS   CPSR_f,#&90000000
fc226c24 : e49df004 : LDR     PC,[R13],#4
fc226c28 : e92d489b : STMDB   R13!,{R0,R1,R3,R4,R7,R11,R14}
fc226c2c : e1a00001 : MOV     R0,R1
fc226c30 : e5d47001 : LDRB    R7,[R4,#1]
fc226c34 : eb004178 : BL      &FC23721C
fc226c38 : e5850020 : STR     R0,[R5,#32]
fc226c3c : e5d5e034 : LDRB    R14,[R5,#52]
fc226c40 : e3cee008 : BIC     R14,R14,#8
fc226c44 : e5c5e034 : STRB    R14,[R5,#52]
fc226c48 : e59fe244 : LDR     R14,&FC226E94
fc226c4c : e130000e : TEQ     R0,R14

--------------------------------------------------------------------------------

Time: Sat Jan 16 07:20:28 2016
Location: Offset 00003194 in module FileCore
Current Wimp task: SCSI Filer
Last app to start: BASIC -quit "SCSI::HardDisc4.$.Work.Office.!BirthDays.!RunImage"

R0  = fc225ab4 R1  = 000100ac R2  = 00010800 R3  = 90000113
R4  = 00000200 R5  = 00000000 R6  = 00000000 R7  = 2033cbb8
R8  = 300fa900 R9  = fc0293a0 R10 = fc231444 R11 = 0000000a
R12 = 00000000 R13 = fa207d08 R14 = 000000ac R15 = fc228a60
DFAR = 00000057  Mode SVC32  Flags nZcv if   PSR = 40000113

fc228a18 : e3a0007e : MOV     R0,#&7E            ; ="~"
fc228a1c : eb000118 : BL      &FC228E84
fc228a20 : e3a0e011 : MOV     R14,#&11           ; =17
fc228a24 : e24f00d0 : ADR     R0,&FC22895C
fc228a28 : e2400a03 : SUB     R0,R0,#&3000
fc228a2c : e5d01000 : LDRB    R1,[R0,#0]
fc228a30 : e131000e : TEQ     R1,R14
fc228a34 : 0a000005 : BEQ     &FC228A50
fc228a38 : e5b01004 : LDR     R1,[R0,#4]!
fc228a3c : e3c120ff : BIC     R2,R1,#&FF         ; ="ˇ"
fc228a40 : e3320b42 : TEQ     R2,#&00010800
fc228a44 : 1afffffb : BNE     &FC228A38
fc228a48 : e3310b42 : TEQ     R1,#&00010800
fc228a4c : 1afffff6 : BNE     &FC228A2C
fc228a50 : e3c11cff : BIC     R1,R1,#&FF00
fc228a54 : e3811801 : ORR     R1,R1,#&00010000
fc228a58 * e5dce057 * LDRB    R14,[R12,#87]
fc228a5c : e181140e : ORR     R1,R1,R14,LSL #8
fc228a60 : eb0022ec : BL      &FC231618
fc228a64 : ea000030 : B       &FC228B2C
fc228a68 : ea00002f : B       &FC228B2C
fc228a6c : e3100001 : TST     R0,#1
fc228a70 : 0a00002d : BEQ     &FC228B2C
fc228a74 : e3c00001 : BIC     R0,R0,#1
fc228a78 : e92d0071 : STMDB   R13!,{R0,R4-R6}
fc228a7c : e24dd028 : SUB     R13,R13,#&28       ; ="("
fc228a80 : e328f202 : MSR     CPSR_f,#&20000000
fc228a84 : e5d00000 : LDRB    R0,[R0,#0]
fc228a88 : e1a0100d : MOV     R1,R13
fc228a8c : e3a02004 : MOV     R2,#4
fc228a90 : eb000175 : BL      &FC22906C
fc228a94 : 759d0028 : LDRVC   R0,[R13,#40]

--------------------------------------------------------------------------------

Time: Sat Jan 16 07:20:28 2016
Location: Offset 0000be74 in module FileCore
Current Wimp task: SCSI Filer
Last app to start: BASIC -quit "SCSI::HardDisc4.$.Work.Office.!BirthDays.!RunImage"

R0  = fc225ab4 R1  = 000100ac R2  = 00010800 R3  = 90000113
R4  = 00000000 R5  = 00000000 R6  = 00000000 R7  = 00000000
R8  = 300fa900 R9  = fc0293a0 R10 = fc231444 R11 = 0000000a
R12 = 00000000 R13 = fa207cc4 R14 = fc231634 R15 = fc231740
DFAR = 00000014  Mode SVC32  Flags nzCv if   PSR = 20000113

fc2316f8 : e8bd80ff : LDMIA   R13!,{R0-R7,PC}
fc2316fc : e92d40ff : STMDB   R13!,{R0-R7,R14}
fc231700 : eaffffeb : B       &FC2316B4
fc231704 : e92d40ff : STMDB   R13!,{R0-R7,R14}
fc231708 : eaffffea : B       &FC2316B8
fc23170c : 6f736552 : SWIVS   &736552
fc231710 : 65637275 : STRVSB  R7,[R3,#-629]!
fc231714 : 2e243a73 : MCRCS   CP10,1,R3,C4,C3,3
fc231718 : 6f736552 : SWIVS   &736552
fc23171c : 65637275 : STRVSB  R7,[R3,#-629]!
fc231720 : 69462e73 : STMVSDB R6,{R0,R1,R4-R6,R9-R11,R13}^
fc231724 : 6f43656c : SWIVS   &43656C
fc231728 : 4d2e6572 : STCMI   CP5,C6,[R14,#-456]!
fc23172c : 61737365 : Undefined instruction
fc231730 : 00736567 : RSBEQS  R6,R3,R7,ROR #10
fc231734 : e92d40ff : STMDB   R13!,{R0-R7,R14}
fc231738 * e59c1014 * LDR     R1,[R12,#20]
fc23173c : e3310000 : TEQ     R1,#0
fc231740 : 1affffff : BNE     &FC231744
fc231744 : 18bd80ff : LDMNEIA R13!,{R0-R7,PC}
fc231748 : e28c006c : ADD     R0,R12,#&6C        ; ="l"
fc23174c : e24f1048 : ADR     R1,&FC23170C
fc231750 : e3a02000 : MOV     R2,#0
fc231754 : ebffdde6 : BL      &FC228EF4
fc231758 : e59c106c : LDR     R1,[R12,#108]
fc23175c : e59c1070 : LDR     R1,[R12,#112]
fc231760 : e59c1074 : LDR     R1,[R12,#116]
fc231764 : e59c1078 : LDR     R1,[R12,#120]
fc231768 : 73a01001 : MOVVC   R1,#1
fc23176c : 758c1014 : STRVC   R1,[R12,#20]
fc231770 : 658d0000 : STRVS   R0,[R13,#0]
fc231774 : e8bd80ff : LDMIA   R13!,{R0-R7,PC}

--------------------------------------------------------------------------------

Time: Sat Jan 16 07:20:28 2016
Location: Offset 0000048c in module MessageTrans
Current Wimp task: SCSI Filer
Last app to start: BASIC -quit "SCSI::HardDisc4.$.Work.Office.!BirthDays.!RunImage"

R0  = 0000006c R1  = fc23170c R2  = 00000000 R3  = 90000113
R4  = 00000000 R5  = 00000000 R6  = 00000000 R7  = 00000000
R8  = 300fa900 R9  = fc0293a0 R10 = fc14c504 R11 = 00000001
R12 = 200017d4 R13 = fa207c88 R14 = fc010620 R15 = fc14c6ec
DFAR = 0000006c  Mode SVC32  Flags Nzcv if   PSR = 80000113

fc14c6a4 : 02833004 : ADDEQ   R3,R3,#4
fc14c6a8 : 63e07102 : MVNVS   R7,#&80000000
fc14c6ac : 73a07000 : MOVVC   R7,#0
fc14c6b0 : e1a06000 : MOV     R6,R0
fc14c6b4 : e3a00000 : MOV     R0,#0
fc14c6b8 : ef02000d : SWI     XOS_Find
fc14c6bc : 71a00006 : MOVVC   R0,R6
fc14c6c0 : e2977001 : ADDS    R7,R7,#1
fc14c6c4 : e5dce006 : LDRB    R14,[R12,#6]
fc14c6c8 : e24ee001 : SUB     R14,R14,#1
fc14c6cc : e5cce006 : STRB    R14,[R12,#6]
fc14c6d0 : e8bd80f2 : LDMIA   R13!,{R1,R4-R7,PC}
fc14c6d4 : 48535153 : LDMMIDA R3,{R0,R1,R4,R6,R8,R12,R14}^
fc14c6d8 : 54534146 : LDRPLB  R4,[R3],#-326
fc14c6dc : 574f4c53 : SMLSLDPL R4,PC,R3,R12      ; *** Not R15
fc14c6e0 : e92d407f : STMDB   R13!,{R0-R6,R14}
fc14c6e4 * e5906000 * LDR     R6,[R0,#0]
fc14c6e8 : e51f5018 : LDR     R5,&FC14C6D8
fc14c6ec : e1550006 : CMP     R5,R6
fc14c6f0 : 1a00001a : BNE     &FC14C760
fc14c6f4 : e5905004 : LDR     R5,[R0,#4]
fc14c6f8 : e3150101 : TST     R5,#&40000000
fc14c6fc : 0a000016 : BEQ     &FC14C75C
fc14c700 : e5905008 : LDR     R5,[R0,#8]
fc14c704 : e28ce000 : ADD     R14,R12,#0
fc14c708 : e59ee000 : LDR     R14,[R14,#0]
fc14c70c : e33e0000 : TEQ     R14,#0
fc14c710 : 0a000012 : BEQ     &FC14C760
fc14c714 : e13e0005 : TEQ     R14,R5
fc14c718 : 1afffffa : BNE     &FC14C708
fc14c71c : e5954014 : LDR     R4,[R5,#20]
fc14c720 : e1340000 : TEQ     R4,R0

--------------------------------------------------------------------------------

This should be fixed with SCSIFS 1.30

https://www.riscosopen.org/viewer/revisions/logs?ident=1504985576-834643.html

(although, due to other changes it should be 1.32 which shows up in tomorrow’s ROMs)

This should be fixed with SCSIFS 1.30

I could not reproduce the original error today but a lot has changed here since then.

The bad news is that on using the 170910 beta ROM the RPi3 would not boot from its SCSI SSD, stalling permanently at “Waiting for boot drive to be ready; press Escape to cancel”, Escape doing nothing. All was, and still is, good with the previous ROM of 20Aug17.

(Bizarrely trying to install the new ROM into a functioning RC15 card resulted in either a terminal DHCP stall or with manual network configuration a failure to find the default gateway. The RC15 ROM starts perfectly.)

The same ROM version, 170910, seemed OK softloaded on the Titanium, it even booted from a backup SCSI pen. It went ever so slightly wrong on attempting to boot from a backup SCSI SSD, it didn’t and now won’t start at all. The boot gives two beeps as normal but there is no desktop. Video black level appears to be present. F12 is not resulting in a command line. Attempted CMOS resets do nothing useful. RC5 is the flashed ROM, and it is that that is not now starting.

The bad news is that on using the 170910 beta ROM the RPi3 would not boot from its SCSI SSD, stalling permanently at “Waiting for boot drive to be ready; press Escape to cancel”, Escape doing nothing. All was, and still is, good with the previous ROM of 20Aug17.

A bug crept into the Pi ROM which broke USB, and it looks like my fix didn’t make it in time for the nightly build. Try again tomorrow!

The same ROM version, 170910, seemed OK softloaded on the Titanium, it even booted from a backup SCSI pen. It went ever so slightly wrong on attempting to boot from a backup SCSI SSD, it didn’t and now won’t start at all. The boot gives two beeps as normal but there is no desktop. Video black level appears to be present. F12 is not resulting in a command line. Attempted CMOS resets do nothing useful. RC5 is the flashed ROM, and it is that that is not now starting.

Odd. I’d expect things to maybe bit a more unstable today compared to yesterday (the ROM initialisation was changed around a bit in order to allow for the OS to handle the keyboard scan instead of relying on the HAL), and that did reveal a bug in the PCI module which should now be fixed, but if that was likely to cause problems on the Titanium I expect Sprow would have encountered it when he was testing his changes.

The same ROM version, 170910, seemed OK softloaded on the Titanium, it even booted from a backup SCSI pen. It went ever so slightly wrong on attempting to boot from a backup SCSI SSD, it didn’t and now won’t start at all.

if that was likely to cause problems on the Titanium I expect Sprow would have encountered it when he was testing his changes.

I’ve not seen anything odd with the 10-Sep-2017 on my home Titanium, though I did just go feet first and program it into flash. I don’t have a SCSI SSD, but a random SCSI USB stick with a !Boot on it worked fine with *Configure FileSystem SCSI

Note: there should only be one “beep” now, the double beep was denoting the “No keyboard autobooting” message which doesn’t happen now, so it’s likely the 2nd beep is an error box waiting for you to press Escape.

David

The only change I notice on using the 170910 ROM on my Panda system is that the USB stick that is plugged in permenently is now showing up as SCSI::0 not SCSI::2.

The Panda system has a built in card reader and the two icons for that are now SCSI::1 and SCSI::2.

Reverting to the last ROM 170908 restores the SCSI drive order.

Do you have a card reader in your system?

Note: there should only be one “beep” now, the double beep was denoting the “No keyboard autobooting” message which doesn’t happen now, so it’s likely the 2nd beep is an error box waiting for you to press Escape.

There was an error in the !Boot on the SCSI SSD which unfortunately left the Titanium in a 256 colour mode which is not good on the RC5 ROM. For that reason the 10Sep17 ROM has now been flashed.

Thanks for the responses, the Titanium is good again now.