ZuluSCSI / ZuluSCSI-firmware

Firmware for the ZuluSCSI advanced SCSI emulator
https://zuluscsi.com
Other
182 stars 20 forks source link

Stability Issues RP2040 #168

Closed darkalpha84 closed 1 year ago

darkalpha84 commented 1 year ago

Greetings - i bought myself a ZuluSCSi RP2040. Im using a 256 gb Sandisk card. The device was bought to give my old P3 550 mhz new storage possibilities.

The PCI-SCSI adapter is a Tekram DC-390 with SCSI2 and sync 10MB/S transfer speed support. I'm experiencing the same issues from this post:

https://github.com/ZuluSCSI/ZuluSCSI-firmware/issues/18

Im using a 50gb hdd image which is accepted by the controller and fdisk (DOS disk setup utility) can utilize all space. Booting Windows ME only works every 2nd time. Like described in the other thread the led stays red. I have to power off the whole system and remove the PSU powercable. Booting then works. Windows 2000 can't even fnish the setup process without a bluescreen concerning the filesystem.

zuluerr.txt comes up with this:

[152ms] SCSI ID:0 BlockSize:512 Type:0 Quirks:0 ImageSize:51199155kB [253ms] Initialization complete! [34988ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [558070ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [610271ms] -------------- [610271ms] WATCHDOG TIMEOUT, attempting bus reset [610272ms] GPIO states: out 0x3BFCEEFF oe 0x178F3BFF in 0x3BFDECFF [610273ms] STACK 0x2001A990: 0x00000001 0x00000001 0x2001F80C 0x2001D908 [610273ms] STACK 0x2001A9A0: 0x000003E8 0x2000CA43 0x2000D118 0x21000000 [610274ms] STACK 0x2001A9B0: 0x2001C908 0x20003DC9 0x00000200 0x00000008 [610275ms] STACK 0x2001A9C0: 0x2001BAE0 0x00010174 0x000000CD 0x00000000 [610276ms] STACK 0x2001A9D0: 0x00010174 0x00000000 0x00000000 0x2000166B [610276ms] STACK 0x2001A9E0: 0x00000000 0x00000900 0x00000000 0x00000000 [610277ms] STACK 0x2001A9F0: 0x00000000 0x20005477 0x00000000 0x2000F0D9 [610278ms] STACK 0x2001AA00: 0x00000000 0x1002280F 0x00000000 0x1002285F [625279ms] -------------- [625279ms] WATCHDOG TIMEOUT! [625279ms] Platform: ZuluSCSI RP2040 [625280ms] FW Version: 23.03.30-release Mar 30 2023 16:53:18 [625280ms] GPIO states: out 0x3BFCEEFF oe 0x178F3BFF in 0x3BFDECFF [625281ms] STACK 0x2001A990: 0x00000000 0x2001B958 0x2001DA4B 0x2001DB07 [625282ms] STACK 0x2001A9A0: 0x000003E8 0x2000CA43 0x2000CA42 0x21000000 [625282ms] STACK 0x2001A9B0: 0x2001C908 0x20003DD9 0x00000200 0x00000008 [625283ms] STACK 0x2001A9C0: 0x2001BAE0 0x00010174 0x000000CD 0x00000000 [625284ms] STACK 0x2001A9D0: 0x00010174 0x00000000 0x00000000 0x2000166B [625285ms] STACK 0x2001A9E0: 0x00000000 0x00000900 0x00000000 0x00000000 [625285ms] STACK 0x2001A9F0: 0x00000000 0x20005477 0x00000000 0x2000F0D9 [625286ms] STACK 0x2001AA00: 0x00000000 0x1002280F 0x00000000 0x1002285F

Ive already tried disabling sync and used async mode - but no change.

Any suggestions? :)

Thanks :)

aperezbios commented 1 year ago

@darkalpha84 thanks for the report. We need to know which firmware version you're running, and the log file output you've provided is truncated, and omits that detail. Can you let us know which firmware version is currently on your ZuluSCSI? Nevermind, I see you're on 23.03.30-release

aperezbios commented 1 year ago

@PetteriAimonen can you take a look at this crash log, at your convenience? Thanks.

