USB client mass storage detection problem on WINCE5.0

Tech Tip: Click here to run a free scan for Windows Errors and optimize PC performance

From: vcn <vincent.chardon@xxxxxxxx>
Date: Thu, 24 Jul 2008 02:19:30 -0700 (PDT)

High,

I am working on Windows CE5.0 with our own BSP for a pxa270, based on
mainstoneIII one.
l am trying to make work the USB client mass storage. ( I want my
device be recognize as a USB mass storage device not connecting a USB
key on my device).

My problem is that the USB device is sometimes detected by XP
sometimes not. (The fact it is occasionnally working indicate it is
not a configuration problem). The problem does not seems to be related
to an USB problem , but more probably to a race condition between the
usb mass storage driver and the and storage manager (fsdmgr.dll). I
have even found a way to make it work systematically :
I use the image in debug , I change the debug zone settings only for
ms2_usbfn.dll (enable "USB events"), fsdmgr.dll (Enable
"Error,Events,StoreApi") and usbmsfn.dll (Enable "Error"). With those
settings the device is successfully detected by XP within 5-6 seconds.
If I turn off "StoreAPi" in fsdmgr.dll or "USB events" in
ms2_usbfn.dll then the connexion always fail. The error I see is that
IOCTL_DISK_READ command fail. But the command is rejected because the
store handle seems to be invalid not because the flash cannot be read
(the flash driver does not receive the read command).
All these facts make me think it is not a hardware problem, but
something related to a race condition between the drivers.

I have seen several post concerning problem with that driver specially
with the PXA270 USB function driver. I have all the QFE installed
(including the mainstone BSP update on which our BSP is based). I have
also compared the sources of concerned drivers (ms2_usbfn.dll,
usbfnms.dll in particular) with the one found in WINCE 6.0, I found no
significative difference but OTG support in WINCE 6.0 which I do not
care about.
Has anybody enounters a similar problem ? Do you have any clue or any
idea how to investigate further the problem ?

See an excerpt of the trace bellow, I have including only the trace
when it works because the beginning is the same in both case the only
difference is that when it works after the command IOCTL_DISK_READ
finally succeed ...
Despite the fact it apparently works there is stranges errors in
particular the message "UsbMsFn!BOT_DeviceNotify: Failed to open
store"
I have made some cut in order to make the trace shorter. I hope I have
not removed a usefull information.

