nrf52840 usb_msc demo windos10 Home Edition USB disk will be lost in about 20 minutes

[Mars_xu]

I used usb_msc with nRF5SDK160098a08e2 sdk to connect the usb of pca10056 development board to win10 home version and the disk could not be recognized after about 20 minutes;

1. Current test conclusion:

 1) Appears only in win10 home edition, not in win10 professional edition;
 2) Use the Debug mode, the optimization level is set to None, the disk is displayed for 20 minutes after mounting, and the disk is not lost. When entering the secondary folder to write the file, the file cannot be written, and finally the disk is lost;

2. Test procedures

nRF5SDK160098a08e2-new-2.rar

3. Problem phenomenon
1) When the USB is connected to the computer

2) The disk is lost in about 20 minutes

4. Test method
1) Burn the compiled program
2) USB connection to win10 home version computer
2) Create TEST1/TEST2/test.txt

   
3) Write the current time in the test.txt file every 10 minutes;
4) After a few times you will find that the file cannot be written;
5) The disk has been lost;

5. The compiled hex file is convenient for you to quickly reproduce the problem;

 2425.usbd_msc_pca10056.hex

[ketiljo]

Can it be that the  USB port power is turned off after a while? There shouldn't be any difference in how Windows handles a USB drive across Windows versions. Check in Device Manager: 

[Mars_xu]

1. Test conclusion

1) I turn off the power saving option of USB hub power tube of win10 home version, and the test will still appear;

2. Other information:

1) The windows 10 home version is connected to the MCU of other four manufacturers without disk loss;

2) It can be determined that there is a difference between win10 home edition and win10 professional edition. Previously, we used MCU from other manufacturers to encounter disk loss in win10 home edition, which was finally solved by upgrading SDK;

3. Personal suggestions:

1) You find a win10 home computer, which is very easy to reproduce;

[Mars_xu]

Hi Edvin:

1. First, we open the log of the USB part and continue the test;

2. There is no USB logic analysis. How to use USB hound to monitor events;

[Edvin]

Hello,

I am not familiar with USB hound. Is it this one?

As mentioned, I have not tried it. Are you able to monitor the traffic both ways from the nRF to the computer using that FW?

BR,

Edvin

[Mars_xu]

Hi Edvin:

1. Normal log:

2. The log when the exception occurs, the command changes;

3. When I check, log printing stays here;

4. When the file is operated again, the subsequent parts are printed one after another

5. log attachment

  

Fullscreen 2352.log.txt Download

[00:00:00.113,830] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.113,830] <debug> usbd_msc: Ep proc status: 0

[00:00:00.113,830] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.049,865] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.049,865] <debug> usbd_msc: cbw_wait_start

[00:00:00.049,865] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.049,865] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.049,865] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.049,865] <debug> usbd_msc: Ep proc status: 0

[00:00:00.049,926] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.110,900] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.110,900] <debug> usbd_msc: cbw_wait_start

[00:00:00.110,961] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.110,961] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.110,961] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.110,961] <debug> usbd_msc: Ep proc status: 0

[00:00:00.110,961] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.046,997] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.046,997] <debug> usbd_msc: cbw_wait_start

[00:00:00.046,997] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.046,997] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.046,997] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.046,997] <debug> usbd_msc: Ep proc status: 0

[00:00:00.046,997] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.108,032] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.108,032] <debug> usbd_msc: cbw_wait_start

[00:00:00.108,032] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.108,032] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.108,032] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.108,032] <debug> usbd_msc: Ep proc status: 0

[00:00:00.108,093] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.044,067] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.044,067] <debug> usbd_msc: cbw_wait_start

[00:00:00.044,128] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.044,128] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.044,128] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.044,128] <debug> usbd_msc: Ep proc status: 0

[00:00:00.044,128] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.105,163] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.105,163] <debug> usbd_msc: cbw_wait_start

[00:00:00.105,163] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.105,163] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.105,163] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.105,163] <debug> usbd_msc: Ep proc status: 0

[00:00:00.105,163] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.041,198] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.041,198] <debug> usbd_msc: cbw_wait_start

[00:00:00.041,198] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.041,198] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.041,198] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.041,198] <debug> usbd_msc: Ep proc status: 0

[00:00:00.041,259] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.102,233] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.102,233] <debug> usbd_msc: cbw_wait_start

[00:00:00.102,294] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.102,294] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.102,294] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.102,294] <debug> usbd_msc: Ep proc status: 0

[00:00:00.102,294] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.038,330] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.038,330] <debug> usbd_msc: cbw_wait_start

[00:00:00.038,330] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.038,330] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.038,330] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.038,330] <debug> usbd_msc: Ep proc status: 0

[00:00:00.038,330] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.099,365] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.099,365] <debug> usbd_msc: cbw_wait_start

[00:00:00.099,365] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.099,365] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.099,365] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.099,365] <debug> usbd_msc: Ep proc status: 0

[00:00:00.099,426] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.035,400] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.035,400] <debug> usbd_msc: cbw_wait_start

[00:00:00.035,461] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.035,461] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.035,461] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.035,461] <debug> usbd_msc: Ep proc status: 0