aperezbios commented 1 year ago

@darkalpha84 I attempted to replicate the behavior you reported, with the same firmware version, and am so far unable to replicate it. I'm using a PCI SCSI controller from the same 53C8xx family as your Tekram DC-390, which is the most similar PCI controller I have handy at the moment. if you could please provide us with a full, un-truncated Zululog.txt file, it will be helpful to us.

It's possible your SD card is not quite keeping up with the RP2040. Can you tell us which speed class it is marked as, as well as its size and make/model? Thanks!

PetteriAimonen commented 1 year ago

The crash location is in here, waiting for a data transfer to host to finish.

Would it be possible to get a debug log? This can be enabled either by dip switch, or by putting the following in zuluscsi.ini, whichever is more convenient. The debug messages then go in zululog.txt.

[SCSI]
Debug = 1

If you are in interested in trying, PrefetchBytes = 0 may make some difference in behavior.

darkalpha84 commented 1 year ago

Thank you a lot for the fast responses. Informations requested:

The sd card used was a SanDisk Ultra 256 GB Micro SD XC I with a 10 in a circle. The upper part of the sd card is white and the lower part is grey. The specs say Class 10 120 MB/S.

The 2nd card i used to test was a SanDisk Ultra 32 GB MicroSD HC I Class 10 A1 Upper part of the card is red lower part is grey. Specs say 98 MB/S Class 10 UHS Speed U1

I did some further testing and ill try to describe it as clear as possible.

The best and latest experience was with the 32 GB card. i did debug log off PrefetchBytes = 0 EnableSCSI2 = 1 EnableParity = 1 MaxSyncSpeed = 0 using an 8 gb hdd image.

I was able to install win2k. Using win2k on the first boot after PC has been off for the night worked flawless. I did stresstesting two of the partitions with scandisk at the same time and all went fine while doing other stuff. I rebooted and before i hit the desktop i got bluescreen ntfs file system error. That reminds me of the problems with windows ME. 1st boot fine, 2nd crash. Although i didnt use the log dipswitch i got some zuluerr.txt output:

[10ms] Platform: ZuluSCSI RP2040 [10ms] FW Version: 23.03.30-release Mar 30 2023 16:53:18 [11ms] DIP switch settings: debug log 0, termination 1 [11ms] SCSI termination is enabled [12ms] Flash chip size: 4096 kB [14ms] SCSI target/disk mode selected by DIP switch, acting as a SCSI disk [38ms] SDIO SD card error on line 434, error code 2 [Device had power before SD card was inserted] [41ms] SDIO SD card error on line 434, error code 2 [44ms] SDIO SD card error on line 434, error code 2 [47ms] SDIO SD card error on line 434, error code 2 [47ms] SD card init failed, sdErrorCode: 2 sdErrorData: 0 [10665ms] SD card init succeeded after retry [SD card was put in] [10665ms] SD card detected, FAT64 volume size: 29908 MB [10666ms] SD MID: 0x6F, OID: 0x03 0x03 [10666ms] SD Name: SDABC [10666ms] SD Date: 12/2022 [10667ms] SD Serial: 0xAA000124 [10668ms] Reading configuration from zuluscsi.ini [10669ms] Active configuration: [10669ms] -- SelectionDelay: 255 [10670ms] -- EnableSCSI2 is on [10672ms] -- EnableParity is on [10686ms] -- Opening /boot.img for id:0, specified in zuluscsi.ini [10686ms] ---- Read prefetch disabled [10779ms] Finding HDD images in directory /hdds/: [10779ms] Could not open directory: /hdds/ [10780ms] Finding HDD images in additional directory Dir2 = "/cds/": [10781ms] -- Could not open directory: /cds/ [10786ms] -- Platform supports ROM drive up to 3740 kB [10787ms] ---- ROM drive image not detected [10787ms] SCSI ID:0 BlockSize:512 Type:0 Quirks:0 ImageSize:8217247kB [10888ms] Initialization complete! assertion "g_scsi_dma_state == SCSIDMA_IDLE" failed: file "lib/ZuluSCSI_platform_RP2040/scsi_accel_rp2040.cpp", line 955, function: void scsi_accel_rp2040_setSyncMode(int, int)