....
several retrie and error removed
....
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: configured -> detached
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x1,Enable:
0x0 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x2,Enable:
0x0 --
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_DismountStore(0x000C0F60)
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_CloseHandle(0x000C0F60)
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Attached
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: detached -> attached
PID:7fc3596 TID:47f0be6a 0x87eef748: FSDMGR:STG_OpenStore(DSK1:)
PID:a7f808d2 TID:47f0be6a 0x87eef748: OpenStore= 47d1b916
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_DeviceIoControl(0x000C0F60) IoCode=465920
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_DismountStore(0x000C0F60)
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Root
port reset
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: attached -> default
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Attached
to full speed bus.
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Set
address (255).
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: default -> addressed
PID:7fc3596 TID:87f944d2 0x87fc3000: CStore Destructor(000B8000)
PID:a7f808d2 TID:87f944d2 0x87fc3000: FMD_OEMIoControl: unrecognized
IOCTL (0x71c24).
PID:7fc3596 TID:87f944d2 0x87fc3000: Loading partition driver
mspart.dll hModule=87F0BCBC
PID:7fc3596 TID:87f944d2 0x87fc3000: Driver mspart.dll loaded
PID:7fc3596 TID:87f944d2 0x87fc3000: Opened the store hStore=000B8E20
PID:7fc3596 TID:87f944d2 0x87fc3000: NumSec=63504 BytesPerSec=512
FreeSec=2 BiggestCreatable=1
PID:7fc3596 TID:87f944d2 0x87fc3000: Partition Part00
NumSectors=63500
PID:7fc3596 TID:87f944d2 0x87fc3000: MSPART!PD_OpenPartition:
dwStoreId=000B8E20, PartName=Part00
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify:
Received setup packet: 80 06 0100 0000 0040
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ProcessGetDescriptor:
Get device descriptor request.
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x0,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!EnumTransferComplete:
18 bytes written
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!
CPipe::SendControlStatusHandshake: Sending Control Status Handshake
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x0,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Detached
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: addressed -> detached
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_DismountStore(0x000C0F60)
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_CloseHandle(0x000C0F60)
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Attached
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: detached -> attached
PID:7fc3596 TID:47f0be6a 0x87eef748: FSDMGR:STG_OpenStore(DSK1:)
PID:a7f808d2 TID:47f0be6a 0x87eef748: OpenStore= 87cee392
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_DeviceIoControl(0x000C0F60) IoCode=465920
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_DismountStore(0x000C0F60)
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_DismountStore(0x000C0F60)
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_CloseHandle(0x000C0F60)
PID:7fc3596 TID:87f944d2 0x87fc3000: CStore Destructor(000B8000)
PID:a7f808d2 TID:87f944d2 0x87fc3000: FMD_OEMIoControl: unrecognized
IOCTL (0x71c24).
PID:7fc3596 TID:87f944d2 0x87fc3000: Loading partition driver
mspart.dll hModule=87F0BCBC
PID:7fc3596 TID:87f944d2 0x87fc3000: Driver mspart.dll loaded
PID:7fc3596 TID:87f944d2 0x87fc3000: Opened the store hStore=000B8E20
PID:7fc3596 TID:87f944d2 0x87fc3000: NumSec=63504 BytesPerSec=512
FreeSec=2 BiggestCreatable=1
PID:7fc3596 TID:87f944d2 0x87fc3000: Partition Part00
NumSectors=63500
PID:7fc3596 TID:87f944d2 0x87fc3000: MSPART!PD_OpenPartition:
dwStoreId=000B8E20, PartName=Part00
PID:a7f808d2 TID:47f0be6a 0x87eef748: ERROR: c:\wince500\public\common
\oak\drivers\usbfn\class\storage\transport\bot\bot.cpp line 907:
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbMsFn!BOT_DeviceNotify: Failed
to open store
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Root
port reset
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: attached -> default
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Attached
to full speed bus.
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Set
address (255).
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: default -> addressed
PID:7fc3596 TID:87f944d2 0x87fc3000: CStore Destructor(000B8000)
PID:a7f808d2 TID:87f944d2 0x87fc3000: FMD_OEMIoControl: unrecognized
IOCTL (0x71c24).
PID:7fc3596 TID:87f944d2 0x87fc3000: Loading partition driver
mspart.dll hModule=87F0BCBC
PID:7fc3596 TID:87f944d2 0x87fc3000: Driver mspart.dll loaded
PID:7fc3596 TID:87f944d2 0x87fc3000: Opened the store hStore=000B8E20
PID:7fc3596 TID:87f944d2 0x87fc3000: NumSec=63504 BytesPerSec=512
FreeSec=2 BiggestCreatable=1
PID:7fc3596 TID:87f944d2 0x87fc3000: Partition Part00
NumSectors=63500
PID:7fc3596 TID:87f944d2 0x87fc3000: MSPART!PD_OpenPartition:
dwStoreId=000B8E20, PartName=Part00
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify:
Received setup packet: 80 06 0100 0000 0012
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ProcessGetDescriptor:
Get device descriptor request.
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x0,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!EnumTransferComplete:
18 bytes written
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!
CPipe::SendControlStatusHandshake: Sending Control Status Handshake
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x0,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify:
Received setup packet: 80 06 0200 0000 0009
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ProcessGetDescriptor:
Get configuration descriptor request.
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x0,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!EnumTransferComplete: 9
bytes written
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!
CPipe::SendControlStatusHandshake: Sending Control Status Handshake
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x0,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify:
Received setup packet: 00 09 0001 0000 0000
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!
ProcessSetConfiguration: Set configuration (1) request.
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: addressed -> configured
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x1,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x2,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x2,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!
CPipe::SendControlStatusHandshake: Sending Control Status Handshake
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify:
Received setup packet: 02 01 0000 0002 0000
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ProcessClearFeature:
Clear endpoint (0x02) halt request.
.....
A similar sequence of retries an error
.....
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x2,Enable:
0x1 --
PID:7fc3596 TID:e7ee60aa 0x87ee68bc:
FSDMGR:STG_DeviceIoControl(0x000B96A0) IoCode=482312
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: UsbMsFn!ProcessScsiRead10:
IOCTL_DISK_READ failed; error = 160
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: UsbMsFn!STORE_ExecuteCommand:
failed to execute command 0x28
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: UsbMsFn!BOT_ExecuteCB: Command :
failed
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x1,Enable:
0x1 --
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: UsbFnMdd!UfnMdd_Notify:
Received setup packet: 02 01 0000 0081 0000
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: UsbFnMdd!ProcessClearFeature:
Clear endpoint (0x81) halt request.
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: UsbFnMdd!
CPipe::SendControlStatusHandshake: Sending Control Status Handshake
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x2,Enable:
0x1 --
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x1,Enable:
0x1 --
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x1,Enable:
0x1 --
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x2,Enable:
0x1 --
PID:7fc3596 TID:e7ee60aa 0x87ee68bc:
FSDMGR:STG_DeviceIoControl(0x000B96A0) IoCode=482312
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: UsbMsFn!ProcessScsiRead10:
IOCTL_DISK_READ failed; error = 160
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: UsbMsFn!STORE_ExecuteCommand:
failed to execute command 0x28
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: UsbMsFn!BOT_ExecuteCB: Command :
failed
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x1,Enable:
0x1 --
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: UsbFnMdd!UfnMdd_Notify:
Received setup packet: 02 01 0000 0081 0000
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: UsbFnMdd!ProcessClearFeature:
Clear endpoint (0x81) halt request.
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: UsbFnMdd!
CPipe::SendControlStatusHandshake: Sending Control Status Handshake
PID:a7f808d2 TID:e7ee60aa 0x87ee68bc: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x2,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Detached
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: configured -> detached
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x1,Enable:
0x0 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x2,Enable:
0x0 --
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_DismountStore(0x000B96A0)
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_CloseHandle(0x000B96A0)
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Attached
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: detached -> attached
PID:7fc3596 TID:47f0be6a 0x87eef748: FSDMGR:STG_OpenStore(DSK1:)
PID:7fc3596 TID:87f944d2 0x87fc3000: CStore Destructor(000B8000)
PID:a7f808d2 TID:87f944d2 0x87fc3000: FMD_OEMIoControl: unrecognized
IOCTL (0x71c24).
PID:7fc3596 TID:87f944d2 0x87fc3000: Loading partition driver
mspart.dll hModule=87F0BCBC
PID:7fc3596 TID:87f944d2 0x87fc3000: Driver mspart.dll loaded
PID:7fc3596 TID:87f944d2 0x87fc3000: Opened the store hStore=000B8E20
PID:7fc3596 TID:87f944d2 0x87fc3000: NumSec=63504 BytesPerSec=512
FreeSec=2 BiggestCreatable=1
PID:7fc3596 TID:87f944d2 0x87fc3000: Partition Part00
NumSectors=63500
PID:7fc3596 TID:87f944d2 0x87fc3000: MSPART!PD_OpenPartition:
dwStoreId=000B8E20, PartName=Part00
PID:a7f808d2 TID:47f0be6a 0x87eef748: OpenStore= 87ee6b56
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_DeviceIoControl(0x000B2750) IoCode=465920
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_DismountStore(0x000B2750)
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Root
port reset
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: attached -> default
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Attached
to full speed bus.
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Set
address (255).
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: default -> addressed
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify:
Received setup packet: 80 06 0100 0000 0040
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ProcessGetDescriptor:
Get device descriptor request.
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x0,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!EnumTransferComplete:
18 bytes written
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!
CPipe::SendControlStatusHandshake: Sending Control Status Handshake
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x0,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Detached
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: addressed -> detached
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_DismountStore(0x000B2750)
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_CloseHandle(0x000B2750)
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Attached
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: detached -> attached
PID:7fc3596 TID:47f0be6a 0x87eef748: FSDMGR:STG_OpenStore(DSK1:)
PID:7fc3596 TID:87f944d2 0x87fc3000: CStore Destructor(000B8000)
PID:a7f808d2 TID:87f944d2 0x87fc3000: FMD_OEMIoControl: unrecognized
IOCTL (0x71c24).
PID:7fc3596 TID:87f944d2 0x87fc3000: Loading partition driver
mspart.dll hModule=87F0BCBC
PID:7fc3596 TID:87f944d2 0x87fc3000: Driver mspart.dll loaded
PID:7fc3596 TID:87f944d2 0x87fc3000: Opened the store hStore=000B8E20
PID:7fc3596 TID:87f944d2 0x87fc3000: NumSec=63504 BytesPerSec=512
FreeSec=2 BiggestCreatable=1
PID:7fc3596 TID:87f944d2 0x87fc3000: Partition Part00
NumSectors=63500
PID:7fc3596 TID:87f944d2 0x87fc3000: MSPART!PD_OpenPartition:
dwStoreId=000B8E20, PartName=Part00
PID:a7f808d2 TID:47f0be6a 0x87eef748: OpenStore= c7ee6b56
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_DeviceIoControl(0x000B2780) IoCode=465920
PID:7fc3596 TID:47f0be6a 0x87eef748:
FSDMGR:STG_DismountStore(0x000B2780)
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Root
port reset
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: attached -> default
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Attached
to full speed bus.
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify: Set
address (255).
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: default -> addressed
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify:
Received setup packet: 80 06 0100 0000 0012
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ProcessGetDescriptor:
Get device descriptor request.
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x0,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!EnumTransferComplete:
18 bytes written
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!
CPipe::SendControlStatusHandshake: Sending Control Status Handshake
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x0,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify:
Received setup packet: 80 06 0200 0000 0009
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ProcessGetDescriptor:
Get configuration descriptor request.
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x0,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!EnumTransferComplete: 9
bytes written
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!
CPipe::SendControlStatusHandshake: Sending Control Status Handshake
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x0,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!UfnMdd_Notify:
Received setup packet: 00 09 0001 0000 0000
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!
ProcessSetConfiguration: Set configuration (1) request.
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!ChangeDeviceState:
State change: addressed -> configured
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x1,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x2,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: bulverde_usbfn!
BulUsbDevice::EnableEndpointInterrupt: Enter. dwEndpoint:0x2,Enable:
0x1 --
PID:a7f808d2 TID:47f0be6a 0x87eef748: UsbFnMdd!
CPipe::SendControlStatusHandshake: Sending Control Status Handshake
PID:7fc3596 TID:e7ee60aa 0x87ee68bc:
FSDMGR:STG_DeviceIoControl(0x000B2780) IoCode=482312
.....
from then similar messages goes on the device is successfully working

Thank you for your help,
.

Follow-Ups:
- Re: USB client mass storage detection problem on WINCE5.0
  - From: vcn

Prev by Date: RE: Wi-Fi Authentication Test...
Next by Date: Re: USB Serial modem class driver on Windows CE 5.0
Previous by thread: RE: Wi-Fi Authentication Test...
Next by thread: Re: USB client mass storage detection problem on WINCE5.0
Index(es):
- Date
- Thread

Relevant Pages

Re: USB client mass storage detection problem on WINCE5.0
... of storage is updated in PNPThread. ... As during USB mass storage connection there are several ... destroyed while being used by usb mass storage driver. ... State change: configured -> detached ...
(microsoft.public.windowsce.platbuilder)
Re: SCSI device not created upon a CF card plug in
... GEOM should pick up the state change, but the bug may continue to exist ... CF card reader on boot will be picked up as /dev/da4 ... what to look for and that can be added to the FreeBSD umass driver. ...
(freebsd-stable)
Re: [PATCH] PCI: Add pci shutdown ability
... state change is concerned. ... in a second pass, change the power state, since after you have stopped ... Proper locking in the driver should prevent that. ...
(Linux-Kernel)