[00:00:00.035,461] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.096,496] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.096,496] <debug> usbd_msc: cbw_wait_start

[00:00:00.096,496] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.096,496] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.096,496] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.096,496] <debug> usbd_msc: Ep proc status: 0

[00:00:00.096,496] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.032,531] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.032,531] <debug> usbd_msc: cbw_wait_start

[00:00:00.032,531] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.032,531] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.032,531] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.032,531] <debug> usbd_msc: Ep proc status: 0

[00:00:00.032,531] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.093,566] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.093,566] <debug> usbd_msc: cbw_wait_start

[00:00:00.093,627] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.093,627] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.093,627] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.093,627] <debug> usbd_msc: Ep proc status: 0

[00:00:00.093,627] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.029,663] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.029,663] <debug> usbd_msc: cbw_wait_start

[00:00:00.029,663] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.029,663] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.029,663] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.029,663] <debug> usbd_msc: Ep proc status: 0

[00:00:00.029,663] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.090,698] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.090,698] <debug> usbd_msc: cbw_wait_start

[00:00:00.090,698] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.090,698] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.090,698] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.090,698] <debug> usbd_msc: Ep proc status: 0

[00:00:00.090,698] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.026,733] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.026,733] <debug> usbd_msc: cbw_wait_start

[00:00:00.026,794] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.026,794] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.026,794] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.026,794] <debug> usbd_msc: Ep proc status: 0

[00:00:00.026,794] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.087,829] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.087,829] <debug> usbd_msc: cbw_wait_start

[00:00:00.087,829] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.087,829] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.087,829] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.087,829] <debug> usbd_msc: Ep proc status: 0

[00:00:00.087,829] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.023,864] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.023,864] <debug> usbd_msc: cbw_wait_start

[00:00:00.023,864] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.023,864] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.023,864] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.023,864] <debug> usbd_msc: Ep proc status: 0

[00:00:00.023,925] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.084,899] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.084,899] <debug> usbd_msc: cbw_wait_start

[00:00:00.084,899] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.084,899] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.084,899] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.084,899] <debug> usbd_msc: Ep proc status: 0

[00:00:00.084,960] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.020,996] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.020,996] <debug> usbd_msc: cbw_wait_start

[00:00:00.020,996] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.020,996] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.020,996] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.020,996] <debug> usbd_msc: Ep proc status: 0

[00:00:00.020,996] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.082,031] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.082,031] <debug> usbd_msc: cbw_wait_start

[00:00:00.082,031] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.082,031] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.082,031] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.082,031] <debug> usbd_msc: Ep proc status: 0

[00:00:00.082,031] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.018,066] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.018,066] <debug> usbd_msc: cbw_wait_start

[00:00:00.018,066] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.018,066] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.018,066] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.018,066] <debug> usbd_msc: Ep proc status: 0

[00:00:00.018,127] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.079,162] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.079,162] <debug> usbd_msc: cbw_wait_start

[00:00:00.079,162] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.079,162] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.079,162] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.079,162] <debug> usbd_msc: Ep proc status: 0

[00:00:00.079,162] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.015,197] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.015,197] <debug> usbd_msc: cbw_wait_start

[00:00:00.015,197] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.015,197] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.015,197] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.015,197] <debug> usbd_msc: Ep proc status: 0

[00:00:00.015,197] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.076,232] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.076,232] <debug> usbd_msc: cbw_wait_start

[00:00:00.076,232] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.076,232] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.076,232] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.076,232] <debug> usbd_msc: Ep proc status: 0

[00:00:00.076,293] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.012,329] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.012,329] <debug> usbd_msc: cbw_wait_start

[00:00:00.012,329] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.012,329] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.012,329] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.012,329] <debug> usbd_msc: Ep proc status: 0

[00:00:00.012,329] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.073,364] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.073,364] <debug> usbd_msc: cbw_wait_start

[00:00:00.073,364] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.073,364] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.073,364] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.073,364] <debug> usbd_msc: Ep proc status: 0

[00:00:00.073,364] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.009,460] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.009,460] <debug> usbd_msc: cbw_wait_start

[00:00:00.009,460] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.009,460] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.009,460] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.009,460] <debug> usbd_msc: Ep proc status: 0

[00:00:00.009,460] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.070,495] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.070,495] <debug> usbd_msc: cbw_wait_start

[00:00:00.070,495] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.070,495] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.070,495] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.070,495] <debug> usbd_msc: Ep proc status: 0

[00:00:00.070,495] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.006,530] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.006,530] <debug> usbd_msc: cbw_wait_start

[00:00:00.006,530] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.006,530] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.006,530] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.006,530] <debug> usbd_msc: Ep proc status: 0

[00:00:00.006,530] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.067,565] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.067,565] <debug> usbd_msc: cbw_wait_start

[00:00:00.067,565] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.067,565] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.067,565] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.067,565] <debug> usbd_msc: Ep proc status: 0

[00:00:00.067,626] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.003,662] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.003,662] <debug> usbd_msc: cbw_wait_start