++ MbedOS Fault Handler ++

FaultType: HardFault

Context: R0 : 000000B1 R1 : 10035656 R2 : 2001A954 R3 : 100286C5 R4 : 10032D4E R5 : 000003BB R6 : 20023828 R7 : 2001F78A R8 : 00000000 R9 : 00000000 R10 : 00000000 R11 : 00000000 R12 : 000000B0 SP : 2001A960 LR : 10029961 PC : 100286C4 xPSR : 21000000 PSP : 2001A940 MSP : 2003BFC0 CPUID: 410CC601 Mode : Thread Priv : Privileged Stack: PSP

-- MbedOS Fault Handler --

[445504ms] -------------- [445505ms] CRASH! [445505ms] Platform: ZuluSCSI RP2040 [445505ms] FW Version: 23.03.30-release Mar 30 2023 16:53:18 [445506ms] error_status: 0x80FF013D [445506ms] error_address: 0x100286C4 [445507ms] error_value: 0x2001F4EC [445507ms] STACK 0x2001A960: 0x10035649 0x10032DA2 0x10032DA2 0x00000002 [445508ms] STACK 0x2001A970: 0x00000004 0x2000D4A3 0x000100E0 0x00000002 [445508ms] STACK 0x2001A980: 0x00000004 0x20023828 0x2001F78A 0x2000C85B [445509ms] STACK 0x2001A990: 0x00000004 0x00010193 0x00000024 0x20023828 [445510ms] STACK 0x2001A9A0: 0x00010180 0x00000024 0x00010178 0x2000C907 [445511ms] STACK 0x2001A9B0: 0x20023828 0x2000A295 0x00000000 0x00000004 [445512ms] STACK 0x2001A9C0: 0x2001A9C8 0x00010174 0x000001B4 0x00000000 [445512ms] STACK 0x2001A9D0: 0x00000000 0x00000000 0x00000000 0x20001665

I did some testing with the 256 gb card [10 MB sync mode] with debug switch on before using the 32gb card, but it genereated a 27 mb logfile... I parsed it and all looked fine:

