BlueSCSI hang on AHA-2940US

log.txt

Platform: BlueSCSI (Pico1/W)
FW Version: 2025.10.27-rel Oct 27 2025 20:32:19
Flash chip size: 2048 kB
Reclock RP2040 & Pico 1/1W based boards to standardized speed
Initial Clock set to 133MHz
Reclocking the MCU to 203MHz
Setting the SDIO clock to 41MHz
After reclocking, system reports clock set to 203MHz
 
=== SD Card Info ===
SD card detected, exFAT volume size: 60887 MB
SD Name: SC64G, MID: 0x03, OID: 0x53 0x44
[664ms] 
[664ms] === Global Config ===
[664ms] Reading configuration from bluescsi.ini
[694ms] DBG -- SelectionDelay = 255
[694ms] DBG -- EnableUnitAttention = No
[695ms] DBG -- EnableSCSI2 = Yes
[695ms] DBG -- EnableSelLatch = No
[695ms] DBG -- MapLunsToIDs = No
[696ms] DBG -- EnableParity = Yes
[698ms] 
[698ms] === Finding images ===
[897ms] === Finding images in / ===
[915ms] == Opening /CD6_win98_quick.iso for ID:6 LUN:0
[915ms] ---- WARNING: /CD6_win98_quick.iso is fragmented, see https://github.com/BlueSCSI/BlueSCSI-v2/wiki/Image-File-Fragmentation
[916ms] ---- Configuring as CD-ROM drive
[917ms] DBG ---- Read prefetch enabled: 8192 bytes
[926ms] 
[926ms] == Platform supports ROM drive up to 800 kB
[926ms] DBG ---- ROM drive image not detected
[927ms]  
[927ms] === Configured SCSI Devices ===
[927ms] ID: 6, BlockSize: 2048, Type: Optical, Quirks: Apple, Size: 1243868kB, Removable
[929ms] 
[948ms] ID: 6, Eject button: #1
[948ms] DBG No removable media found for 2 eject buttons, skipping.
[950ms] DBG Waiting for USB enumeration to enter Card Reader mode.
[2050ms] DBG Waiting for USB enumeration timed out after 1000ms.
[2050ms] DBG -- Try increasing 'USBMassStorageWaitPeriod' in the bluescsi.ini
[2051ms] Clock set to: 203MHz
[2051ms] Initialization complete!
[2052ms] Disabling status LED
[2060ms] INFO: Pico Voltage: 3.210V.
[20775ms] DBG -- BUS_BUSY
[20775ms] DBG ---- SELECTION: 6
[20777ms] DBG ---- MESSAGE_OUT
[20777ms] DBG ------ OUT: 0xC0 
[20777ms] DBG ---- COMMAND: Inquiry
[20777ms] DBG ------ OUT: 0x12 0x00 0x00 0x00 0x20 0x00 
[20780ms] DBG BlueSCSI Toolbox enabled = enabled
[20781ms] DBG ---- DATA_IN
[20781ms] DBG ---- Total IN: 32 OUT: 0 CHECKSUM: 22980
[20781ms] DBG ---- STATUS: 0 GOOD
[20787ms] DBG ---- MESSAGE_IN
[20787ms] DBG ------ IN: 0x00 
[20788ms] DBG -- BUS_FREE
[20788ms] DBG ---- SELECTION: 6
[20789ms] DBG ---- MESSAGE_OUT
[20789ms] DBG ------ OUT: 0xC0 
[20789ms] DBG ---- COMMAND: TestUnitReady
[20790ms] DBG ------ OUT: 0x00 0x00 0x00 0x00 0x00 0x00 
[20790ms] DBG ---- STATUS: 0 GOOD
[20793ms] DBG ---- MESSAGE_IN
[20793ms] DBG ------ IN: 0x00 
[20794ms] DBG -- BUS_FREE
[20794ms] DBG ---- SELECTION: 6
[20794ms] DBG ---- MESSAGE_OUT
[20795ms] DBG ------ OUT: 0xC0 
[20795ms] DBG ---- COMMAND: CDROM Read TOC
[20795ms] DBG ------ OUT: 0x43 0x00 0x00 0x00 0x00 0x00 
[20796ms] DBG ------ OUT: 0x01 0x10 0x00 0x00 
[20796ms] DBG ---- DATA_IN
[20797ms] DBG ---- Total IN: 20 OUT: 0 CHECKSUM: 24850
[20797ms] DBG ---- STATUS: 0 GOOD
[20801ms] DBG ---- MESSAGE_IN
[20801ms] DBG ------ IN: 0x00 
[20802ms] DBG -- BUS_FREE
[20802ms] DBG ---- SELECTION: 6
[20802ms] DBG ---- MESSAGE_OUT
[20802ms] DBG ------ OUT: 0xC0 
[20803ms] DBG ---- COMMAND: Read10
[20803ms] DBG ------ OUT: 0x28 0x00 0x00 0x00 0x00 0x11 
[20804ms] DBG ------ OUT: 0x00 0x00 0x01 0x00 
[20804ms] DBG ------ Read 1x2048 starting at 17
[20805ms] DBG ---- DATA_IN
[20806ms] DBG ---- Total IN: 2048 OUT: 0 CHECKSUM: 48557
[20806ms] DBG ---- STATUS: 0 GOOD
[20810ms] DBG ---- MESSAGE_IN
[20810ms] DBG ------ IN: 0x00 
[20811ms] DBG -- BUS_FREE
[20811ms] DBG ---- SELECTION: 6
[20811ms] DBG ---- MESSAGE_OUT
[20811ms] DBG ------ OUT: 0xC0 
[20812ms] DBG ---- COMMAND: Read10
[20812ms] DBG ------ OUT: 0x28 0x00 0x00 0x00 0x00 0x8E 
[20813ms] DBG ------ OUT: 0x00 0x00 0x01 0x00 
[20813ms] DBG ------ Read 1x2048 starting at 142
[20814ms] DBG ---- DATA_IN
[20815ms] DBG ---- Total IN: 2048 OUT: 0 CHECKSUM: 8350
[20815ms] DBG ---- STATUS: 0 GOOD
[20819ms] DBG ---- MESSAGE_IN
[20819ms] DBG ------ IN: 0x00 
[20820ms] DBG -- BUS_FREE
[21087ms] DBG -- BUS_BUSY
[21087ms] DBG -- BUS_FREE
[21350ms] DBG -- BUS_BUSY
[21350ms] DBG -- BUS_FREE
[21612ms] DBG -- BUS_BUSY
[21612ms] DBG -- BUS_FREE
[21875ms] DBG -- BUS_BUSY
[21875ms] DBG -- BUS_FREE
[22137ms] DBG -- BUS_BUSY
[22139ms] DBG -- BUS_FREE
[22140ms] DBG -- BUS_BUSY
[22141ms] DBG -- BUS_FREE
[22141ms] DBG -- BUS_BUSY
[22143ms] DBG -- BUS_FREE
[22143ms] DBG -- BUS_BUSY
[22145ms] DBG -- BUS_FREE
[22145ms] DBG -- BUS_BUSY
[22146ms] DBG -- BUS_FREE
[22146ms] DBG -- BUS_BUSY
[22148ms] DBG -- BUS_FREE
[22148ms] DBG -- BUS_BUSY
[22150ms] DBG -- BUS_FREE
[22150ms] DBG -- BUS_BUSY
[22151ms] DBG -- BUS_FREE
[22152ms] DBG -- BUS_BUSY
[22152ms] DBG ---- SELECTION: 6
[22152ms] DBG ---- MESSAGE_OUT
[22152ms] DBG ------ OUT: 0xC1 
[22153ms] DBG ---- COMMAND: Inquiry
[22153ms] DBG ------ OUT: 0x12 0x00 0x00 0x00 0x20 0x00 
[22154ms] DBG ---- DATA_IN
[22154ms] DBG ---- Total IN: 32 OUT: 0 CHECKSUM: 23224
[22154ms] DBG ---- STATUS: 0 GOOD
[22163ms] DBG ---- MESSAGE_IN
[22163ms] DBG ------ IN: 0x00 
[22164ms] DBG -- BUS_FREE
[22164ms] DBG ---- SELECTION: 6
[22164ms] DBG ---- MESSAGE_OUT
[22165ms] DBG ------ OUT: 0xC2 
[22165ms] DBG ---- COMMAND: Inquiry
[22165ms] DBG ------ OUT: 0x12 0x00 0x00 0x00 0x20 0x00 
[22166ms] DBG ---- DATA_IN
[22166ms] DBG ---- Total IN: 32 OUT: 0 CHECKSUM: 23224
[22167ms] DBG ---- STATUS: 0 GOOD
[22170ms] DBG ---- MESSAGE_IN
[22170ms] DBG ------ IN: 0x00 
[22171ms] DBG -- BUS_FREE
[22171ms] DBG ---- SELECTION: 6
[22172ms] DBG ---- MESSAGE_OUT
[22172ms] DBG ------ OUT: 0xC3 
[22172ms] DBG ---- COMMAND: Inquiry
[22173ms] DBG ------ OUT: 0x12 0x00 0x00 0x00 0x20 0x00 
[22173ms] DBG ---- DATA_IN
[22174ms] DBG ---- Total IN: 32 OUT: 0 CHECKSUM: 23224
[22174ms] DBG ---- STATUS: 0 GOOD
[22177ms] DBG ---- MESSAGE_IN
[22178ms] DBG ------ IN: 0x00 
[22179ms] DBG -- BUS_FREE
[22179ms] DBG ---- SELECTION: 6
[22179ms] DBG ---- MESSAGE_OUT
[22179ms] DBG ------ OUT: 0xC4 
[22180ms] DBG ---- COMMAND: Inquiry
[22180ms] DBG ------ OUT: 0x12 0x00 0x00 0x00 0x20 0x00 
[22181ms] DBG ---- DATA_IN
[22181ms] DBG ---- Total IN: 32 OUT: 0 CHECKSUM: 23224
[22182ms] DBG ---- STATUS: 0 GOOD
[22185ms] DBG ---- MESSAGE_IN
[22185ms] DBG ------ IN: 0x00 
[22186ms] DBG -- BUS_FREE
[22186ms] DBG ---- SELECTION: 6
[22186ms] DBG ---- MESSAGE_OUT
[22187ms] DBG ------ OUT: 0xC5 
[22187ms] DBG ---- COMMAND: Inquiry
[22187ms] DBG ------ OUT: 0x12 0x00 0x00 0x00 0x20 0x00 
[22188ms] DBG ---- DATA_IN
[22188ms] DBG ---- Total IN: 32 OUT: 0 CHECKSUM: 23224
[22189ms] DBG ---- STATUS: 0 GOOD
[22192ms] DBG ---- MESSAGE_IN
[22192ms] DBG ------ IN: 0x00 
[22193ms] DBG -- BUS_FREE
[22193ms] DBG ---- SELECTION: 6
[22194ms] DBG ---- MESSAGE_OUT
[22194ms] DBG ------ OUT: 0xC6 
[22194ms] DBG ---- COMMAND: Inquiry
[22195ms] DBG ------ OUT: 0x12 0x00 0x00 0x00 0x20 0x00 
[22195ms] DBG ---- DATA_IN
[22196ms] DBG ---- Total IN: 32 OUT: 0 CHECKSUM: 23224
[22196ms] DBG ---- STATUS: 0 GOOD
[22199ms] DBG ---- MESSAGE_IN
[22199ms] DBG ------ IN: 0x00 
[22200ms] DBG -- BUS_FREE
[22200ms] DBG ---- SELECTION: 6
[22200ms] DBG ---- MESSAGE_OUT
[22201ms] DBG ------ OUT: 0xC7 
[22201ms] DBG ---- COMMAND: Inquiry
[22201ms] DBG ------ OUT: 0x12 0x00 0x00 0x00 0x20 0x00 
[22202ms] DBG ---- DATA_IN
[22202ms] DBG ---- Total IN: 32 OUT: 0 CHECKSUM: 23224
[22203ms] DBG ---- STATUS: 0 GOOD
[22206ms] DBG ---- MESSAGE_IN
[22206ms] DBG ------ IN: 0x00 
[22207ms] DBG -- BUS_FREE
[22731ms] DBG -- BUS_BUSY
[22731ms] DBG -- BUS_FREE
[22994ms] DBG -- BUS_BUSY
[22994ms] DBG -- BUS_FREE
[23782ms] DBG -- BUS_BUSY
[23782ms] DBG -- BUS_FREE
[24307ms] DBG ---- SELECTION: 6
[24307ms] DBG ---- MESSAGE_OUT
[24307ms] DBG ------ OUT: 0xC0 
[24307ms] DBG ------ OUT: 0x01 
[24308ms] DBG ------ OUT: 0x03 
[24308ms] DBG ------ OUT: 0x01 
[24308ms] DBG ------ OUT: 0x0C 
[24309ms] DBG ------ OUT: 0x0F 
[24309ms] SCSI ID 6 negotiated synchronous mode 21 MB/s (period 4x12 ns, offset 15 bytes)
[24310ms] INFO: You may get a boost from overclocking as your system supports synchronous SCSI speeds. https://bluescsi.com/docs/Ultra-SCSI-Fast20-OverClock
[24312ms] DBG ---- MESSAGE_IN
[24312ms] DBG ------ IN: 0x01 0x03 0x01 0x0C 0x0F 
[24313ms] DBG ---- COMMAND: Inquiry
[24313ms] DBG ------ OUT: 0x12 0x00 0x00 0x00 0x20 0x00 
[24313ms] DBG ---- DATA_IN, syncOffset 15 syncPeriod 12
[24314ms] DBG ---- Total IN: 32 OUT: 0 CHECKSUM: 22980
[24314ms] DBG ---- STATUS: 0 GOOD
[24322ms] DBG ---- MESSAGE_IN
[24322ms] DBG ------ IN: 0x00 
[24323ms] DBG -- BUS_FREE
[24323ms] DBG -- BUS_BUSY
[24324ms] DBG -- BUS_FREE
[58428ms] DBG -- BUS_BUSY
[58428ms] DBG ---- SELECTION: 6
[58429ms] DBG ---- MESSAGE_OUT
[58429ms] DBG ------ OUT: 0xC0 
[58429ms] DBG ---- COMMAND: Inquiry
[58430ms] DBG ------ OUT: 0x12 0x00 0x00 0x00 0x20 0x00 
[58430ms] DBG ---- DATA_IN, syncOffset 15 syncPeriod 12
[63432ms] scsi_accel_rp2040_finishWrite() timeout
[63432ms] SCSI DMA state: WRITE
[63432ms] Current buffer: 0x00000020/0x00000020, next 0x00000000 bytes
[63433ms] SyncOffset: 15 SyncPeriod 12
[63433ms] PIO Parity SM: tx_fifo 4, rx_fifo 4, pc 29, instr 0x00004037
[63434ms] PIO Data SM: tx_fifo 4, rx_fifo 4, pc 3, instr 0x00005261
[63435ms] PIO Sync SM: tx_fifo 4, rx_fifo 0, pc 19, instr 0x0000201A
[63435ms] DMA CH A: ctrl: 0x0100B011 count: 0x00000005
[63436ms] DMA CH B: ctrl: 0x0002B809 count: 0x00000000
[63436ms] DMA CH C: ctrl: 0x01013805 count: 0x00000001
[63437ms] DMA CH D: ctrl: 0x0101C809 count: 0xFFFFFFFF
[63437ms] GPIO states: 0x06BFFA9A
[63438ms] DBG ---- Total IN: 32 OUT: 0 CHECKSUM: 22980
[63438ms] DBG ---- STATUS: 0 GOOD
[63455ms] DBG -- BUS_FREE
[63457ms] DBG ---- SELECTION: 6
[63458ms] DBG ---- MESSAGE_OUT
[78052ms] --------------
[78052ms] WATCHDOG TIMEOUT, attempting bus reset
[78053ms] Platform: BlueSCSI
[78053ms] FW Version: 2025.10.27-rel Oct 27 2025 20:32:19
[78054ms] GPIO states: out 0x16E8FCFF oe 0x0ADF8E00 in 0x16E9F9FF
[78054ms] scsiDev.cdb: 0x12 0x00 0x00 0x00 0x20 0x00 0x00 0x00 0x00 0x00 0x00 0x00 
[78055ms] scsiDev.phase: 2
[78055ms] SCSI DMA state: IDLE
[78056ms] Current buffer: 0x00000020/0x00000020, next 0x00000000 bytes
[78056ms] SyncOffset: 0 SyncPeriod 0
[78057ms] PIO Parity SM: tx_fifo 4, rx_fifo 4, pc 29, instr 0x00004037
[78057ms] PIO Data SM: tx_fifo 4, rx_fifo 4, pc 3, instr 0x00005061
[78058ms] PIO Sync SM: tx_fifo 4, rx_fifo 0, pc 19, instr 0x0000201A
[78059ms] DMA CH A: ctrl: 0x0100B011 count: 0x00000005
[78059ms] DMA CH B: ctrl: 0x0002B809 count: 0x00000000
[78060ms] DMA CH C: ctrl: 0x01013805 count: 0x00000001
[78061ms] DMA CH D: ctrl: 0x0101C809 count: 0xFFFFFFFF
[78061ms] GPIO states: 0x16E9F9FF
[78061ms] STACK 0x2003FDE0:    0x1004CCD4 0x16E9F9FF 0x2003FE14 0x20011D90
[78062ms] STACK 0x2003FDF0:    0x1004CCD4 0x00000000 0x2003FE60 0x00000000
[78063ms] STACK 0x2003FE00:    0x2002C6E4 0x0000000C 0x000003E8 0x20038B56
[78064ms] STACK 0x2003FE10:    0xD0000128 0x00000000 0x00000000 0x2000ADD5
[78064ms] STACK 0x2003FE20:    0x000101C8 0x20038B56 0x04000000 0x20011AA5
[78065ms] STACK 0x2003FE30:    0x2001C500 0x00010202 0x2001C4FC 0xFFFFFFF9
[78066ms] STACK 0x2003FE40:    0x000101C8 0x00000000 0x04000000 0xD0000000
[78067ms] STACK 0x2003FE50:    0x200054D1 0x20003E11 0x2000721A 0x61000000
[78068ms] DBG ------ OUT: 0x00 
[78069ms] DBG -- BUS_FREE