[00:00:00.003,662] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.003,662] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.003,662] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.003,662] <debug> usbd_msc: Ep proc status: 0

[00:00:00.003,662] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.064,697] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.064,697] <debug> usbd_msc: cbw_wait_start

[00:00:00.064,697] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.064,697] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.064,697] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.064,697] <debug> usbd_msc: Ep proc status: 0

[00:00:00.064,697] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.000,732] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.000,732] <debug> usbd_msc: cbw_wait_start

[00:00:00.000,732] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.000,732] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.000,732] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.000,732] <debug> usbd_msc: Ep proc status: 0

[00:00:00.000,793] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.061,828] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.061,828] <debug> usbd_msc: cbw_wait_start

[00:00:00.061,828] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.061,828] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.061,828] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.061,828] <debug> usbd_msc: Ep proc status: 0

[00:00:00.061,828] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.122,863] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.122,863] <debug> usbd_msc: cbw_wait_start

[00:00:00.122,863] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.122,863] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.122,863] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.122,863] <debug> usbd_msc: Ep proc status: 0

[00:00:00.122,863] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.058,898] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.058,898] <debug> usbd_msc: cbw_wait_start

[00:00:00.058,898] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.058,898] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.058,898] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.058,898] <debug> usbd_msc: Ep proc status: 0

[00:00:00.058,959] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.119,995] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.119,995] <debug> usbd_msc: cbw_wait_start

[00:00:00.119,995] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.119,995] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.119,995] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.119,995] <debug> usbd_msc: Ep proc status: 0

[00:00:00.119,995] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.056,030] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.056,030] <debug> usbd_msc: cbw_wait_start

[00:00:00.056,030] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.056,030] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.056,030] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.056,030] <debug> usbd_msc: Ep proc status: 0

[00:00:00.056,030] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.117,065] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.117,065] <debug> usbd_msc: cbw_wait_start

[00:00:00.117,065] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.117,065] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.117,065] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.117,065] <debug> usbd_msc: Ep proc status: 0

[00:00:00.117,126] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.053,161] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.053,161] <debug> usbd_msc: cbw_wait_start

[00:00:00.053,161] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.053,161] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.053,161] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.053,161] <debug> usbd_msc: Ep proc status: 0

[00:00:00.053,161] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.114,196] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.114,196] <debug> usbd_msc: cbw_wait_start

[00:00:00.114,196] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.114,196] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.114,196] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.114,196] <debug> usbd_msc: Ep proc status: 0

[00:00:00.114,196] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.050,231] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.050,231] <debug> usbd_msc: cbw_wait_start

[00:00:00.050,231] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.050,231] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.050,231] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.050,231] <debug> usbd_msc: Ep proc status: 0

[00:00:00.050,292] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.111,328] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.111,328] <debug> usbd_msc: cbw_wait_start

[00:00:00.111,328] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.111,328] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.111,328] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.111,328] <debug> usbd_msc: Ep proc status: 0

[00:00:00.111,328] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.047,363] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.047,363] <debug> usbd_msc: cbw_wait_start

[00:00:00.047,363] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.047,363] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.047,363] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.047,363] <debug> usbd_msc: Ep proc status: 0

[00:00:00.047,363] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.108,398] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.108,398] <debug> usbd_msc: cbw_wait_start

[00:00:00.108,398] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.108,398] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.108,398] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.108,398] <debug> usbd_msc: Ep proc status: 0

[00:00:00.108,459] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.044,433] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.044,433] <debug> usbd_msc: cbw_wait_start

[00:00:00.044,494] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.044,494] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.044,494] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.044,494] <debug> usbd_msc: Ep proc status: 0

[00:00:00.044,494] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.105,529] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.105,529] <debug> usbd_msc: cbw_wait_start

[00:00:00.105,529] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.105,529] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.105,529] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.105,529] <debug> usbd_msc: Ep proc status: 0

[00:00:00.105,529] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.041,564] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.041,564] <debug> usbd_msc: cbw_wait_start

[00:00:00.041,564] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.041,564] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.041,564] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.041,564] <debug> usbd_msc: Ep proc status: 0

[00:00:00.041,625] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.102,661] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.102,661] <debug> usbd_msc: cbw_wait_start

[00:00:00.102,661] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.102,661] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.102,661] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.102,661] <debug> usbd_msc: Ep proc status: 0

[00:00:00.102,661] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.038,696] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.038,696] <debug> usbd_msc: cbw_wait_start

[00:00:00.038,696] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.038,696] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.038,696] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.038,696] <debug> usbd_msc: Ep proc status: 0

[00:00:00.038,696] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.099,731] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.099,731] <debug> usbd_msc: cbw_wait_start

[00:00:00.099,731] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.099,731] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.099,731] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.099,731] <debug> usbd_msc: Ep proc status: 0

[00:00:00.099,792] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.035,766] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.035,766] <debug> usbd_msc: cbw_wait_start

[00:00:00.035,766] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.035,766] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.035,766] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.035,766] <debug> usbd_msc: Ep proc status: 0

[00:00:00.035,827] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.096,862] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.096,862] <debug> usbd_msc: cbw_wait_start