[153ms] SCSI ID:0 BlockSize:512 Type:0 Quirks:0 ImageSize:51199155kB [254ms] Initialization complete! [24982ms] DBG BUS RESET [27939ms] DBG ---- SELECTION: 0 [27940ms] DBG ---- MESSAGE_OUT [27941ms] DBG ------ OUT: 0xC0 [27941ms] DBG ------ OUT: 0x01 [27941ms] DBG ------ OUT: 0x03 [27942ms] DBG ------ OUT: 0x01 [27942ms] DBG ------ OUT: 0x19 [27942ms] DBG ------ OUT: 0x0F [27943ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [27944ms] DBG ---- MESSAGE_IN [27944ms] DBG ------ IN: 0x01 0x03 0x01 0x19 0x0F [27945ms] DBG ---- COMMAND: Inquiry [27945ms] DBG ------ OUT: 0x12 0x00 0x00 0x00 0x24 0x00 [27946ms] DBG ---- DATA_IN, syncOffset 15 syncPeriod 25 [27946ms] DBG ---- Total IN: 36 OUT: 0 CHECKSUM: 28182 [27947ms] DBG ---- STATUS: 0 GOOD [27951ms] DBG ---- MESSAGE_IN [27952ms] DBG ------ IN: 0x00 [27952ms] DBG -- BUS_FREE [27969ms] DBG -- BUS_BUSY [27974ms] DBG -- BUS_FREE [27994ms] DBG -- BUS_BUSY [27995ms] DBG -- BUS_FREE [28248ms] DBG -- BUS_BUSY [28249ms] DBG -- BUS_FREE [28278ms] DBG ---- SELECTION: 0 [28278ms] DBG ---- MESSAGE_OUT [28278ms] DBG ------ OUT: 0xC0 [28279ms] DBG ------ OUT: 0x01 [28279ms] DBG ------ OUT: 0x03 [28279ms] DBG ------ OUT: 0x01 [28280ms] DBG ------ OUT: 0x19 [28280ms] DBG ------ OUT: 0x0F [28280ms] DBG ---- MESSAGE_IN [28281ms] DBG ------ IN: 0x01 0x03 0x01 0x19 0x0F [28282ms] DBG ---- COMMAND: Inquiry [28282ms] DBG ------ OUT: 0x12 0x00 0x00 0x00 0x24 0x00 [28282ms] DBG ---- DATA_IN, syncOffset 15 syncPeriod 25 [28283ms] DBG ---- Total IN: 36 OUT: 0 CHECKSUM: 28182 [28284ms] DBG ---- STATUS: 0 GOOD [28289ms] DBG ---- MESSAGE_IN [28290ms] DBG ------ IN: 0x00 [28290ms] DBG -- BUS_FREE [28290ms] DBG ---- SELECTION: 0 [28291ms] DBG ---- MESSAGE_OUT [28291ms] DBG ------ OUT: 0xC0 [28291ms] DBG ---- COMMAND: TestUnitReady [28292ms] DBG ------ OUT: 0x00 0x00 0x00 0x00 0x00 0x00 [28292ms] DBG ---- STATUS: 0 GOOD [28296ms] DBG ---- MESSAGE_IN [28296ms] DBG ------ IN: 0x00 [28296ms] DBG -- BUS_FREE [28297ms] DBG ---- SELECTION: 0 [28297ms] DBG ---- MESSAGE_OUT [28297ms] DBG ------ OUT: 0xC0 [28298ms] DBG ---- COMMAND: ReadCapacity [28298ms] DBG ------ OUT: 0x25 0x00 0x00 0x00 0x00 0x00 [28299ms] DBG ------ OUT: 0x00 0x00 0x00 0x00 [28299ms] DBG ---- DATA_IN, syncOffset 15 syncPeriod 25 [28300ms] DBG ---- Total IN: 8 OUT: 0 CHECKSUM: 35851 [28300ms] DBG ---- STATUS: 0 GOOD [28305ms] DBG ---- MESSAGE_IN [28305ms] DBG ------ IN: 0x00 [28305ms] DBG -- BUS_FREE [28306ms] DBG ---- SELECTION: 0 [28306ms] DBG ---- MESSAGE_OUT [28306ms] DBG ------ OUT: 0xC0 [28307ms] DBG ---- COMMAND: WriteVerify [28307ms] DBG ------ OUT: 0x2E 0x00 0x00 0x00 0x00 0x04 [28308ms] DBG ------ OUT: 0x00 0x00 0x00 0x00 [28308ms] DBG ------ Write 0x512 starting at 4 [28308ms] DBG ---- DATA_OUT, syncOffset 15 syncPeriod 25 [28309ms] DBG ---- Total IN: 0 OUT: 0 CHECKSUM: 0 [28310ms] DBG ---- STATUS: 0 GOOD

I'll add a compressed version of the logfiles .

Thanks :) zululogs issue 168.zip

PetteriAimonen commented 1 year ago

Thanks for the logs!

There seems to be several things happening:

  1. Prefetch of next sector data is ignoring BUS RESET from host. Just need to add a check for that, the workaround of PrefetchBytes = 0 is good for now.
  2. The host is issuing BUS RESETs every now and then. This could be indicative of parity errors in the transfer, but it could be some other kind of communication problem.
  3. The message assertion "g_scsi_dma_state == SCSIDMA_IDLE" failed is similar to what was fixed in 4c1750726ea9a60, so probably a similar bug elsewhere.

I think there may be some underlying noise or cable problem here, which causes the host to issue a lot of retries and then it triggers bugs in the error recovery code. The error recovery bugs will be fixed, but it would be good to fix the communication problems also.

Some questions:

darkalpha84 commented 1 year ago

Again thanks for your reply :)! (1) The SCSI cable is about 50 cm long. It's the shortest ive got. It got 3 plugs only. (2) The ZULUScsi is the only device on this 50 pin bus. On the back there is an iomega jaz 2gb connected with the 50 pin 8 bit external interface. (3) The hostadapter is configured with termination on, as is the ZULU. (4) Its only buspowered - i already tried it with the 4 pin connector too, but i felt it made no difference - you want me to have it connected to the PSU?