[00:00:00.096,862] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.096,862] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.096,862] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.096,862] <debug> usbd_msc: Ep proc status: 0

[00:00:00.096,862] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.032,897] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.032,897] <debug> usbd_msc: cbw_wait_start

[00:00:00.032,897] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.032,897] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.032,897] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.032,897] <debug> usbd_msc: Ep proc status: 0

[00:00:00.032,958] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.093,933] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.093,933] <debug> usbd_msc: cbw_wait_start

[00:00:00.093,933] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.093,933] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.093,933] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.093,933] <debug> usbd_msc: Ep proc status: 0

[00:00:00.093,994] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.030,029] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.030,029] <debug> usbd_msc: cbw_wait_start

[00:00:00.030,029] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.030,029] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.030,029] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.030,029] <debug> usbd_msc: Ep proc status: 0

[00:00:00.030,029] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.091,064] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.091,064] <debug> usbd_msc: cbw_wait_start

[00:00:00.091,064] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.091,064] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.091,064] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.091,064] <debug> usbd_msc: Ep proc status: 0

[00:00:00.091,125] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.096,069] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.096,069] <debug> usbd_msc: cbw_wait_start

[00:00:00.096,069] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.096,069] <debug> usbd_msc: CMD: READCAPACITY10

[00:00:00.096,069] <debug> usbd_msc: transfer_in_start: p_buff: 0x20031E98, size: 8

[00:00:00.096,069] <debug> usbd_msc: Ep proc status: 0

[00:00:00.096,130] <debug> usbd_msc: state: 3, ep in event, status: 0

[00:00:00.096,130] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.096,130] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.116,760] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.116,760] <debug> usbd_msc: cbw_wait_start

[00:00:00.116,760] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.116,760] <debug> usbd_msc: CMD: READCAPACITY10

[00:00:00.116,760] <debug> usbd_msc: transfer_in_start: p_buff: 0x20031E98, size: 8

[00:00:00.116,760] <debug> usbd_msc: Ep proc status: 0

[00:00:00.116,760] <debug> usbd_msc: state: 3, ep in event, status: 0

[00:00:00.116,760] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.116,760] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.023,315] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.023,315] <debug> usbd_msc: cbw_wait_start

[00:00:00.023,315] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.023,315] <debug> usbd_msc: CMD: READCAPACITY10

[00:00:00.023,315] <debug> usbd_msc: transfer_in_start: p_buff: 0x20031E98, size: 8

[00:00:00.023,315] <debug> usbd_msc: Ep proc status: 0

[00:00:00.023,315] <debug> usbd_msc: state: 3, ep in event, status: 0

[00:00:00.023,315] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.027,099] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.027,099] <debug> usbd_msc: cbw_wait_start

[00:00:00.027,099] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.027,099] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.027,099] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.027,099] <debug> usbd_msc: Ep proc status: 0

[00:00:00.027,160] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.044,067] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.044,067] <debug> usbd_msc: cbw_wait_start

[00:00:00.044,067] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.044,067] <debug> usbd_msc: CMD: INQUIRY

[00:00:00.044,067] <debug> usbd_msc: transfer_in_start: p_buff: 0x20031E98, size: 36

[00:00:00.044,067] <debug> usbd_msc: Ep proc status: 0

[00:00:00.044,128] <debug> usbd_msc: state: 3, ep in event, status: 0

[00:00:00.044,128] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.044,128] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.044,128] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.044,128] <debug> usbd_msc: cbw_wait_start

[00:00:00.044,128] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.044,128] <debug> usbd_msc: CMD: UNSUPPORTED

[00:00:00.044,128] <debug> usbd_msc: p_msc_ctx->cbw.cdb[0]=133

[00:00:00.044,128] <debug> usbd_msc:  55 53 42 43 C0 36 95 F9|USBC.6..

[00:00:00.044,128] <debug> usbd_msc:  00 02 00 00 80 00 10 85|........

......

..... 

[00:00:00.044,128] <debug> usbd_msc: Ep proc status: 0

[00:00:00.044,189] <debug> app_usbd_core: SETUP: t: 0x02 r: 0x01

[00:00:00.044,189] <debug> usbd_msc: csw_wait_start: 1

[00:00:00.044,189] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.044,189] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.044,189] <debug> usbd_msc: cbw_wait_start

[00:00:00.044,250] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.044,250] <debug> usbd_msc: CMD: REQUESTSENSE

[00:00:00.049,682] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.055,725] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.055,725] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.055,786] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.098,327] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.104,370] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.104,370] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.104,492] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.051,269] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.057,312] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.057,312] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.057,373] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.033,508] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.039,550] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.039,550] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.039,611] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.111,450] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.117,431] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.117,492] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.117,553] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.093,688] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.099,670] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.099,731] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.099,792] <debug> usbd_msc: state: 0, ep out event, status: 1

[Mars_xu]

Hi Edvin,

1. This is the software, the log has been sent to you,
You first analyze, if necessary, use USB hound for analysis,
Capable of capturing two-way messages;

[Edvin]

Hello sir,

Message from the driver team:

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

Yes, it looks like an unsupported command with opcode 133 (0x85) is received. That command is ATA PASS-THROUGH(16), which is an extension not supported by the device. Can the customer check if adding the following code in app_usbd_msc.c changes the host's behaviour:

- Add function above state_cbw():

static ret_code_t cmd_atapassthrough(app_usbd_class_inst_t const * p_inst, app_usbd_msc_t const * p_msc, app_usbd_msc_ctx_t * p_msc_ctx)
{
    NRF_LOG_DEBUG("CMD: ATA PASS-THROUGH");
    return csw_wait_start(p_inst, APP_USBD_MSC_CSW_STATUS_PASS);
}

- In switch-case inside state_sbw(), just before default, add case:

case 0x85:
    ret = cmd_atapassthrough(p_inst, p_msc, p_msc_ctx);
    break;

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

Can you please test the suggested workaround, and see how it behaves? Check if the log prints the "CMD: ATA PASS-THROUGH" message, and if the switch-case handling solves the issue.

Best regards,

Edvin

[Mars_xu]

Hi Edvin:

The USB test has been re-run according to your suggestion, and the disk will still be lost;
The log is as follows;

Fullscreen log0618.txt Download

[00:00:00.064,514] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.064,514] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.064,514] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.064,514] <debug> usbd_msc: Ep proc status: 0

[00:00:00.064,514] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.000,549] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.000,549] <debug> usbd_msc: cbw_wait_start

[00:00:00.000,549] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.000,549] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.000,549] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.000,549] <debug> usbd_msc: Ep proc status: 0

[00:00:00.000,549] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.061,584] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.061,584] <debug> usbd_msc: cbw_wait_start

[00:00:00.061,584] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.061,584] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.061,584] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.061,584] <debug> usbd_msc: Ep proc status: 0

[00:00:00.061,645] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.122,680] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.122,680] <debug> usbd_msc: cbw_wait_start

[00:00:00.122,680] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.122,680] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.122,680] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.122,680] <debug> usbd_msc: Ep proc status: 0

[00:00:00.122,680] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.058,715] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.058,715] <debug> usbd_msc: cbw_wait_start

[00:00:00.058,715] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.058,715] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.058,715] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.058,715] <debug> usbd_msc: Ep proc status: 0

[00:00:00.058,715] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.119,750] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.119,750] <debug> usbd_msc: cbw_wait_start

[00:00:00.119,750] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.119,750] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.119,750] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.119,750] <debug> usbd_msc: Ep proc status: 0

[00:00:00.119,812] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.055,847] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.055,847] <debug> usbd_msc: cbw_wait_start

[00:00:00.055,847] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.055,847] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.055,847] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.055,847] <debug> usbd_msc: Ep proc status: 0

[00:00:00.055,847] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.116,882] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.116,882] <debug> usbd_msc: cbw_wait_start

[00:00:00.116,882] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.116,882] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.116,882] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.116,882] <debug> usbd_msc: Ep proc status: 0

[00:00:00.116,882] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.052,917] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.052,917] <debug> usbd_msc: cbw_wait_start

[00:00:00.052,917] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.052,917] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.052,917] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.052,917] <debug> usbd_msc: Ep proc status: 0

[00:00:00.052,978] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.114,013] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.114,013] <debug> usbd_msc: cbw_wait_start

[00:00:00.114,013] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.114,013] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.114,013] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.114,013] <debug> usbd_msc: Ep proc status: 0

[00:00:00.114,013] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.050,048] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.050,048] <debug> usbd_msc: cbw_wait_start

[00:00:00.050,048] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.050,048] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.050,048] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.050,048] <debug> usbd_msc: Ep proc status: 0

[00:00:00.050,048] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.111,083] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.111,083] <debug> usbd_msc: cbw_wait_start

[00:00:00.111,083] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.111,083] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.111,083] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.111,083] <debug> usbd_msc: Ep proc status: 0

[00:00:00.111,145] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.047,180] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.047,180] <debug> usbd_msc: cbw_wait_start

[00:00:00.047,180] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.047,180] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.047,180] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.047,180] <debug> usbd_msc: Ep proc status: 0

[00:00:00.047,180] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.108,215] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.108,215] <debug> usbd_msc: cbw_wait_start

[00:00:00.108,215] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.108,215] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.108,215] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.108,215] <debug> usbd_msc: Ep proc status: 0

[00:00:00.108,215] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.044,250] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.044,250] <debug> usbd_msc: cbw_wait_start

[00:00:00.044,250] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.044,250] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.044,250] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.044,250] <debug> usbd_msc: Ep proc status: 0

[00:00:00.044,311] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.105,346] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.105,346] <debug> usbd_msc: cbw_wait_start

[00:00:00.105,346] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.105,346] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.105,346] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.105,346] <debug> usbd_msc: Ep proc status: 0

[00:00:00.105,346] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.041,381] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.041,381] <debug> usbd_msc: cbw_wait_start

[00:00:00.041,381] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.041,381] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.041,381] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.041,381] <debug> usbd_msc: Ep proc status: 0