PetteriAimonen commented 1 year ago

1) The 50 cm cable is on the long side, but should work. The longest I currently have for testing is 30 cm, I guess I could join two of them.

2-3. Ok, sounds good.

4) I think it is unlikely that a power supply issue would cause this kind of problems, so no need to test more than that.

I'll see about resolving the error recovery issues. The error rate is 0.01% in zululog32gb.txt and 0.09% in zululog32gbv2.txt, so it's not necessarily a big problem if the recovery will work ok. Detailed debugging of signal integrity issues in old equipment would require probing the lines with oscilloscope.

darkalpha84 commented 1 year ago

Just did another run with the 256 gb card. Is this here a bad thing?

[254ms] Initialization complete! [57086ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145513ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145518ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145522ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145524ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145526ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145531ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145533ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145535ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145538ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145544ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145546ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145551ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145552ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145558ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145559ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145563ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145566ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145567ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145569ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145574ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145576ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145580ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145582ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145584ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145585ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145591ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145593ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145597ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145600ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145601ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145603ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145608ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145609ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145614ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145616ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145618ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145619ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145624ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145626ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145630ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145633ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145634ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145636ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145641ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145643ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145647ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145650ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145651ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145653ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145658ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145660ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145664ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145732ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145734ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145736ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145741ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145743ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145745ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145746ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145751ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145753ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145754ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145756ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145760ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145762ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145764ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145766ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145770ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145772ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145774ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145775ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145780ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145782ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145784ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145785ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145791ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145792ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145794ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145796ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145800ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145802ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145804ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145805ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145810ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145812ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145813ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145815ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145819ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145821ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145823ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145825ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145829ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145831ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145833ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145834ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145839ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145842ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145843ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145845ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145849ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145851ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145853ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145854ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145859ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145861ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145863ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145864ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145868ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145894ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145896ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145897ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145902ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145909ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145911ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145912ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145917ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145928ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145929ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145931ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145935ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145937ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145938ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145940ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145945ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145947ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145949ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145951ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145954ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145956ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145958ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145960ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145964ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145965ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145967ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145969ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145974ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145976ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145978ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145980ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [145992ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [145994ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [146001ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [146002ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [146391ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [146395ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [159027ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [159031ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [159036ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [159048ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [159254ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [159256ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [159264ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [159266ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [159310ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [159312ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [159314ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [159316ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [159325ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [159327ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [159330ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [159332ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [159369ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [159371ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [162767ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [162777ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [162783ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [162787ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [162790ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [162792ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [162834ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [162837ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [162840ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [162842ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [162845ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [162847ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [162866ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [162868ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [162872ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [162873ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [162876ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [162878ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [164800ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [164810ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [164814ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [164818ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [165446ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [165448ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [165784ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [165785ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [165787ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [165789ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [165793ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [165795ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [165797ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [165798ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [165803ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [165804ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [165813ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [165815ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167391ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167393ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167399ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167400ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167402ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167404ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167408ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167409ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167412ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167413ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167420ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167421ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167423ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167425ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167429ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167430ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167433ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167434ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167442ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167443ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167446ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167447ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167454ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167455ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167458ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167459ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167467ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167468ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [167471ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [167472ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [170816ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [170820ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [170823ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [170824ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [170840ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [170842ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [170845ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [170846ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [170856ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [170857ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [170862ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [170864ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [170880ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [170882ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [170885ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [170886ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [172666ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [172668ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [172669ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [172671ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [172676ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [172677ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [172679ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [172680ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [172685ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [172686ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [172688ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [172690ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [198280ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [198285ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [198288ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [198290ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [206182ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [206187ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [206190ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [206192ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [206195ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [206196ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [206200ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [206202ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [206208ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [206209ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [206213ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [206215ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [208976ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [208981ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [208983ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [208984ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [208989ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [208990ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [208992ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [208993ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [208998ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [208999ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [209001ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes) [209003ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes)

PetteriAimonen commented 1 year ago

I noticed some of those messages in the debug logs as well, it seems the host wants to always execute ReadCapacity command in asynchronous mode.

It's a bit excessive to log every mode change like that, but most hosts only change the mode on boot and on error conditions. Shouldn't cause any problems except long logs.

darkalpha84 commented 1 year ago

You said - "I noticed some of those messages in the debug logs as well, it seems the host wants to always execute ReadCapacity command in asynchronous mode."

These happen with MaxSyncSpeed = 10 <- isnt that synchronous mode? Testing with MaxSyncSpeed = 0 wont produce

[208981ms] SCSI ID 0 negotiated synchronous mode 10 MB/s (period 4x25 ns, offset 15 bytes) [208983ms] SCSI ID 0 negotiated synchronous mode 0 MB/s (period 4x0 ns, offset 0 bytes)

stuff...

EDIT: No offense - I just would be a bit confused otherwise :)

PetteriAimonen commented 1 year ago

Yeah, MaxSyncSpeed is just the maximum speed. The host can negotiate a slower speed. In this case the log messages look like: Set asynchronous mode (logged as 0 MB/s), do ReadCapacity, set synchronous mode 10 MB/s. I'm not sure why the host switches modes, maybe there is some ancient drive that couldn't do ReadCapacity with sync mode on :)

I cobbled together a 60 cm long cable and ran random_tester.py on Linux at 10MB/s sync mode for a while, no errors in logs. So it's not at least directly caused by cable length. But there could be some invisible corrosion or damage on the cable, so if you have another one it might be worth trying.

Here is a test build that should avoid crashing on these error conditions: https://github.com/ZuluSCSI/ZuluSCSI-firmware/actions/runs/4596341235

darkalpha84 commented 1 year ago

Hi there - tested your firmware. Im not getting a zuluerr.txt anymore - still booting Windows ME ended in the PC shutting off. Hitting the reset button had the effect, that the zulu scsi wasnt found anymore by the SCSI controller.... I had that before and had to cut off power completley. Maybe the 50 gig hdd image is to much for the controller?

EDIT - same problems with the 8gb hdd image on the 32 gb card. Booting ME 1st time works. 2nd time blackscreen and shutdown. But maybe thats a systemproblem...

EDIT 2 - Using the 32gb card and the 8gb hdd image win2k seemes to reboot fine. Even without cutting power to the pc. Windows ME behaves strange and weird. I dont know what its doing to the scsi hostadapter or what about the system it doesnt like. Cutting the power defenetly helped winME booting better... Changing the cable had no effect at all.

Conclusion: Ill start with blank hdd images and do some deeper testing. Atm. i dont think ZULU is the source of trouble (anymore). I was able to boot dos fine and use ghost to read and write winme partition from one sd card to the other without any problems so i dont think the image file is giving the SCSI controller trouble.

I'd like to thank all of you for your support - especially Petteri for helping me a lot and brewing a new testfirmware. I hope others can also benefit from the stuff you changed.

Maybe someone else has some idea whats wrong.

Thanks for now - ill report back after deeper testing.

darkalpha84 commented 1 year ago

Hi there - tried win98 on usual IDE hdd - works perfect. As soon as im trying scsi 98 wont load after finishing installing. Stuck on the screen windows 98 is loading.

EDIT - Well i cant get any Win9x based OS work with booting from SCSI. Maybe anyone of you got some clues. Otherwise i would consider this closed because win2k seemes to work fine what makes me think its not a zulu issue but a win9x issue with booting from scsi.

aperezbios commented 1 year ago

@darkalpha84 please enable the debug DIP switch, and re-attempt to boot via SCSI. Then, once it hangs, send along the resulting zululog. We need to see the debug logs to be able to tell what's going on.

darkalpha84 commented 1 year ago

Dear @aperezbios, you asked for it - you get it :) I used a clean 32 gb sd card, clean 8gig hdd image. This log contains a complete windows me install starting with the partitioning of the harddrive until the bluescreen windows protectionfault after the setup completed the penultimate setupstep. I think it was missing the last final setuprun before windows was finally set up for the real first boot to the desktop. But setup didnt start after rebooting.

Hope it helps :) - just a 90 mb log ^^

zululog.zip

PetteriAimonen commented 1 year ago

The theory that Win98 has some kind of problem with SCSI on your system may be true. There is nothing obvious in the log this time, it still has bus resets in 0.004% of transfers but seems to recover without problems.

I think I may try Win98 installation on my test system at some point, just to see how it behaves there. But from what I remember, it wasn't that rare for Win98 to just mysteriously hang on boot back in the 1990s either :D

darkalpha84 commented 1 year ago

Cool - im really interested if it works for you.

PetteriAimonen commented 1 year ago

Looks like Win98SE setup doesn't even want to boot on my test system - perhaps it is too new (some Intel Atom from 2007). Not much more that I can do to debug this issue at the moment, but good to hear that it at least works reasonably ok with Win2k now.