[00:00:00.041,381] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.102,416] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.102,416] <debug> usbd_msc: cbw_wait_start

[00:00:00.102,416] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.102,416] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.102,416] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.102,416] <debug> usbd_msc: Ep proc status: 0

[00:00:00.102,478] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.038,513] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.038,513] <debug> usbd_msc: cbw_wait_start

[00:00:00.038,513] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.038,513] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.038,513] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.038,513] <debug> usbd_msc: Ep proc status: 0

[00:00:00.038,513] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.099,548] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.099,548] <debug> usbd_msc: cbw_wait_start

[00:00:00.099,548] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.099,548] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.099,548] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.099,548] <debug> usbd_msc: Ep proc status: 0

[00:00:00.099,548] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.035,583] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.035,583] <debug> usbd_msc: cbw_wait_start

[00:00:00.035,583] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.035,583] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.035,583] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.035,583] <debug> usbd_msc: Ep proc status: 0

[00:00:00.035,644] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.096,679] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.096,679] <debug> usbd_msc: cbw_wait_start

[00:00:00.096,679] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.096,679] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.096,679] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.096,679] <debug> usbd_msc: Ep proc status: 0

[00:00:00.096,679] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.032,714] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.032,714] <debug> usbd_msc: cbw_wait_start

[00:00:00.032,714] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.032,714] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.032,714] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.032,714] <debug> usbd_msc: Ep proc status: 0

[00:00:00.032,714] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.093,750] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.093,750] <debug> usbd_msc: cbw_wait_start

[00:00:00.093,750] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.093,750] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.093,750] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.093,750] <debug> usbd_msc: Ep proc status: 0

[00:00:00.093,811] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.029,846] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.029,846] <debug> usbd_msc: cbw_wait_start

[00:00:00.029,846] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.029,846] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.029,846] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.029,846] <debug> usbd_msc: Ep proc status: 0

[00:00:00.029,846] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.090,881] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.090,881] <debug> usbd_msc: cbw_wait_start

[00:00:00.090,881] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.090,881] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.090,881] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.090,881] <debug> usbd_msc: Ep proc status: 0

[00:00:00.090,881] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.026,916] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.026,916] <debug> usbd_msc: cbw_wait_start

[00:00:00.026,916] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.026,916] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.026,916] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.026,916] <debug> usbd_msc: Ep proc status: 0

[00:00:00.026,977] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.088,012] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.088,012] <debug> usbd_msc: cbw_wait_start

[00:00:00.088,012] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.088,012] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.088,012] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.088,012] <debug> usbd_msc: Ep proc status: 0

[00:00:00.088,012] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.024,047] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.024,047] <debug> usbd_msc: cbw_wait_start

[00:00:00.024,047] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.024,047] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.024,047] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.024,047] <debug> usbd_msc: Ep proc status: 0

[00:00:00.024,047] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.085,083] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.085,083] <debug> usbd_msc: cbw_wait_start

[00:00:00.085,083] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.085,083] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.085,083] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.085,083] <debug> usbd_msc: Ep proc status: 0

[00:00:00.085,144] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.021,118] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.021,118] <debug> usbd_msc: cbw_wait_start

[00:00:00.021,179] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.021,179] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.021,179] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.021,179] <debug> usbd_msc: Ep proc status: 0

[00:00:00.021,179] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.082,214] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.082,214] <debug> usbd_msc: cbw_wait_start

[00:00:00.082,214] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.082,214] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.082,214] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.082,214] <debug> usbd_msc: Ep proc status: 0

[00:00:00.082,214] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.018,249] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.018,249] <debug> usbd_msc: cbw_wait_start

[00:00:00.018,249] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.018,249] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.018,249] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.018,249] <debug> usbd_msc: Ep proc status: 0

[00:00:00.018,310] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.079,284] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.079,284] <debug> usbd_msc: cbw_wait_start

[00:00:00.079,345] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.079,345] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.079,345] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.079,345] <debug> usbd_msc: Ep proc status: 0

[00:00:00.079,345] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.015,380] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.015,380] <debug> usbd_msc: cbw_wait_start

[00:00:00.015,380] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.015,380] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.015,380] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.015,380] <debug> usbd_msc: Ep proc status: 0

[00:00:00.015,380] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.076,416] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.076,416] <debug> usbd_msc: cbw_wait_start

[00:00:00.076,416] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.076,416] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.076,416] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.076,416] <debug> usbd_msc: Ep proc status: 0

[00:00:00.076,477] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.012,451] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.012,451] <debug> usbd_msc: cbw_wait_start

[00:00:00.012,512] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.012,512] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.012,512] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.012,512] <debug> usbd_msc: Ep proc status: 0

[00:00:00.012,512] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.073,547] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.073,547] <debug> usbd_msc: cbw_wait_start

[00:00:00.073,547] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.073,547] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.073,547] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.073,547] <debug> usbd_msc: Ep proc status: 0

[00:00:00.073,547] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.009,582] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.009,582] <debug> usbd_msc: cbw_wait_start

[00:00:00.009,582] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.009,582] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.009,582] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.009,582] <debug> usbd_msc: Ep proc status: 0

[00:00:00.009,643] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.070,617] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.070,617] <debug> usbd_msc: cbw_wait_start

[00:00:00.070,617] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.070,617] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.070,617] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.070,617] <debug> usbd_msc: Ep proc status: 0

[00:00:00.070,678] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.006,713] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.006,713] <debug> usbd_msc: cbw_wait_start

[00:00:00.006,713] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.006,713] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.006,713] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.006,713] <debug> usbd_msc: Ep proc status: 0

[00:00:00.006,713] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.067,749] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.067,749] <debug> usbd_msc: cbw_wait_start

[00:00:00.067,749] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.067,749] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.067,749] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.067,749] <debug> usbd_msc: Ep proc status: 0

[00:00:00.067,749] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.003,784] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.003,784] <debug> usbd_msc: cbw_wait_start

[00:00:00.003,845] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.003,845] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.003,845] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.003,845] <debug> usbd_msc: Ep proc status: 0

[00:00:00.003,845] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.064,880] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.064,880] <debug> usbd_msc: cbw_wait_start

[00:00:00.064,880] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.064,880] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.064,880] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.064,880] <debug> usbd_msc: Ep proc status: 0

[00:00:00.064,880] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.000,915] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.000,915] <debug> usbd_msc: cbw_wait_start

[00:00:00.000,915] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.000,915] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.000,915] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.000,915] <debug> usbd_msc: Ep proc status: 0

[00:00:00.000,915] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.061,950] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.061,950] <debug> usbd_msc: cbw_wait_start

[00:00:00.061,950] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.061,950] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.061,950] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.061,950] <debug> usbd_msc: Ep proc status: 0

[00:00:00.062,011] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.123,046] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.123,046] <debug> usbd_msc: cbw_wait_start

[00:00:00.123,046] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.123,046] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.123,046] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.123,046] <debug> usbd_msc: Ep proc status: 0

[00:00:00.123,046] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.059,082] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.059,082] <debug> usbd_msc: cbw_wait_start

[00:00:00.059,082] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.059,082] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.059,082] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.059,082] <debug> usbd_msc: Ep proc status: 0

[00:00:00.059,082] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.120,117] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.120,117] <debug> usbd_msc: cbw_wait_start

[00:00:00.120,117] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.120,117] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.120,117] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.120,117] <debug> usbd_msc: Ep proc status: 0

[00:00:00.120,178] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.056,213] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.056,213] <debug> usbd_msc: cbw_wait_start

[00:00:00.056,213] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.056,213] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.056,213] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.056,213] <debug> usbd_msc: Ep proc status: 0

[00:00:00.056,213] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.117,248] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.117,248] <debug> usbd_msc: cbw_wait_start

[00:00:00.117,248] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.117,248] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.117,248] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.117,248] <debug> usbd_msc: Ep proc status: 0

[00:00:00.117,248] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.053,283] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.053,283] <debug> usbd_msc: cbw_wait_start

[00:00:00.053,283] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.053,283] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.053,283] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.053,283] <debug> usbd_msc: Ep proc status: 0

[00:00:00.053,344] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.114,379] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.114,379] <debug> usbd_msc: cbw_wait_start

[00:00:00.114,379] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.114,379] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.114,379] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.114,379] <debug> usbd_msc: Ep proc status: 0

[00:00:00.114,379] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.050,415] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.050,415] <debug> usbd_msc: cbw_wait_start

[00:00:00.050,415] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.050,415] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.050,415] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.050,415] <debug> usbd_msc: Ep proc status: 0

[00:00:00.050,415] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.111,450] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.111,450] <debug> usbd_msc: cbw_wait_start

[00:00:00.111,450] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.111,450] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.111,450] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.111,450] <debug> usbd_msc: Ep proc status: 0

[00:00:00.111,511] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.041,442] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.041,442] <debug> usbd_msc: cbw_wait_start

[00:00:00.041,442] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.041,442] <debug> usbd_msc: CMD: READCAPACITY10

[00:00:00.041,442] <debug> usbd_msc: transfer_in_start: p_buff: 0x20031E98, size: 8

[00:00:00.041,442] <debug> usbd_msc: Ep proc status: 0

[00:00:00.041,442] <debug> usbd_msc: state: 3, ep in event, status: 0

[00:00:00.041,442] <debug> usbd_msc: csw_wait_start: 0

us: 0

[00:00:00.047,546] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.047,546] <debug> usbd_msc: cbw_wait_start

[00:00:00.047,546] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.047,546] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.047,546] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.047,546] <debug> usbd_msc: Ep proc status: 0

[00:00:00.047,546] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.062,072] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.062,072] <debug> usbd_msc: cbw_wait_start

[00:00:00.062,072] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.062,072] <debug> usbd_msc: CMD: READCAPACITY10

[00:00:00.062,072] <debug> usbd_msc: transfer_in_start: p_buff: 0x20031E98, size: 8

[00:00:00.062,072] <debug> usbd_msc: Ep proc status: 0

[00:00:00.062,072] <debug> usbd_msc: state: 3, ep in event, status: 0

[00:00:00.062,072] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.062,133] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.093,566] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.093,566] <debug> usbd_msc: cbw_wait_start

[00:00:00.093,566] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.093,566] <debug> usbd_msc: CMD: READCAPACITY10

[00:00:00.093,566] <debug> usbd_msc: transfer_in_start: p_buff: 0x20031E98, size: 8

[00:00:00.093,566] <debug> usbd_msc: Ep proc status: 0

[00:00:00.093,627] <debug> usbd_msc: state: 3, ep in event, status: 0

[00:00:00.093,627] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.093,627] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.108,581] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.108,581] <debug> usbd_msc: cbw_wait_start

[00:00:00.108,581] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.108,581] <debug> usbd_msc: CMD: TESTUNITREADY

[00:00:00.108,581] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.108,581] <debug> usbd_msc: Ep proc status: 0

[00:00:00.108,581] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.114,440] <debug> usbd_msc: state: 6, ep out event, status: 1

[00:00:00.114,440] <debug> usbd_msc: cbw_wait_start

[00:00:00.114,440] <debug> usbd_msc: state: 2, ep out event, status: 0

[00:00:00.114,440] <debug> usbd_msc: CMD: INQUIRY

[00:00:00.114,440] <debug> usbd_msc: transfer_in_start: p_buff: 0x20031E98, size: 36

[00:00:00.114,440] <debug> usbd_msc: Ep proc status: 0

[00:00:00.114,440] <debug> usbd_msc: state: 3, ep in event, status: 0

[00:00:00.114,440] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.114,501] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.114,501] <debug> usbd_msc: state: 6, ep out event, status: 1

tus: 0

[00:00:00.114,501] <debug> usbd_msc: CMD: ATA PASS-THROUGH

[00:00:00.114,501] <debug> usbd_msc: csw_wait_start: 0

[00:00:00.114,501] <debug> usbd_msc: Ep proc status: 0

[00:00:00.114,501] <debug> usbd_msc: state: 6, ep in event, status: 0

[00:00:00.031,982] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.038,024] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.038,024] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.038,085] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.109,985] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.115,966] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.116,027] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.116,088] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.033,630] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.039,611] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.039,672] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.039,733] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.111,572] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.117,614] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.117,614] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.117,675] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.093,811] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.099,853] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.099,853] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.099,914] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.046,813] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.052,856] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.052,917] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.052,978] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.029,113] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.035,095] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.035,156] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.035,217] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.107,055] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.113,159] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.113,159] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.018,310] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.090,148] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.096,191] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.096,191] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.096,313] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.043,151] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.049,194] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.049,194] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.049,255] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.025,390] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.031,433] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.031,433] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.031,494] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.007,629] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.013,671] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.013,671] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.013,732] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.085,632] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.091,613] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.091,674] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.091,735] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.067,871] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.073,852] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.073,913] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.074,035] <debug> usbd_msc: state: 0, ep out event, status: 1

[00:00:00.050,170] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.056,152] <debug> app_usbd_core: SETUP: t: 0x80 r: 0x06

[00:00:00.056,213] <debug> app_usbd_core: SETUP: t: 0x00 r: 0x09

[00:00:00.056,274] <debug> usbd_msc: state: 0, ep out event, status: 1

app_usbd_msc.c  file is as follows;

app_usbd_msc-1.c

[Edvin]

Hello,

I noticed that the timestamp from your log isn't chronological? Do you know the reason for that? Is the nRF resetting when the log timer resets? If so, have you checked why it resets? By the way, are the timestamps from the logging module or your terminal?

One example of this is between line 9 and 11. Is this a reset, or just the app_timer/nrf_log acting funny?

Have you tried to print something in the log on startup, and see if that occurs when the log reset the timer?

If you have a reset, can you check the reset register on restart?

[Edvin]

Hello,

I see that our R&D team is discussing whether or not they can spend resources (physical and time) to research this issue by installing the Windows version on a computer. 

In the meantime, they still struggled to understand the timestamp on the logs. Was the ATA PASS the last command issued from the USB host, or was there something after that? I am sorry if this is really obvious for you. It was not to us, unfortunately. 

From their last internal message:

"My suspicion is that there is another ATA-related command issued from Windows which we consider a fail. My candidate is the "ATA SENSE". On Google I found that some Linux versions check for SMART values every 10 seconds and this is my suspicion in this case. Maybe this Windows version does this check in a similar fashion.

Are there any other commands that are not handled? I can't see it from your logs, but I am not sure if there are any resets in the log or not.

BR,

Edvin

[Edvin]

Hello,

Did you check whether you had any resets during the runtime of the log? 

Our main suspicion is that there are still some unhandled USB events, but I don't see any NRF_LOG_DEBUG("CMD: UNSUPPORTED"), which would suggest an unhandled event in the log. Therefore, I want to know whether your log is from one take, or if you have snipped together several runs.

It would also be good if you write something like:

NRF_LOG_INFO("application start"); in the beginning of your application. Then you would know if you have any resets during the runtime.

Best regards,

Edvin