Re: [PATCH] usb: cdc-wdm: close race between read and workqueue

All of lore.kernel.org
 help / color / mirror / Atom feed

* Re: [PATCH] usb: cdc-wdm: close race between read and workqueue
       [not found] <20240314115132.3907-1-oneukum () suse ! com>
@ 2024-04-15  5:42 ` Aleksander Morgado
  2024-04-15  6:47   ` Bjørn Mork
  2024-04-15  8:42   ` Oliver Neukum
  0 siblings, 2 replies; 12+ messages in thread
From: Aleksander Morgado @ 2024-04-15  5:42 UTC (permalink / raw)
  To: oneukum, bjorn; +Cc: linux-usb, gregkh, linux, linux-kernel, ejcaruso


[-- Attachment #1.1.1: Type: text/plain, Size: 1448 bytes --]

Hey Oliver & Bjørn,

On 3/14/24 11:50, Oliver Neukum wrote:
> wdm_read() cannot race with itself. However, in
> service_outstanding_interrupt() it can race with the
> workqueue, which can be triggered by error handling.
> 
> Hence we need to make sure that the WDM_RESPONDING
> flag is not just only set but tested.
> 
> Fixes: afba937e540c9 ("USB: CDC WDM driver")
> Signed-off-by: Oliver Neukum <oneukum@suse.com>

We are not aware of all the details involved in this patch, but we had 
to revert it in all the different ChromeOS kernel versions where we had 
it cherry-picked, because it broke the MBIM communication with the Intel 
XMM based Fibocomm L850 modem. Other modems shipped in Chromebooks like 
the QC based Fibocomm FM101 don't seem to be affected.

Attached is an example output of mbimcli talking directly to the cdc-wdm 
port (i.e. without ModemManager or the mbim-proxy). In the example, we 
are receiving a bunch of different messages from previous mbimcli runs. 
Looking at the timestamps, it looks as if we only receive a message 
right after we have sent one, e.g. after each "open request" we end up 
receiving responses for requests sent in earlier runs; or something 
along those lines.

Is this bad behavior of this specific modem chipset, and if so, how can 
we workaround it? If you need any additional information or help to test 
new patches, let us know.

Cheers!

-- 
Aleksander

[-- Attachment #1.1.2: cdc-wdm-errors.txt --]
[-- Type: text/plain, Size: 15253 bytes --]

metaknight-rev4 ~ # mbimcli -d /dev/cdc-wdm0 --query-device-services --verbose-full
[11 Apr 2024, 01:13:25] [Debug] [/dev/cdc-wdm0] opening device...
[11 Apr 2024, 01:13:25] [Debug] [/dev/cdc-wdm0] queried max control message size: 512
[11 Apr 2024, 01:13:25] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:01:00:00:00:00:02:00:00


[11 Apr 2024, 01:13:25] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 1
<<<<<< Contents:
<<<<<<   max control transfer = 512


[11 Apr 2024, 01:13:25] [Debug] [/dev/cdc-wdm0] received message... (partial fragment)
>>>>>> RAW:
>>>>>>   length = 168
>>>>>>   data   = 03:00:00:80:A8:00:00:00:05:00:00:00:03:00:00:00:02:00:00:00:00:00:00:00:00:00:00:00:01:00:00:00:01:00:00:00:3D:01:DC:C5:FE:F5:4D:05:0D:3A:BE:F7:05:8E:9A:AF:00:00:00:00:00:00:00:00:04:00:00:00:03:00:00:00:04:00:00:00:06:00:00:00:09:00:00:00:C2:F6:58:8E:F0:37:4B:C9:86:65:F4:D4:4B:D0:93:67:00:00:00:00:00:00:00:00:06:00:00:00:01:00:00:00:02:00:00:00:03:00:00:00:04:00:00:00:05:00:00:00:06:00:00:00:68:22:3D:04:9F:6C:4E:0F:82:2D:28:44:1F:B7:23:40:00:00:00:00:00:00:00:00:02:00:00:00:01:00:00:00:02:00:00:00


[11 Apr 2024, 01:13:25] [Debug] [/dev/cdc-wdm0] received message fragment (translated)...
>>>>>> Header:
>>>>>>   length      = 168
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 5
>>>>>> Fragment header:
>>>>>>   total   = 3
>>>>>>   current = 2


[11 Apr 2024, 01:13:25] [Debug] [/dev/cdc-wdm0] no transaction matched in received message
[11 Apr 2024, 01:13:25] [Debug] [/dev/cdc-wdm0] received unexpected message (translated)...
>>>>>> Header:
>>>>>>   length      = 168
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 5
>>>>>> Fragment header:
>>>>>>   total   = 3
>>>>>>   current = 2


[11 Apr 2024, 01:13:30] [Debug] [/dev/cdc-wdm0] number of consecutive timeouts: 1
[11 Apr 2024, 01:13:30] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:02:00:00:00:00:02:00:00


[11 Apr 2024, 01:13:30] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 2
<<<<<< Contents:
<<<<<<   max control transfer = 512


[11 Apr 2024, 01:13:30] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 212
>>>>>>   data   = 03:00:00:80:D4:00:00:00:06:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:01:00:00:00:00:00:00:00:A4:00:00:00:01:00:00:00:01:00:00:00:01:00:00:00:02:00:00:00:3C:00:00:80:03:00:00:00:01:00:00:00:08:00:00:00:40:00:00:00:0A:00:00:00:4C:00:00:00:1E:00:00:00:6C:00:00:00:2C:00:00:00:98:00:00:00:0C:00:00:00:48:00:53:00:50:00:41:00:2B:00:00:00:38:00:36:00:37:00:35:00:38:00:30:00:30:00:35:00:30:00:37:00:37:00:39:00:34:00:31:00:35:00:00:00:31:00:38:00:35:00:30:00:30:00:2E:00:35:00:30:00:30:00:31:00:2E:00:30:00:30:00:2E:00:30:00:35:00:2E:00:32:00:37:00:2E:00:31:00:32:00:56:00:31:00:2E:00:30:00:2E:00:34:00


[11 Apr 2024, 01:13:30] [Debug] [/dev/cdc-wdm0] no transaction matched in received message
[11 Apr 2024, 01:13:30] [Debug] [/dev/cdc-wdm0] received unexpected message (translated)...
>>>>>> Header:
>>>>>>   length      = 212
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 6
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'device-caps' (0x00000001)
>>>>>> Fields:
>>>>>>   DeviceType = 'embedded'
>>>>>>   CellularClass = 'gsm'
>>>>>>   VoiceClass = 'no-voice'
>>>>>>   SimClass = 'removable'
>>>>>>   DataClass = 'umts, hsdpa, hsupa, lte, custom'
>>>>>>   SmsCaps = 'pdu-receive, pdu-send'
>>>>>>   ControlCaps = 'reg-manual'
>>>>>>   MaxSessions = '8'
>>>>>>   CustomDataClass = 'HSPA+'
>>>>>>   DeviceId = '867580050779415'
>>>>>>   FirmwareInfo = '18500.5001.00.05.27.12'
>>>>>>   HardwareInfo = 'V1.0.4'


[11 Apr 2024, 01:13:35] [Debug] [/dev/cdc-wdm0] number of consecutive timeouts: 2
[11 Apr 2024, 01:13:35] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:03:00:00:00:00:02:00:00


[11 Apr 2024, 01:13:35] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 3
<<<<<< Contents:
<<<<<<   max control transfer = 512


[11 Apr 2024, 01:13:35] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 212
>>>>>>   data   = 03:00:00:80:D4:00:00:00:07:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:01:00:00:00:00:00:00:00:A4:00:00:00:01:00:00:00:01:00:00:00:01:00:00:00:02:00:00:00:3C:00:00:80:03:00:00:00:01:00:00:00:08:00:00:00:40:00:00:00:0A:00:00:00:4C:00:00:00:1E:00:00:00:6C:00:00:00:2C:00:00:00:98:00:00:00:0C:00:00:00:48:00:53:00:50:00:41:00:2B:00:00:00:38:00:36:00:37:00:35:00:38:00:30:00:30:00:35:00:30:00:37:00:37:00:39:00:34:00:31:00:35:00:00:00:31:00:38:00:35:00:30:00:30:00:2E:00:35:00:30:00:30:00:31:00:2E:00:30:00:30:00:2E:00:30:00:35:00:2E:00:32:00:37:00:2E:00:31:00:32:00:56:00:31:00:2E:00:30:00:2E:00:34:00


[11 Apr 2024, 01:13:35] [Debug] [/dev/cdc-wdm0] no transaction matched in received message
[11 Apr 2024, 01:13:35] [Debug] [/dev/cdc-wdm0] received unexpected message (translated)...
>>>>>> Header:
>>>>>>   length      = 212
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 7
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'device-caps' (0x00000001)
>>>>>> Fields:
>>>>>>   DeviceType = 'embedded'
>>>>>>   CellularClass = 'gsm'
>>>>>>   VoiceClass = 'no-voice'
>>>>>>   SimClass = 'removable'
>>>>>>   DataClass = 'umts, hsdpa, hsupa, lte, custom'
>>>>>>   SmsCaps = 'pdu-receive, pdu-send'
>>>>>>   ControlCaps = 'reg-manual'
>>>>>>   MaxSessions = '8'
>>>>>>   CustomDataClass = 'HSPA+'
>>>>>>   DeviceId = '867580050779415'
>>>>>>   FirmwareInfo = '18500.5001.00.05.27.12'
>>>>>>   HardwareInfo = 'V1.0.4'


[11 Apr 2024, 01:13:40] [Debug] [/dev/cdc-wdm0] number of consecutive timeouts: 3
[11 Apr 2024, 01:13:40] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:04:00:00:00:00:02:00:00


[11 Apr 2024, 01:13:40] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 4
<<<<<< Contents:
<<<<<<   max control transfer = 512


[11 Apr 2024, 01:13:40] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 212
>>>>>>   data   = 03:00:00:80:D4:00:00:00:08:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:01:00:00:00:00:00:00:00:A4:00:00:00:01:00:00:00:01:00:00:00:01:00:00:00:02:00:00:00:3C:00:00:80:03:00:00:00:01:00:00:00:08:00:00:00:40:00:00:00:0A:00:00:00:4C:00:00:00:1E:00:00:00:6C:00:00:00:2C:00:00:00:98:00:00:00:0C:00:00:00:48:00:53:00:50:00:41:00:2B:00:00:00:38:00:36:00:37:00:35:00:38:00:30:00:30:00:35:00:30:00:37:00:37:00:39:00:34:00:31:00:35:00:00:00:31:00:38:00:35:00:30:00:30:00:2E:00:35:00:30:00:30:00:31:00:2E:00:30:00:30:00:2E:00:30:00:35:00:2E:00:32:00:37:00:2E:00:31:00:32:00:56:00:31:00:2E:00:30:00:2E:00:34:00


[11 Apr 2024, 01:13:40] [Debug] [/dev/cdc-wdm0] no transaction matched in received message
[11 Apr 2024, 01:13:40] [Debug] [/dev/cdc-wdm0] received unexpected message (translated)...
>>>>>> Header:
>>>>>>   length      = 212
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 8
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'device-caps' (0x00000001)
>>>>>> Fields:
>>>>>>   DeviceType = 'embedded'
>>>>>>   CellularClass = 'gsm'
>>>>>>   VoiceClass = 'no-voice'
>>>>>>   SimClass = 'removable'
>>>>>>   DataClass = 'umts, hsdpa, hsupa, lte, custom'
>>>>>>   SmsCaps = 'pdu-receive, pdu-send'
>>>>>>   ControlCaps = 'reg-manual'
>>>>>>   MaxSessions = '8'
>>>>>>   CustomDataClass = 'HSPA+'
>>>>>>   DeviceId = '867580050779415'
>>>>>>   FirmwareInfo = '18500.5001.00.05.27.12'
>>>>>>   HardwareInfo = 'V1.0.4'


[11 Apr 2024, 01:13:44] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 212
>>>>>>   data   = 03:00:00:80:D4:00:00:00:09:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:01:00:00:00:00:00:00:00:A4:00:00:00:01:00:00:00:01:00:00:00:01:00:00:00:02:00:00:00:3C:00:00:80:03:00:00:00:01:00:00:00:08:00:00:00:40:00:00:00:0A:00:00:00:4C:00:00:00:1E:00:00:00:6C:00:00:00:2C:00:00:00:98:00:00:00:0C:00:00:00:48:00:53:00:50:00:41:00:2B:00:00:00:38:00:36:00:37:00:35:00:38:00:30:00:30:00:35:00:30:00:37:00:37:00:39:00:34:00:31:00:35:00:00:00:31:00:38:00:35:00:30:00:30:00:2E:00:35:00:30:00:30:00:31:00:2E:00:30:00:30:00:2E:00:30:00:35:00:2E:00:32:00:37:00:2E:00:31:00:32:00:56:00:31:00:2E:00:30:00:2E:00:34:00


[11 Apr 2024, 01:13:44] [Debug] [/dev/cdc-wdm0] no transaction matched in received message
[11 Apr 2024, 01:13:44] [Debug] [/dev/cdc-wdm0] received unexpected message (translated)...
>>>>>> Header:
>>>>>>   length      = 212
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 9
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'device-caps' (0x00000001)
>>>>>> Fields:
>>>>>>   DeviceType = 'embedded'
>>>>>>   CellularClass = 'gsm'
>>>>>>   VoiceClass = 'no-voice'
>>>>>>   SimClass = 'removable'
>>>>>>   DataClass = 'umts, hsdpa, hsupa, lte, custom'
>>>>>>   SmsCaps = 'pdu-receive, pdu-send'
>>>>>>   ControlCaps = 'reg-manual'
>>>>>>   MaxSessions = '8'
>>>>>>   CustomDataClass = 'HSPA+'
>>>>>>   DeviceId = '867580050779415'
>>>>>>   FirmwareInfo = '18500.5001.00.05.27.12'
>>>>>>   HardwareInfo = 'V1.0.4'


[11 Apr 2024, 01:13:45] [Debug] [/dev/cdc-wdm0] number of consecutive timeouts: 4
[11 Apr 2024, 01:13:45] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:05:00:00:00:00:02:00:00


[11 Apr 2024, 01:13:45] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 5
<<<<<< Contents:
<<<<<<   max control transfer = 512


[11 Apr 2024, 01:13:45] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 212
>>>>>>   data   = 03:00:00:80:D4:00:00:00:0A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:01:00:00:00:00:00:00:00:A4:00:00:00:01:00:00:00:01:00:00:00:01:00:00:00:02:00:00:00:3C:00:00:80:03:00:00:00:01:00:00:00:08:00:00:00:40:00:00:00:0A:00:00:00:4C:00:00:00:1E:00:00:00:6C:00:00:00:2C:00:00:00:98:00:00:00:0C:00:00:00:48:00:53:00:50:00:41:00:2B:00:00:00:38:00:36:00:37:00:35:00:38:00:30:00:30:00:35:00:30:00:37:00:37:00:39:00:34:00:31:00:35:00:00:00:31:00:38:00:35:00:30:00:30:00:2E:00:35:00:30:00:30:00:31:00:2E:00:30:00:30:00:2E:00:30:00:35:00:2E:00:32:00:37:00:2E:00:31:00:32:00:56:00:31:00:2E:00:30:00:2E:00:34:00


[11 Apr 2024, 01:13:45] [Debug] [/dev/cdc-wdm0] no transaction matched in received message
[11 Apr 2024, 01:13:45] [Debug] [/dev/cdc-wdm0] received unexpected message (translated)...
>>>>>> Header:
>>>>>>   length      = 212
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 10
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'device-caps' (0x00000001)
>>>>>> Fields:
>>>>>>   DeviceType = 'embedded'
>>>>>>   CellularClass = 'gsm'
>>>>>>   VoiceClass = 'no-voice'
>>>>>>   SimClass = 'removable'
>>>>>>   DataClass = 'umts, hsdpa, hsupa, lte, custom'
>>>>>>   SmsCaps = 'pdu-receive, pdu-send'
>>>>>>   ControlCaps = 'reg-manual'
>>>>>>   MaxSessions = '8'
>>>>>>   CustomDataClass = 'HSPA+'
>>>>>>   DeviceId = '867580050779415'
>>>>>>   FirmwareInfo = '18500.5001.00.05.27.12'
>>>>>>   HardwareInfo = 'V1.0.4'


[11 Apr 2024, 01:13:50] [Debug] [/dev/cdc-wdm0] number of consecutive timeouts: 5
[11 Apr 2024, 01:13:50] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:06:00:00:00:00:02:00:00


[11 Apr 2024, 01:13:50] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 6
<<<<<< Contents:
<<<<<<   max control transfer = 512


[11 Apr 2024, 01:13:54] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 212
>>>>>>   data   = 03:00:00:80:D4:00:00:00:0D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:01:00:00:00:00:00:00:00:A4:00:00:00:01:00:00:00:01:00:00:00:01:00:00:00:02:00:00:00:3C:00:00:80:03:00:00:00:01:00:00:00:08:00:00:00:40:00:00:00:0A:00:00:00:4C:00:00:00:1E:00:00:00:6C:00:00:00:2C:00:00:00:98:00:00:00:0C:00:00:00:48:00:53:00:50:00:41:00:2B:00:00:00:38:00:36:00:37:00:35:00:38:00:30:00:30:00:35:00:30:00:37:00:37:00:39:00:34:00:31:00:35:00:00:00:31:00:38:00:35:00:30:00:30:00:2E:00:35:00:30:00:30:00:31:00:2E:00:30:00:30:00:2E:00:30:00:35:00:2E:00:32:00:37:00:2E:00:31:00:32:00:56:00:31:00:2E:00:30:00:2E:00:34:00


[11 Apr 2024, 01:13:54] [Debug] [/dev/cdc-wdm0] no transaction matched in received message
[11 Apr 2024, 01:13:54] [Debug] [/dev/cdc-wdm0] received unexpected message (translated)...
>>>>>> Header:
>>>>>>   length      = 212
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 13
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'device-caps' (0x00000001)
>>>>>> Fields:
>>>>>>   DeviceType = 'embedded'
>>>>>>   CellularClass = 'gsm'
>>>>>>   VoiceClass = 'no-voice'
>>>>>>   SimClass = 'removable'
>>>>>>   DataClass = 'umts, hsdpa, hsupa, lte, custom'
>>>>>>   SmsCaps = 'pdu-receive, pdu-send'
>>>>>>   ControlCaps = 'reg-manual'
>>>>>>   MaxSessions = '8'
>>>>>>   CustomDataClass = 'HSPA+'
>>>>>>   DeviceId = '867580050779415'
>>>>>>   FirmwareInfo = '18500.5001.00.05.27.12'
>>>>>>   HardwareInfo = 'V1.0.4'


[11 Apr 2024, 01:13:55] [Debug] [/dev/cdc-wdm0] number of consecutive timeouts: 6
[11 Apr 2024, 01:13:55] [Debug] [/dev/cdc-wdm0] open operation timed out: closed
error: couldn't open the MbimDevice: Operation timed out: device is closed


[-- Attachment #1.1.3: OpenPGP public key --]
[-- Type: application/pgp-keys, Size: 3191 bytes --]

[-- Attachment #2: OpenPGP digital signature --]
[-- Type: application/pgp-signature, Size: 840 bytes --]

^ permalink raw reply	[flat|nested] 12+ messages in thread

* Re: [PATCH] usb: cdc-wdm: close race between read and workqueue
  2024-04-15  5:42 ` [PATCH] usb: cdc-wdm: close race between read and workqueue Aleksander Morgado
@ 2024-04-15  6:47   ` Bjørn Mork
  2024-04-15  9:06     ` Oliver Neukum
  2024-04-15  8:42   ` Oliver Neukum
  1 sibling, 1 reply; 12+ messages in thread
From: Bjørn Mork @ 2024-04-15  6:47 UTC (permalink / raw)
  To: Aleksander Morgado
  Cc: oneukum, linux-usb, gregkh, linux, linux-kernel, ejcaruso

Aleksander Morgado <aleksandermj@chromium.org> writes:
> On 3/14/24 11:50, Oliver Neukum wrote:
>> wdm_read() cannot race with itself. However, in
>> service_outstanding_interrupt() it can race with the
>> workqueue, which can be triggered by error handling.
>> Hence we need to make sure that the WDM_RESPONDING
>> flag is not just only set but tested.
>> Fixes: afba937e540c9 ("USB: CDC WDM driver")
>> Signed-off-by: Oliver Neukum <oneukum@suse.com>
>
> We are not aware of all the details involved in this patch, but we had
> to revert it in all the different ChromeOS kernel versions where we
> had it cherry-picked, because it broke the MBIM communication with the
> Intel XMM based Fibocomm L850 modem. Other modems shipped in
> Chromebooks like the QC based Fibocomm FM101 don't seem to be
> affected.
>
> Attached is an example output of mbimcli talking directly to the
> cdc-wdm port (i.e. without ModemManager or the mbim-proxy). In the
> example, we are receiving a bunch of different messages from previous
> mbimcli runs. Looking at the timestamps, it looks as if we only
> receive a message right after we have sent one, e.g. after each "open
> request" we end up receiving responses for requests sent in earlier
> runs; or something along those lines.
>
> Is this bad behavior of this specific modem chipset, and if so, how
> can we workaround it? If you need any additional information or help
> to test new patches, let us know.

I'm not sure I understand what problem that patch is supposed to fix.
Which means that everything I write could be completely wrong...

But to me it looks like the described issue is exactly what you should
expect if that change ever triggers.  I believe we must resubmit the
urb from service_outstanding_interrupt(). That's why it was added. See
the explanation Robert wrote when introducing it:
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git/commit/drivers/usb/class/cdc-wdm.c?id=c1da59dad0ebd3f9bd238f3fff82b1f7ffda7829

As for the XMM behaviour: it's been a long time since I tried any of
those, but AFAIR one the major differences compared to Qualcomm was the
strict queue handling in the firmware.  This caused a number of problems
where the cdc-wdm driver wanted to skip a message for some reason.  So
I'm not surprised that a bug like this is triggered by one of those
modems. That's probably the only thing they are good for :-)

Bjørn

^ permalink raw reply	[flat|nested] 12+ messages in thread

* Re: [PATCH] usb: cdc-wdm: close race between read and workqueue
  2024-04-15  6:47   ` Bjørn Mork
@ 2024-04-15  9:06     ` Oliver Neukum
  2024-04-15  9:26       ` Bjørn Mork
  0 siblings, 1 reply; 12+ messages in thread
From: Oliver Neukum @ 2024-04-15  9:06 UTC (permalink / raw)
  To: Bjørn Mork, Aleksander Morgado
  Cc: oneukum, linux-usb, gregkh, linux, linux-kernel, ejcaruso

On 15.04.24 08:47, Bjørn Mork wrote:

> I'm not sure I understand what problem that patch is supposed to fix.
> Which means that everything I write could be completely wrong...

wdm_in_callback() can schedule service_outs_intr(), which can call
service_outstanding_interrupt(), which sets WDM_RESPONDING and submits
desc->response.
That is not problematic in itself, but wdm_read() also calls
service_outstanding_interrupt(), which can lead to teh same URB
being submitted twice (which caused me to write the patch)
or, apparently, in this case, it leads to discarding a buffer
by resubmitting and completing an URB.

> But to me it looks like the described issue is exactly what you should
> expect if that change ever triggers.  I believe we must resubmit the

Yes, it does.

> urb from service_outstanding_interrupt(). That's why it was added. See
> the explanation Robert wrote when introducing it:
> https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git/commit/drivers/usb/class/cdc-wdm.c?id=c1da59dad0ebd3f9bd238f3fff82b1f7ffda7829

Well, the explanation is correct in that we must read
data available. However, if the RESPONDING flag is set
and the URB submitted, we are already doing so.

> As for the XMM behaviour: it's been a long time since I tried any of
> those, but AFAIR one the major differences compared to Qualcomm was the
> strict queue handling in the firmware.  This caused a number of problems
> where the cdc-wdm driver wanted to skip a message for some reason.  So
> I'm not surprised that a bug like this is triggered by one of those
> modems. That's probably the only thing they are good for :-)

I am not sure where exactly the issue lies here. Suggestions for
debugging?

	Regards
		Oliver

^ permalink raw reply	[flat|nested] 12+ messages in thread

* Re: [PATCH] usb: cdc-wdm: close race between read and workqueue
  2024-04-15  9:06     ` Oliver Neukum
@ 2024-04-15  9:26       ` Bjørn Mork
  2024-04-15  9:52         ` Greg KH
  2024-04-15 10:14         ` Oliver Neukum
  0 siblings, 2 replies; 12+ messages in thread
From: Bjørn Mork @ 2024-04-15  9:26 UTC (permalink / raw)
  To: Oliver Neukum
  Cc: Aleksander Morgado, linux-usb, gregkh, linux, linux-kernel,
	ejcaruso

Oliver Neukum <oneukum@suse.com> writes:
> On 15.04.24 08:47, Bjørn Mork wrote:
>
>> urb from service_outstanding_interrupt(). That's why it was added. See
>> the explanation Robert wrote when introducing it:
>> https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git/commit/drivers/usb/class/cdc-wdm.c?id=c1da59dad0ebd3f9bd238f3fff82b1f7ffda7829
>
> Well, the explanation is correct in that we must read
> data available. However, if the RESPONDING flag is set
> and the URB submitted, we are already doing so.

Sounds reasonable.  Except that the bug proves we didn't.

If you are right that service_outstanding_interrupt can race againts
itself (and I don't doubt that), then I guess this could also happen
between failure to submit the URB and clearing the flag?

>> As for the XMM behaviour: it's been a long time since I tried any of
>> those, but AFAIR one the major differences compared to Qualcomm was the
>> strict queue handling in the firmware.  This caused a number of problems
>> where the cdc-wdm driver wanted to skip a message for some reason.  So
>> I'm not surprised that a bug like this is triggered by one of those
>> modems. That's probably the only thing they are good for :-)
>
> I am not sure where exactly the issue lies here. Suggestions for
> debugging?

Nothing more than the obvious;  Get one of those modems and do some
usbmon snooping.


Bjørn

^ permalink raw reply	[flat|nested] 12+ messages in thread

* Re: [PATCH] usb: cdc-wdm: close race between read and workqueue
  2024-04-15  9:26       ` Bjørn Mork
@ 2024-04-15  9:52         ` Greg KH
  2024-04-15 10:07           ` Oliver Neukum
  2024-04-15 10:14         ` Oliver Neukum
  1 sibling, 1 reply; 12+ messages in thread
From: Greg KH @ 2024-04-15  9:52 UTC (permalink / raw)
  To: Bjørn Mork
  Cc: Oliver Neukum, Aleksander Morgado, linux-usb, linux, linux-kernel,
	ejcaruso

On Mon, Apr 15, 2024 at 11:26:00AM +0200, Bjørn Mork wrote:
> Oliver Neukum <oneukum@suse.com> writes:
> > On 15.04.24 08:47, Bjørn Mork wrote:
> >
> >> urb from service_outstanding_interrupt(). That's why it was added. See
> >> the explanation Robert wrote when introducing it:
> >> https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git/commit/drivers/usb/class/cdc-wdm.c?id=c1da59dad0ebd3f9bd238f3fff82b1f7ffda7829
> >
> > Well, the explanation is correct in that we must read
> > data available. However, if the RESPONDING flag is set
> > and the URB submitted, we are already doing so.
> 
> Sounds reasonable.  Except that the bug proves we didn't.
> 
> If you are right that service_outstanding_interrupt can race againts
> itself (and I don't doubt that), then I guess this could also happen
> between failure to submit the URB and clearing the flag?
> 
> >> As for the XMM behaviour: it's been a long time since I tried any of
> >> those, but AFAIR one the major differences compared to Qualcomm was the
> >> strict queue handling in the firmware.  This caused a number of problems
> >> where the cdc-wdm driver wanted to skip a message for some reason.  So
> >> I'm not surprised that a bug like this is triggered by one of those
> >> modems. That's probably the only thing they are good for :-)
> >
> > I am not sure where exactly the issue lies here. Suggestions for
> > debugging?
> 
> Nothing more than the obvious;  Get one of those modems and do some
> usbmon snooping.

So for now, should we just revert this problem commit?

thanks,

greg k-h

^ permalink raw reply	[flat|nested] 12+ messages in thread

* Re: [PATCH] usb: cdc-wdm: close race between read and workqueue
  2024-04-15  9:52         ` Greg KH
@ 2024-04-15 10:07           ` Oliver Neukum
  0 siblings, 0 replies; 12+ messages in thread
From: Oliver Neukum @ 2024-04-15 10:07 UTC (permalink / raw)
  To: Greg KH, Bjørn Mork
  Cc: Oliver Neukum, Aleksander Morgado, linux-usb, linux, linux-kernel,
	ejcaruso



On 15.04.24 11:52, Greg KH wrote:
> On Mon, Apr 15, 2024 at 11:26:00AM +0200, Bjørn Mork wrote:

>> Nothing more than the obvious;  Get one of those modems and do some
>> usbmon snooping.
> 
> So for now, should we just revert this problem commit?

Yes. A regression is a regression. Please revert.

	Regards
		Oliver

		

^ permalink raw reply	[flat|nested] 12+ messages in thread

* Re: [PATCH] usb: cdc-wdm: close race between read and workqueue
  2024-04-15  9:26       ` Bjørn Mork
  2024-04-15  9:52         ` Greg KH
@ 2024-04-15 10:14         ` Oliver Neukum
  2024-04-15 10:53           ` Bjørn Mork
  1 sibling, 1 reply; 12+ messages in thread
From: Oliver Neukum @ 2024-04-15 10:14 UTC (permalink / raw)
  To: Bjørn Mork, Oliver Neukum
  Cc: Aleksander Morgado, linux-usb, gregkh, linux, linux-kernel,
	ejcaruso

On 15.04.24 11:26, Bjørn Mork wrote:
> Oliver Neukum <oneukum@suse.com> writes:
>> On 15.04.24 08:47, Bjørn Mork wrote:
>>
>>> urb from service_outstanding_interrupt(). That's why it was added. See
>>> the explanation Robert wrote when introducing it:
>>> https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git/commit/drivers/usb/class/cdc-wdm.c?id=c1da59dad0ebd3f9bd238f3fff82b1f7ffda7829
>>
>> Well, the explanation is correct in that we must read
>> data available. However, if the RESPONDING flag is set
>> and the URB submitted, we are already doing so.
> 
> Sounds reasonable.  Except that the bug proves we didn't.

Why? I am afraid I do not get that part.
  
> If you are right that service_outstanding_interrupt can race againts
> itself (and I don't doubt that), then I guess this could also happen
> between failure to submit the URB and clearing the flag?

Yes, it can. In fact in this case the behavior should not change.
I am afraid we have a misunderstanding. It seems to me that in the
unchanged driver the result of service_outstanding_interrupt()
is undefined.
Please explain.

	Regards
		Oliver



^ permalink raw reply	[flat|nested] 12+ messages in thread

* Re: [PATCH] usb: cdc-wdm: close race between read and workqueue
  2024-04-15 10:14         ` Oliver Neukum
@ 2024-04-15 10:53           ` Bjørn Mork
  2024-04-15 11:08             ` Oliver Neukum
  0 siblings, 1 reply; 12+ messages in thread
From: Bjørn Mork @ 2024-04-15 10:53 UTC (permalink / raw)
  To: Oliver Neukum
  Cc: Aleksander Morgado, linux-usb, gregkh, linux, linux-kernel,
	ejcaruso

Oliver Neukum <oneukum@suse.com> writes:
> On 15.04.24 11:26, Bjørn Mork wrote:
>> Oliver Neukum <oneukum@suse.com> writes:
>>> On 15.04.24 08:47, Bjørn Mork wrote:
>>>
>>>> urb from service_outstanding_interrupt(). That's why it was added. See
>>>> the explanation Robert wrote when introducing it:
>>>> https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git/commit/drivers/usb/class/cdc-wdm.c?id=c1da59dad0ebd3f9bd238f3fff82b1f7ffda7829
>>>
>>> Well, the explanation is correct in that we must read
>>> data available. However, if the RESPONDING flag is set
>>> and the URB submitted, we are already doing so.
>> Sounds reasonable.  Except that the bug proves we didn't.
>
> Why? I am afraid I do not get that part.

I don't get how it happens either.  But that's the only thing changed by
the patch.

>  > If you are right that service_outstanding_interrupt can race
>   againts
>> itself (and I don't doubt that), then I guess this could also happen
>> between failure to submit the URB and clearing the flag?
>
> Yes, it can. In fact in this case the behavior should not change.
> I am afraid we have a misunderstanding. It seems to me that in the
> unchanged driver the result of service_outstanding_interrupt()
> is undefined.
> Please explain.

Sorry, I am so lost here that I am probably only confusing things.  I do
not understand why we unlock &desc->iuspin around the usb_submit_urb
call.  And git tells me I wrote that.


Bjørn

^ permalink raw reply	[flat|nested] 12+ messages in thread

* Re: [PATCH] usb: cdc-wdm: close race between read and workqueue
  2024-04-15 10:53           ` Bjørn Mork
@ 2024-04-15 11:08             ` Oliver Neukum
  0 siblings, 0 replies; 12+ messages in thread
From: Oliver Neukum @ 2024-04-15 11:08 UTC (permalink / raw)
  To: Bjørn Mork, Oliver Neukum
  Cc: Aleksander Morgado, linux-usb, gregkh, linux, linux-kernel,
	ejcaruso



On 15.04.24 12:53, Bjørn Mork wrote:
> Oliver Neukum <oneukum@suse.com> writes:
>> On 15.04.24 11:26, Bjørn Mork wrote:
>>> Oliver Neukum <oneukum@suse.com> writes:
>>>> On 15.04.24 08:47, Bjørn Mork wrote:
>>>>
>>>>> urb from service_outstanding_interrupt(). That's why it was added. See
>>>>> the explanation Robert wrote when introducing it:
>>>>> https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git/commit/drivers/usb/class/cdc-wdm.c?id=c1da59dad0ebd3f9bd238f3fff82b1f7ffda7829
>>>>
>>>> Well, the explanation is correct in that we must read
>>>> data available. However, if the RESPONDING flag is set
>>>> and the URB submitted, we are already doing so.
>>> Sounds reasonable.  Except that the bug proves we didn't.
>>
>> Why? I am afraid I do not get that part.
> 
> I don't get how it happens either.  But that's the only thing changed by
> the patch.

Now you have lost me. I agree that this is the only thing that the patch
changes, but how do you derive the consequences from that?

>>   > If you are right that service_outstanding_interrupt can race
>>    againts
>>> itself (and I don't doubt that), then I guess this could also happen
>>> between failure to submit the URB and clearing the flag?
>>
>> Yes, it can. In fact in this case the behavior should not change.
>> I am afraid we have a misunderstanding. It seems to me that in the
>> unchanged driver the result of service_outstanding_interrupt()
>> is undefined.
>> Please explain.
> 
> Sorry, I am so lost here that I am probably only confusing things.  I doresp_count
> not understand why we unlock &desc->iuspin around the usb_submit_urb
> call.  And git tells me I wrote that.

Dropping iuspin there allowed you to call usb_submit_urb() with GFP_KERNEL.
clear_wdm_read_flag(), as it then existed, could not race with itself because
its only caller wdm_read() is holding a mutex.

That, however, is not very material to the question at hand. iuspin at that
time protected only resp_count. Even today the URB itself is protected by
WDM_RESPONDING. (Which is why I think that test_and_set_bit is required)

Now, if we say that service_outstanding_interrupt() is racing with itself,
we have to ask why this is helpful. Do we at least agree that the regression
Aleksander is seeing is due to the removal of a race or are we looking at a side effect?

	Regards
		Oliver





^ permalink raw reply	[flat|nested] 12+ messages in thread

* Re: [PATCH] usb: cdc-wdm: close race between read and workqueue
  2024-04-15  5:42 ` [PATCH] usb: cdc-wdm: close race between read and workqueue Aleksander Morgado
  2024-04-15  6:47   ` Bjørn Mork
@ 2024-04-15  8:42   ` Oliver Neukum
  2024-04-15 12:08     ` Aleksander Morgado
  1 sibling, 1 reply; 12+ messages in thread
From: Oliver Neukum @ 2024-04-15  8:42 UTC (permalink / raw)
  To: Aleksander Morgado, oneukum, bjorn
  Cc: linux-usb, gregkh, linux, linux-kernel, ejcaruso



On 15.04.24 07:42, Aleksander Morgado wrote:

Hi,

> We are not aware of all the details involved in this patch,

I had gotten bug reports about resubmitting an active URB.

> but we had to revert it in all the different ChromeOS kernel versions where we had it cherry-picked, because it broke the MBIM communication with the Intel XMM based Fibocomm L850 modem. > Other modems shipped in Chromebooks like the QC based Fibocomm FM101 don't seem to be affected.

That is odd.

> Attached is an example output of mbimcli talking directly to the cdc-wdm port (i.e. without ModemManager or the mbim-proxy).

Could you provide a working example, that is with another chipset? And, most important, dmesg for both cases with
the log level set to maximum?

> In the example, we are receiving a bunch of different messages from previous mbimcli runs. Looking at the timestamps, it looks as if we only receive a message right after we have sent one, e.g. after each "open request" we end up receiving responses for requests sent in earlier runs; or something along those lines.

It looks like you are hitting the race later than my bug reporters, which means
that the submission works and we do not overwrite the buffer.
  
> Is this bad behavior of this specific modem chipset, and if so, how can we workaround it? If you need any additional information or help to test new patches, let us know.

Generally losing data is bad, so I cannot readily tell.
Please provide data for the working case.

	Regards
		Oliver


^ permalink raw reply	[flat|nested] 12+ messages in thread

* Re: [PATCH] usb: cdc-wdm: close race between read and workqueue
  2024-04-15  8:42   ` Oliver Neukum
@ 2024-04-15 12:08     ` Aleksander Morgado
  0 siblings, 0 replies; 12+ messages in thread
From: Aleksander Morgado @ 2024-04-15 12:08 UTC (permalink / raw)
  To: Oliver Neukum, bjorn; +Cc: linux-usb, gregkh, linux, linux-kernel, ejcaruso

[-- Attachment #1: Type: text/plain, Size: 738 bytes --]

Hey Oliver,

>> Attached is an example output of mbimcli talking directly to the 
>> cdc-wdm port (i.e. without ModemManager or the mbim-proxy).
> 
> Could you provide a working example, that is with another chipset? And, 
> most important, dmesg for both cases with
> the log level set to maximum?
> 

Find attached a bunch of different logs. There are 3 cases tested:
  * FM101 (dmesg and mbimcli outputs), this shows the communication 
working in a QC based modem.
  * L850 (usbmon, dmesg and mbimcli outputs), this shows the break in 
the communication.
  * L850 with patch reverted (usbmon, dmesg and mbimcli outputs), this 
shows the communication working fine again.


Let me know if you need anything else.
Cheers!

-- 
Aleksander

[-- Attachment #2: dmesg-fm101.txt --]
[-- Type: text/plain, Size: 1454 bytes --]

[  385.258734] cdc_mbim 2-4:1.0: wdm176_resume
[  385.259227] cdc_mbim 2-4:1.0: Tx URB has been submitted index=0
[  385.739244] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  385.739311] cdc_mbim 2-4:1.0: submit response URB 0
[  385.739961] cdc_mbim 2-4:1.0: Tx URB has been submitted index=0
[  385.771086] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  385.771150] cdc_mbim 2-4:1.0: submit response URB 0
[  385.774475] cdc_mbim 2-4:1.0: Tx URB has been submitted index=0
[  385.802972] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  385.803012] cdc_mbim 2-4:1.0: submit response URB 0
[  385.834985] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  385.835031] cdc_mbim 2-4:1.0: submit response URB 0
[  385.867021] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  385.867066] cdc_mbim 2-4:1.0: submit response URB 0
[  385.899232] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  385.899298] cdc_mbim 2-4:1.0: submit response URB 0
[  385.931287] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  385.931353] cdc_mbim 2-4:1.0: submit response URB 0
[  385.963031] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  385.963094] cdc_mbim 2-4:1.0: submit response URB 0
[  385.963558] cdc_mbim 2-4:1.0: wdm_release: cleanup
[  388.463762] cdc_mbim 2-4:1.0: wdm176_suspend

[-- Attachment #3: dmesg-l850.txt --]
[-- Type: text/plain, Size: 1949 bytes --]

[  570.389769] cdc_mbim 2-4:1.0: wdm176_resume
[  570.390183] cdc_mbim 2-4:1.0: Tx URB has been submitted index=0
[  570.394157] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  570.394192] cdc_mbim 2-4:1.0: submit response URB 0
[  572.889554] cdc_mbim 2-4:1.0: wdm176_suspend
[  575.491796] cdc_mbim 2-4:1.0: wdm176_resume
[  575.491906] cdc_mbim 2-4:1.0: Tx URB has been submitted index=0
[  575.495150] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  575.495190] cdc_mbim 2-4:1.0: submit response URB 0
[  577.991555] cdc_mbim 2-4:1.0: wdm176_suspend
[  580.497675] cdc_mbim 2-4:1.0: wdm176_resume
[  580.497767] cdc_mbim 2-4:1.0: Tx URB has been submitted index=0
[  580.501162] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  580.501200] cdc_mbim 2-4:1.0: submit response URB 0
[  582.997543] cdc_mbim 2-4:1.0: wdm176_suspend
[  585.503709] cdc_mbim 2-4:1.0: wdm176_resume
[  585.503796] cdc_mbim 2-4:1.0: Tx URB has been submitted index=0
[  585.507158] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  585.507192] cdc_mbim 2-4:1.0: submit response URB 0
[  588.003551] cdc_mbim 2-4:1.0: wdm176_suspend
[  590.509818] cdc_mbim 2-4:1.0: wdm176_resume
[  590.509888] cdc_mbim 2-4:1.0: Tx URB has been submitted index=0
[  590.513187] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  590.513222] cdc_mbim 2-4:1.0: submit response URB 0
[  593.009566] cdc_mbim 2-4:1.0: wdm176_suspend
[  595.515751] cdc_mbim 2-4:1.0: wdm176_resume
[  595.515859] cdc_mbim 2-4:1.0: Tx URB has been submitted index=0
[  595.519184] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  595.519237] cdc_mbim 2-4:1.0: submit response URB 0
[  598.015553] cdc_mbim 2-4:1.0: wdm176_suspend
[  600.423422] cdc_mbim 2-4:1.0: wdm_release: cleanup
[  600.519836] cdc_mbim 2-4:1.0: wdm176_resume
[  602.528574] cdc_mbim 2-4:1.0: wdm176_suspend

[-- Attachment #4: dmesg-l850-patch-reverted.txt --]
[-- Type: text/plain, Size: 1040 bytes --]

[  242.043806] cdc_mbim 2-4:1.0: wdm176_resume
[  242.044181] cdc_mbim 2-4:1.0: Tx URB has been submitted index=0
[  242.047203] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  242.047247] cdc_mbim 2-4:1.0: submit response URB 0
[  242.048864] cdc_mbim 2-4:1.0: Tx URB has been submitted index=0
[  242.061188] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  242.061228] cdc_mbim 2-4:1.0: submit response URB 0
[  242.062174] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  242.062208] cdc_mbim 2-4:1.0: submit response URB 0
[  242.063162] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  242.063194] cdc_mbim 2-4:1.0: submit response URB 0
[  242.064942] cdc_mbim 2-4:1.0: Tx URB has been submitted index=0
[  242.066224] cdc_mbim 2-4:1.0: NOTIFY_RESPONSE_AVAILABLE received: index 0 len 0
[  242.066259] cdc_mbim 2-4:1.0: submit response URB 0
[  242.066861] cdc_mbim 2-4:1.0: wdm_release: cleanup
[  244.543728] cdc_mbim 2-4:1.0: wdm176_suspend

[-- Attachment #5: mbimcli-fm101.txt --]
[-- Type: text/plain, Size: 18250 bytes --]

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] opening device...
[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] queried max control message size: 4096
[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:01:00:00:00...

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 1
<<<<<< Contents:
<<<<<<   max control transfer = 4096

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 16
>>>>>>   data   = 01:00:00:80:10:00:00:00:01:00:00:00...

[15 Apr 2024, 04:17:16] [Debug] MBIM Device at '/dev/cdc-wdm0' ready
[15 Apr 2024, 04:17:16] [Debug] Asynchronously querying device services...
[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 48
<<<<<<   data   = 03:00:00:00:30:00:00:00:02:00:00:00...

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 48
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 2
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'device-services' (0x00000010)
<<<<<<   type    = 'query' (0x00000000)

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 1172
>>>>>>   data   = 03:00:00:80:94:04:00:00:02:00:00:00...

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message (translated)...
>>>>>> Header:
>>>>>>   length      = 1172
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 2
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'device-services' (0x00000010)
>>>>>> Fields:
>>>>>>   DeviceServicesCount = '21'
>>>>>>   MaxDssSessions = '0'
>>>>>>   DeviceServices = '{
>>>>>>     [0] = {
>>>>>>           DeviceServiceId = 'a289cc33-bcbb-8b4f-b6b0-133ec2aae6df'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '20'
>>>>>>           Cids = '1,2,3,4,5,6,7,8,9,10,11,12,13,15,16,19,20,21,22,23'
>>>>>>     },
>>>>>>     [1] = {
>>>>>>           DeviceServiceId = '533fbeeb-14fe-4467-9f90-33a223e56c3f'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '5'
>>>>>>           Cids = '1,2,3,4,5'
>>>>>>     },
>>>>>>     [2] = {
>>>>>>           DeviceServiceId = 'e550a0c8-5e82-479e-82f7-10abf4c3351f'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [3] = {
>>>>>>           DeviceServiceId = '4bf38476-1e6a-41db-b1d8-bed289c25bdb'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '4'
>>>>>>           Cids = '1,2,3,4'
>>>>>>     },
>>>>>>     [4] = {
>>>>>>           DeviceServiceId = 'd8f20131-fcb5-4e17-8602-d6ed3816164c'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '3'
>>>>>>           Cids = '1,2,3'
>>>>>>     },
>>>>>>     [5] = {
>>>>>>           DeviceServiceId = '1d2b5ff7-0aa1-48b2-aa52-50f15767174e'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '2'
>>>>>>           Cids = '1,3'
>>>>>>     },
>>>>>>     [6] = {
>>>>>>           DeviceServiceId = 'd1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [7] = {
>>>>>>           DeviceServiceId = '883b7c26-985f-43fa-9804-27d7fb80959c'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '2'
>>>>>>           Cids = '1,2'
>>>>>>     },
>>>>>>     [8] = {
>>>>>>           DeviceServiceId = '2d0c12c9-0e6a-495a-915c-8d174fe5d63c'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '4'
>>>>>>           Cids = '1,2,3,4'
>>>>>>     },
>>>>>>     [9] = {
>>>>>>           DeviceServiceId = 'e9f7dea2-feaf-4009-93ce-90a3694103b6'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [10] = {
>>>>>>           DeviceServiceId = '5967bdcc-7fd2-49a2-9f5c-b2e70e527db3'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '6'
>>>>>>           Cids = '1,2,3,4,9,10'
>>>>>>     },
>>>>>>     [11] = {
>>>>>>           DeviceServiceId = '6427015f-579d-48f5-8c54-f43ed1e76f83'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '3'
>>>>>>           Cids = '1,2,3'
>>>>>>     },
>>>>>>     [12] = {
>>>>>>           DeviceServiceId = 'c2f6588e-f037-4bc9-8665-f4d44bd09367'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '10'
>>>>>>           Cids = '1,2,3,4,5,6,7,8,9,10'
>>>>>>     },
>>>>>>     [13] = {
>>>>>>           DeviceServiceId = '3d01dcc5-fef5-4d05-0d3a-bef7058e9aaf'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '14'
>>>>>>           Cids = '1,3,4,5,6,7,8,9,10,11,12,14,15,16'
>>>>>>     },
>>>>>>     [14] = {
>>>>>>           DeviceServiceId = '68223d04-9f6c-4e0f-822d-28441fb72340'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '2'
>>>>>>           Cids = '1,2'
>>>>>>     },
>>>>>>     [15] = {
>>>>>>           DeviceServiceId = '8d8b9eba-37be-449b-8f1e-61cb034a702e'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '10'
>>>>>>     },
>>>>>>     [16] = {
>>>>>>           DeviceServiceId = 'cfd497ea-f0ae-4f71-9aca-14266eaef198'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '7'
>>>>>>           Cids = '1,2,3,4,5,6,7'
>>>>>>     },
>>>>>>     [17] = {
>>>>>>           DeviceServiceId = 'fbc51292-74bb-484a-abb3-5bf133f70b50'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [18] = {
>>>>>>           DeviceServiceId = '3e1e92cf-c53d-4f14-85d0-a86ad9e12245'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [19] = {
>>>>>>           DeviceServiceId = '0ed374cb-f835-4474-bc11-3b3fd76f5641'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [20] = {
>>>>>>           DeviceServiceId = 'ffffffff-abca-4b11-a4e2-f2fc87f94488'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>   }'

[/dev/cdc-wdm0] Device services retrieved:
	Max DSS sessions: '0'
	        Services: (21)

		          Service: 'basic-connect'
		             UUID: [a289cc33-bcbb-8b4f-b6b0-133ec2aae6df]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: device-caps (1),
		                   subscriber-ready-status (2),
		                   radio-state (3),
		                   pin (4),
		                   pin-list (5),
		                   home-provider (6),
		                   preferred-providers (7),
		                   visible-providers (8),
		                   register-state (9),
		                   packet-service (10),
		                   signal-state (11),
		                   connect (12),
		                   provisioned-contexts (13),
		                   ip-configuration (15),
		                   device-services (16),
		                   device-service-subscribe-list (19),
		                   packet-statistics (20),
		                   network-idle-hint (21),
		                   emergency-mode (22),
		                   ip-packet-filters (23)

		          Service: 'sms'
		             UUID: [533fbeeb-14fe-4467-9f90-33a223e56c3f]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: configuration (1),
		                   read (2),
		                   send (3),
		                   delete (4),
		                   message-store-status (5)

		          Service: 'ussd'
		             UUID: [e550a0c8-5e82-479e-82f7-10abf4c3351f]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: ussd (1)

		          Service: 'phonebook'
		             UUID: [4bf38476-1e6a-41db-b1d8-bed289c25bdb]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: configuration (1),
		                   read (2),
		                   delete (3),
		                   write (4)

		          Service: 'stk'
		             UUID: [d8f20131-fcb5-4e17-8602-d6ed3816164c]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: pac (1),
		                   terminal-response (2),
		                   envelope (3)

		          Service: 'auth'
		             UUID: [1d2b5ff7-0aa1-48b2-aa52-50f15767174e]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: aka (1),
		                   sim (3)

		          Service: 'qmi'
		             UUID: [d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: msg (1)

		          Service: 'ms-host-shutdown'
		             UUID: [883b7c26-985f-43fa-9804-27d7fb80959c]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: notify (1),
		                   unknown (2)

		          Service: 'unknown'
		             UUID: [2d0c12c9-0e6a-495a-915c-8d174fe5d63c]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: 1, 2, 3, 4

		          Service: 'ms-firmware-id'
		             UUID: [e9f7dea2-feaf-4009-93ce-90a3694103b6]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: get (1)

		          Service: 'atds'
		             UUID: [5967bdcc-7fd2-49a2-9f5c-b2e70e527db3]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: signal (1),
		                   location (2),
		                   operators (3),
		                   rat (4),
		                   register-state (9),
		                   unknown (10)

		          Service: 'qdu'
		             UUID: [6427015f-579d-48f5-8c54-f43ed1e76f83]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: update-session (1),
		                   file-open (2),
		                   file-write (3)

		          Service: 'ms-uicc-low-level-access'
		             UUID: [c2f6588e-f037-4bc9-8665-f4d44bd09367]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: atr (1),
		                   open-channel (2),
		                   close-channel (3),
		                   apdu (4),
		                   terminal-capability (5),
		                   reset (6),
		                   application-list (7),
		                   file-status (8),
		                   read-binary (9),
		                   read-record (10)

		          Service: 'ms-basic-connect-extensions'
		             UUID: [3d01dcc5-fef5-4d05-0d3a-bef7058e9aaf]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: provisioned-contexts (1),
		                   lte-attach-configuration (3),
		                   lte-attach-info (4),
		                   sys-caps (5),
		                   device-caps (6),
		                   device-slot-mappings (7),
		                   slot-info-status (8),
		                   pco (9),
		                   device-reset (10),
		                   base-stations-info (11),
		                   location-info-status (12),
		                   unknown (14),
		                   version (15),
		                   modem-configuration (16)

		          Service: 'ms-sar'
		             UUID: [68223d04-9f6c-4e0f-822d-28441fb72340]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: config (1),
		                   transmission-status (2)

		          Service: 'ms-voice-extensions'
		             UUID: [8d8b9eba-37be-449b-8f1e-61cb034a702e]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: nitz (10)

		          Service: 'unknown'
		             UUID: [cfd497ea-f0ae-4f71-9aca-14266eaef198]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: 1, 2, 3, 4, 5, 6, 7

		          Service: 'unknown'
		             UUID: [fbc51292-74bb-484a-abb3-5bf133f70b50]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: 1

		          Service: 'google'
		             UUID: [3e1e92cf-c53d-4f14-85d0-a86ad9e12245]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: carrier-lock (1)

		          Service: 'intel-firmware-update'
		             UUID: [0ed374cb-f835-4474-bc11-3b3fd76f5641]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: modem-reboot (1)

		          Service: 'fibocom'
		             UUID: [ffffffff-abca-4b11-a4e2-f2fc87f94488]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: at-command (1)
[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] closing device...
[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 12
<<<<<<   data   = 02:00:00:00:0C:00:00:00:03:00:00:00...

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 12
<<<<<<   type        = close (0x00000002)
<<<<<<   transaction = 3

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 176
>>>>>>   data   = 07:00:00:80:B0:00:00:00:00:00:00:00...

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message (translated)...
>>>>>> Header:
>>>>>>   length      = 176
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'subscriber-ready-status' (0x00000002)
>>>>>> Fields:
>>>>>>   ReadyState = 'initialized'
>>>>>>   SubscriberId = '###'
>>>>>>   SimIccId = '###'
>>>>>>   ReadyInfo = 'none'
>>>>>>   TelephoneNumbersCount = '1'
>>>>>>   TelephoneNumbers = '###'

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 64
>>>>>>   data   = 07:00:00:80:40:00:00:00:00:00:00:00...

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message (translated)...
>>>>>> Header:
>>>>>>   length      = 64
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'signal-state' (0x0000000b)
>>>>>> Fields:
>>>>>>   Rssi = '17'
>>>>>>   ErrorRate = '99'
>>>>>>   SignalStrengthInterval = '5'
>>>>>>   RssiThreshold = '2'
>>>>>>   ErrorRateThreshold = '4294967295'

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 120
>>>>>>   data   = 07:00:00:80:78:00:00:00:00:00:00:00...

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message (translated)...
>>>>>> Header:
>>>>>>   length      = 120
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'register-state' (0x00000009)
>>>>>> Fields:
>>>>>>   NwError = 'none'
>>>>>>   RegisterState = 'home'
>>>>>>   RegisterMode = 'automatic'
>>>>>>   AvailableDataClasses = 'lte'
>>>>>>   CurrentCellularClass = 'gsm'
>>>>>>   ProviderId = '310260'
>>>>>>   ProviderName = 'T-Mobile'
>>>>>>   RoamingText = '(null)'
>>>>>>   RegistrationFlag = 'packet-service-automatic-attach'

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 72
>>>>>>   data   = 07:00:00:80:48:00:00:00:00:00:00:00...

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message (translated)...
>>>>>> Header:
>>>>>>   length      = 72
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'packet-service' (0x0000000a)
>>>>>> Fields:
>>>>>>   NwError = '0'
>>>>>>   PacketServiceState = 'attached'
>>>>>>   HighestAvailableDataClass = 'lte'
>>>>>>   UplinkSpeed = '100000000'
>>>>>>   DownlinkSpeed = '600000000'

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 120
>>>>>>   data   = 07:00:00:80:78:00:00:00:00:00:00:00...

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message (translated)...
>>>>>> Header:
>>>>>>   length      = 120
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'ms-basic-connect-extensions' (3d01dcc5-fef5-4d05-0d3a-bef7058e9aaf)
>>>>>>   cid     = 'lte-attach-info' (0x00000004)
>>>>>> Fields:
>>>>>>   LteAttachState = 'attached'
>>>>>>   IpType = 'ipv4v6'
>>>>>>   AccessString = 'fast.t-mobile.com'
>>>>>>   UserName = '###'
>>>>>>   Password = '###'
>>>>>>   Compression = 'none'
>>>>>>   AuthProtocol = 'none'

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 16
>>>>>>   data   = 02:00:00:80:10:00:00:00:03:00:00:00...

[15 Apr 2024, 04:17:16] [Debug] [/dev/cdc-wdm0] channel destroyed
[15 Apr 2024, 04:17:16] [Debug] Device closed

[-- Attachment #6: mbimcli-l850.txt --]
[-- Type: text/plain, Size: 9678 bytes --]

[15 Apr 2024, 04:45:24] [Debug] [/dev/cdc-wdm0] opening device...
[15 Apr 2024, 04:45:24] [Debug] [/dev/cdc-wdm0] queried max control message size: 512
[15 Apr 2024, 04:45:24] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:01:00:00:00...

[15 Apr 2024, 04:45:24] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 1
<<<<<< Contents:
<<<<<<   max control transfer = 512

[15 Apr 2024, 04:45:24] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 56
>>>>>>   data   = 03:00:00:80:38:00:00:00:27:00:00:00...

[15 Apr 2024, 04:45:24] [Debug] [/dev/cdc-wdm0] no transaction matched in received message
[15 Apr 2024, 04:45:24] [Debug] [/dev/cdc-wdm0] received unexpected message (translated)...
>>>>>> Header:
>>>>>>   length      = 56
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 39
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'radio-state' (0x00000003)
>>>>>> Fields:
>>>>>>   HwRadioState = 'on'
>>>>>>   SwRadioState = 'off'

[15 Apr 2024, 04:45:29] [Debug] [/dev/cdc-wdm0] number of consecutive timeouts: 1
[15 Apr 2024, 04:45:29] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:02:00:00:00...

[15 Apr 2024, 04:45:29] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 2
<<<<<< Contents:
<<<<<<   max control transfer = 512

[15 Apr 2024, 04:45:29] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 68
>>>>>>   data   = 03:00:00:80:44:00:00:00:28:00:00:00...

[15 Apr 2024, 04:45:29] [Debug] [/dev/cdc-wdm0] no transaction matched in received message
[15 Apr 2024, 04:45:29] [Debug] [/dev/cdc-wdm0] received unexpected message (translated)...
>>>>>> Header:
>>>>>>   length      = 68
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 40
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'signal-state' (0x0000000b)
>>>>>> Fields:
>>>>>>   Rssi = '17'
>>>>>>   ErrorRate = '5'
>>>>>>   SignalStrengthInterval = '5'
>>>>>>   RssiThreshold = '5'
>>>>>>   ErrorRateThreshold = '4294967295'

[15 Apr 2024, 04:45:34] [Debug] [/dev/cdc-wdm0] number of consecutive timeouts: 2
[15 Apr 2024, 04:45:34] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:03:00:00:00...

[15 Apr 2024, 04:45:34] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 3
<<<<<< Contents:
<<<<<<   max control transfer = 512

[15 Apr 2024, 04:45:34] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 260
>>>>>>   data   = 03:00:00:80:04:01:00:00:29:00:00:00...

[15 Apr 2024, 04:45:34] [Debug] [/dev/cdc-wdm0] no transaction matched in received message
[15 Apr 2024, 04:45:34] [Debug] [/dev/cdc-wdm0] received unexpected message (translated)...
>>>>>> Header:
>>>>>>   length      = 260
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 41
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'device-service-subscribe-list' (0x00000013)
>>>>>> Fields:
>>>>>>   EventsCount = '5'
>>>>>>   Events = '{
>>>>>>     [0] = {
>>>>>>           DeviceServiceId = 'a289cc33-bcbb-8b4f-b6b0-133ec2aae6df'
>>>>>>           CidsCount = '11'
>>>>>>           Cids = '2,3,7,9,10,11,12,13,15,22,24'
>>>>>>     },
>>>>>>     [1] = {
>>>>>>           DeviceServiceId = '533fbeeb-14fe-4467-9f90-33a223e56c3f'
>>>>>>           CidsCount = '3'
>>>>>>           Cids = '1,2,5'
>>>>>>     },
>>>>>>     [2] = {
>>>>>>           DeviceServiceId = 'e550a0c8-5e82-479e-82f7-10abf4c3351f'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [3] = {
>>>>>>           DeviceServiceId = '4bf38476-1e6a-41db-b1d8-bed289c25bdb'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [4] = {
>>>>>>           DeviceServiceId = 'd8f20131-fcb5-4e17-8602-d6ed3816164c'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>   }'

[15 Apr 2024, 04:45:39] [Debug] [/dev/cdc-wdm0] number of consecutive timeouts: 3
[15 Apr 2024, 04:45:39] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:04:00:00:00...

[15 Apr 2024, 04:45:39] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 4
<<<<<< Contents:
<<<<<<   max control transfer = 512

[15 Apr 2024, 04:45:39] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 56
>>>>>>   data   = 03:00:00:80:38:00:00:00:2A:00:00:00...

[15 Apr 2024, 04:45:39] [Debug] [/dev/cdc-wdm0] no transaction matched in received message
[15 Apr 2024, 04:45:39] [Debug] [/dev/cdc-wdm0] received unexpected message (translated)...
>>>>>> Header:
>>>>>>   length      = 56
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 42
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'radio-state' (0x00000003)
>>>>>> Fields:
>>>>>>   HwRadioState = 'on'
>>>>>>   SwRadioState = 'off'

[15 Apr 2024, 04:45:44] [Debug] [/dev/cdc-wdm0] number of consecutive timeouts: 4
[15 Apr 2024, 04:45:44] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:05:00:00:00...

[15 Apr 2024, 04:45:44] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 5
<<<<<< Contents:
<<<<<<   max control transfer = 512

[15 Apr 2024, 04:45:44] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 296
>>>>>>   data   = 03:00:00:80:28:01:00:00:2B:00:00:00...

[15 Apr 2024, 04:45:44] [Debug] [/dev/cdc-wdm0] no transaction matched in received message
[15 Apr 2024, 04:45:44] [Debug] [/dev/cdc-wdm0] received unexpected message (translated)...
>>>>>> Header:
>>>>>>   length      = 296
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 43
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'device-service-subscribe-list' (0x00000013)
>>>>>> Fields:
>>>>>>   EventsCount = '6'
>>>>>>   Events = '{
>>>>>>     [0] = {
>>>>>>           DeviceServiceId = 'a289cc33-bcbb-8b4f-b6b0-133ec2aae6df'
>>>>>>           CidsCount = '11'
>>>>>>           Cids = '2,3,7,9,10,11,12,13,15,22,24'
>>>>>>     },
>>>>>>     [1] = {
>>>>>>           DeviceServiceId = '533fbeeb-14fe-4467-9f90-33a223e56c3f'
>>>>>>           CidsCount = '3'
>>>>>>           Cids = '1,2,5'
>>>>>>     },
>>>>>>     [2] = {
>>>>>>           DeviceServiceId = 'e550a0c8-5e82-479e-82f7-10abf4c3351f'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [3] = {
>>>>>>           DeviceServiceId = '4bf38476-1e6a-41db-b1d8-bed289c25bdb'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [4] = {
>>>>>>           DeviceServiceId = 'd8f20131-fcb5-4e17-8602-d6ed3816164c'
>error: couldn't open the MbimDevice: Operation timed out: device is closed
>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [5] = {
>>>>>>           DeviceServiceId = '3d01dcc5-fef5-4d05-0d3a-bef7058e9aaf'
>>>>>>           CidsCount = '2'
>>>>>>           Cids = '9,4'
>>>>>>     },
>>>>>>   }'

[15 Apr 2024, 04:45:49] [Debug] [/dev/cdc-wdm0] number of consecutive timeouts: 5
[15 Apr 2024, 04:45:49] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:06:00:00:00...

[15 Apr 2024, 04:45:49] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 6
<<<<<< Contents:
<<<<<<   max control transfer = 512

[15 Apr 2024, 04:45:49] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 16
>>>>>>   data   = 01:00:00:80:10:00:00:00:01:00:00:00...

[15 Apr 2024, 04:45:49] [Debug] [/dev/cdc-wdm0] no transaction matched in received message
[15 Apr 2024, 04:45:49] [Debug] [/dev/cdc-wdm0] received unexpected message (translated)...
>>>>>> Header:
>>>>>>   length      = 16
>>>>>>   type        = open-done (0x80000001)
>>>>>>   transaction = 1
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)

[15 Apr 2024, 04:45:54] [Debug] [/dev/cdc-wdm0] number of consecutive timeouts: 6
[15 Apr 2024, 04:45:54] [Debug] [/dev/cdc-wdm0] open operation timed out: closed

[-- Attachment #7: mbimcli-l850-patch-reverted.txt --]
[-- Type: text/plain, Size: 14345 bytes --]

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] opening device...
[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] queried max control message size: 512
[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:00:00:00:10:00:00:00:01:00:00:00...

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 1
<<<<<< Contents:
<<<<<<   max control transfer = 512

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 16
>>>>>>   data   = 01:00:00:80:10:00:00:00:01:00:00:00...

[15 Apr 2024, 04:56:50] [Debug] MBIM Device at '/dev/cdc-wdm0' ready
[15 Apr 2024, 04:56:50] [Debug] Asynchronously querying device services...
[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 48
<<<<<<   data   = 03:00:00:00:30:00:00:00:02:00:00:00...

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 48
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 2
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'device-services' (0x00000010)
<<<<<<   type    = 'query' (0x00000000)

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] received message... (partial fragment)
>>>>>> RAW:
>>>>>>   length = 512
>>>>>>   data   = 03:00:00:80:00:02:00:00:02:00:00:00...

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] received message fragment (translated)...
>>>>>> Header:
>>>>>>   length      = 512
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 2
>>>>>> Fragment header:
>>>>>>   total   = 3
>>>>>>   current = 0

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] received message... (partial fragment)
>>>>>> RAW:
>>>>>>   length = 512
>>>>>>   data   = 03:00:00:80:00:02:00:00:02:00:00:00...

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] received message fragment (translated)...
>>>>>> Header:
>>>>>>   length      = 512
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 2
>>>>>> Fragment header:
>>>>>>   total   = 3
>>>>>>   current = 1

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] received message... (partial fragment)
>>>>>> RAW:
>>>>>>   length = 64
>>>>>>   data   = 03:00:00:80:40:00:00:00:02:00:00:00...

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] received message fragment (translated)...
>>>>>> Header:
>>>>>>   length      = 64
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 2
>>>>>> Fragment header:
>>>>>>   total   = 3
>>>>>>   current = 2

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] received message (translated)...
>>>>>> Header:
>>>>>>   length      = 1048
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 2
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'device-services' (0x00000010)
>>>>>> Fields:
>>>>>>   DeviceServicesCount = '21'
>>>>>>   MaxDssSessions = '1'
>>>>>>   DeviceServices = '{
>>>>>>     [0] = {
>>>>>>           DeviceServiceId = 'a289cc33-bcbb-8b4f-b6b0-133ec2aae6df'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '18'
>>>>>>           Cids = '1,2,3,4,5,9,6,11,8,7,21,10,15,12,16,19,23,13'
>>>>>>     },
>>>>>>     [1] = {
>>>>>>           DeviceServiceId = '533fbeeb-14fe-4467-9f90-33a223e56c3f'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '5'
>>>>>>           Cids = '1,2,3,4,5'
>>>>>>     },
>>>>>>     [2] = {
>>>>>>           DeviceServiceId = 'e550a0c8-5e82-479e-82f7-10abf4c3351f'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [3] = {
>>>>>>           DeviceServiceId = '4bf38476-1e6a-41db-b1d8-bed289c25bdb'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '4'
>>>>>>           Cids = '1,2,3,4'
>>>>>>     },
>>>>>>     [4] = {
>>>>>>           DeviceServiceId = 'd8f20131-fcb5-4e17-8602-d6ed3816164c'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '3'
>>>>>>           Cids = '1,2,3'
>>>>>>     },
>>>>>>     [5] = {
>>>>>>           DeviceServiceId = '1d2b5ff7-0aa1-48b2-aa52-50f15767174e'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '2'
>>>>>>           Cids = '1,3'
>>>>>>     },
>>>>>>     [6] = {
>>>>>>           DeviceServiceId = 'c08a26dd-7718-4382-8482-6e0d583c4d0e'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [7] = {
>>>>>>           DeviceServiceId = '10e40d69-375a-42ce-a297-906164f2754c'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [8] = {
>>>>>>           DeviceServiceId = '59a7f323-fe5a-4301-b185-b8ea9e6167b7'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [9] = {
>>>>>>           DeviceServiceId = 'fdc22af2-f441-4d46-af8d-259fcdde4635'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '33554688'
>>>>>>     },
>>>>>>     [10] = {
>>>>>>           DeviceServiceId = '883b7c26-985f-43fa-9804-27d7fb80959c'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [11] = {
>>>>>>           DeviceServiceId = '5967bdcc-7fd2-49a2-9f5c-b2e70e527db3'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '7'
>>>>>>           Cids = '2,3,1,11,4,10,9'
>>>>>>     },
>>>>>>     [12] = {
>>>>>>           DeviceServiceId = '0ed374cb-f835-4474-bc11-3b3fd76f5641'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [13] = {
>>>>>>           DeviceServiceId = 'ed19555d-a6ac-4327-8eb1-fc022e5e2388'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '33554448'
>>>>>>     },
>>>>>>     [14] = {
>>>>>>           DeviceServiceId = 'fa142322-166b-4fd9-89f0-99be90ae8e3d'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [15] = {
>>>>>>           DeviceServiceId = '2b6d8c5a-0ca9-418f-8aac-1a9dc8e32866'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '3'
>>>>>>           Cids = '1,2,3'
>>>>>>     },
>>>>>>     [16] = {
>>>>>>           DeviceServiceId = '823426c2-b060-4493-9f8d-71a518314a64'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [17] = {
>>>>>>           DeviceServiceId = '6a2a8150-abca-4b11-a4e2-f2fc879f5481'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [18] = {
>>>>>>           DeviceServiceId = 'f85d46ef-ab26-4081-9868-4d183c0a3aec'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [19] = {
>>>>>>           DeviceServiceId = 'c0f1eca9-3fdd-424d-82b1-ada742a2d387'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '1'
>>>>>>           Cids = '1'
>>>>>>     },
>>>>>>     [20] = {
>>>>>>           DeviceServiceId = '3d01dcc5-fef5-4d05-0d3a-bef7058e9aaf'
>>>>>>           DssPayload = '0'
>>>>>>           MaxDssInstances = '0'
>>>>>>           CidsCount = '4'
>>>>>>           Cids = '3,4,6,9'
>>>>>>     },
>>>>>>   }'

[/dev/cdc-wdm0] Device services retrieved:
	Max DSS sessions: '1'
	        Services: (21)

		          Service: 'basic-connect'
		             UUID: [a289cc33-bcbb-8b4f-b6b0-133ec2aae6df]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: device-caps (1),
		                   subscriber-ready-status (2),
		                   radio-state (3),
		                   pin (4),
		                   pin-list (5),
		                   register-state (9),
		                   home-provider (6),
		                   signal-state (11),
		                   visible-providers (8),
		                   preferred-providers (7),
		                   network-idle-hint (21),
		                   packet-service (10),
		                   ip-configuration (15),
		                   connect (12),
		                   device-services (16),
		                   device-service-subscribe-list (19),
		                   ip-packet-filters (23),
		                   provisioned-contexts (13)

		          Service: 'sms'
		             UUID: [533fbeeb-14fe-4467-9f90-33a223e56c3f]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: configuration (1),
		                   read (2),
		                   send (3),
		                   delete (4),
		                   message-store-status (5)

		          Service: 'ussd'
		             UUID: [e550a0c8-5e82-479e-82f7-10abf4c3351f]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: ussd (1)

		          Service: 'phonebook'
		             UUID: [4bf38476-1e6a-41db-b1d8-bed289c25bdb]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: configuration (1),
		                   read (2),
		                   delete (3),
		                   write (4)

		          Service: 'stk'
		             UUID: [d8f20131-fcb5-4e17-8602-d6ed3816164c]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: pac (1),
		                   terminal-response (2),
		                   envelope (3)

		          Service: 'auth'
		             UUID: [1d2b5ff7-0aa1-48b2-aa52-50f15767174e]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: aka (1),
		                   sim (3)

		          Service: 'dss'
		             UUID: [c08a26dd-7718-4382-8482-6e0d583c4d0e]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: connect (1)

		          Service: 'unknown'
		             UUID: [10e40d69-375a-42ce-a297-906164f2754c]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: 1

		          Service: 'unknown'
		             UUID: [59a7f323-fe5a-4301-b185-b8ea9e6167b7]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: 1

		          Service: 'intel-thermal-rf'
		             UUID: [fdc22af2-f441-4d46-af8d-259fcdde4635]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: unknown (33554688)

		          Service: 'ms-host-shutdown'
		             UUID: [883b7c26-985f-43fa-9804-27d7fb80959c]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: notify (1)

		          Service: 'atds'
		             UUID: [5967bdcc-7fd2-49a2-9f5c-b2e70e527db3]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: location (2),
		                   operators (3),
		                   signal (1),
		                   unknown (11),
		                   rat (4),
		                   unknown (10),
		                   register-state (9)

		          Service: 'intel-firmware-update'
		             UUID: [0ed374cb-f835-4474-bc11-3b3fd76f5641]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: modem-reboot (1)

		          Service: 'unknown'
		             UUID: [ed19555d-a6ac-4327-8eb1-fc022e5e2388]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: 33554448

		          Service: 'unknown'
		             UUID: [fa142322-166b-4fd9-89f0-99be90ae8e3d]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: 1

		          Service: 'unknown'
		             UUID: [2b6d8c5a-0ca9-418f-8aac-1a9dc8e32866]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: 1, 2, 3

		          Service: 'unknown'
		             UUID: [823426c2-b060-4493-9f8d-71a518314a64]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: 1

		          Service: 'unknown'
		             UUID: [6a2a8150-abca-4b11-a4e2-f2fc879f5481]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: 1

		          Service: 'intel-mutual-authentication'
		             UUID: [f85d46ef-ab26-4081-9868-4d183c0a3aec]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: fcc-lock (1)

		          Service: 'unknown'
		             UUID: [c0f1eca9-3fdd-424d-82b1-ada742a2d387]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: 1

		          Service: 'ms-basic-connect-extensions'
		             UUID: [3d01dcc5-fef5-4d05-0d3a-bef7058e9aaf]:
		      DSS payload: 0
		Max DSS instances: 0
		             CIDs: lte-attach-configuration (3),
		                   lte-attach-info (4),
		                   device-caps (6),
		                   pco (9)
[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] closing device...
[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 12
<<<<<<   data   = 02:00:00:00:0C:00:00:00:03:00:00:00...

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 12
<<<<<<   type        = close (0x00000002)
<<<<<<   transaction = 3

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] received message...
>>>>>> RAW:
>>>>>>   length = 16
>>>>>>   data   = 02:00:00:80:10:00:00:00:03:00:00:00...

[15 Apr 2024, 04:56:50] [Debug] [/dev/cdc-wdm0] channel destroyed
[15 Apr 2024, 04:56:50] [Debug] Device closed

[-- Attachment #8: usbmon-l850.txt --]
[-- Type: text/plain, Size: 22408 bytes --]

ffff8d5340c1de40 570307019 S Ci:2:001:0 s a3 00 0000 0001 0004 4 <
ffff8d5340c1de40 570307061 C Ci:2:001:0 0 4 = a0020000
ffff8d536c3e0cc0 570307102 S Ci:2:001:0 s a3 00 0000 0002 0004 4 <
ffff8d536c3e0cc0 570307144 C Ci:2:001:0 0 4 = a0020000
ffff8d5340c1d0c0 570307203 S Ci:2:001:0 s a3 00 0000 0003 0004 4 <
ffff8d5340c1d0c0 570307245 C Ci:2:001:0 0 4 = a0020000
ffff8d536c3e0780 570307286 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d536c3e0780 570307313 C Ci:2:001:0 0 4 = 63020000
ffff8d5340c1d0c0 570307324 S Ci:2:001:0 s a3 00 0000 0005 0004 4 <
ffff8d5340c1d0c0 570307345 C Ci:2:001:0 0 4 = a0020000
ffff8d536c3e0d80 570307440 S Ci:2:001:0 s a3 00 0000 0006 0004 4 <
ffff8d536c3e0d80 570307450 C Ci:2:001:0 0 4 = a0020000
ffff8d53427440c0 570307458 S Ii:2:001:1 -115:2048 4 <
ffff8d5340c1de40 570307470 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5340c1de40 570307510 C Ci:2:001:0 0 4 = 63020000
ffff8d536c3e0840 570307583 S Co:2:001:0 s 23 03 0005 0004 0000 0
ffff8d53427440c0 570327000 C Ii:2:001:1 0:2048 1 = 10
ffff8d536c3e0840 570327016 C Co:2:001:0 0 0
ffff8d53427440c0 570327031 S Ii:2:001:1 -115:2048 4 <
ffff8d536c3e0e40 570369026 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d536c3e0e40 570369058 C Ci:2:001:0 0 4 = 03024000
ffff8d5340c1d780 570369102 S Co:2:001:0 s 23 01 0019 0004 0000 0
ffff8d5340c1d780 570369148 C Co:2:001:0 0 0
ffff8d536c3e0240 570381026 S Ci:2:005:0 s 80 00 0000 0000 0002 2 <
ffff8d536c3e0240 570381943 C Ci:2:005:0 0 2 = 0500
ffff8d536c3e0240 570381977 S Ci:2:005:0 s 81 00 0000 0000 0002 2 <
ffff8d536c3e0240 570382750 C Ci:2:005:0 0 2 = 0100
ffff8d536c3e0240 570382788 S Co:2:005:0 s 01 03 0000 0000 0000 0
ffff8d536c3e0240 570383239 C Co:2:005:0 0 0
ffff8d5346314480 570383296 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d53856d4000 570383312 S Ii:2:005:1 -115:8 64 <
ffff8d5346314480 570383331 C Ci:2:001:0 0 4 = 23020000
ffff8d53856d4d80 570383665 S Co:2:005:0 s 21 00 0000 0000 0010 16 = 01000000 10000000 01000000 00020000
ffff8d53856d4d80 570384484 C Co:2:005:0 0 16 >
ffff8d53856d4000 570387654 C Ii:2:005:1 0:8 8 = a1010000 00000000
ffff8d53856d4a80 570387674 S Ci:2:005:0 s a1 01 0000 0000 0200 512 <
ffff8d53856d4000 570387701 S Ii:2:005:1 -115:8 64 <
ffff8d53856d4a80 570388551 C Ci:2:005:0 0 56 = 03000080 38000000 27000000 01000000 00000000 a289cc33 bcbb8b4f b6b0133e
ffff8d53856d4000 572883193 C Ii:2:005:1 -2:8 0
ffff8d536c3e0240 572883221 S Co:2:005:0 s 01 03 0000 0300 0000 0
ffff8d536c3e0240 572883787 C Co:2:005:0 0 0
ffff8d536c3e0240 572883810 S Co:2:001:0 s 23 03 0005 0304 0000 0
ffff8d536c3e0240 572892050 C Co:2:001:0 0 0
ffff8d536c3e0240 572905384 S Co:2:001:0 s 23 03 001b 0701 0000 0
ffff8d536c3e0240 572905427 C Co:2:001:0 0 0
ffff8d536c3e0240 572905433 S Co:2:001:0 s 23 03 001b 0702 0000 0
ffff8d536c3e0240 572905452 C Co:2:001:0 0 0
ffff8d536c3e0240 572905456 S Co:2:001:0 s 23 03 001b 0703 0000 0
ffff8d536c3e0240 572905479 C Co:2:001:0 0 0
ffff8d536c3e0240 572905482 S Co:2:001:0 s 23 03 001b 0704 0000 0
ffff8d536c3e0240 572905506 C Co:2:001:0 0 0
ffff8d536c3e0240 572905509 S Co:2:001:0 s 23 03 001b 0705 0000 0
ffff8d536c3e0240 572905533 C Co:2:001:0 0 0
ffff8d536c3e0240 572905536 S Co:2:001:0 s 23 03 001b 0706 0000 0
ffff8d536c3e0240 572905559 C Co:2:001:0 0 0
ffff8d53427440c0 572905570 C Ii:2:001:1 -2:2048 0
ffff8d536c3e0240 575410049 S Ci:2:001:0 s a3 00 0000 0001 0004 4 <
ffff8d536c3e0240 575410099 C Ci:2:001:0 0 4 = a0020000
ffff8d536c3e0240 575410112 S Ci:2:001:0 s a3 00 0000 0002 0004 4 <
ffff8d536c3e0240 575410135 C Ci:2:001:0 0 4 = a0020000
ffff8d536c3e0240 575410141 S Ci:2:001:0 s a3 00 0000 0003 0004 4 <
ffff8d536c3e0240 575410163 C Ci:2:001:0 0 4 = a0020000
ffff8d536c3e0240 575410169 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d536c3e0240 575410191 C Ci:2:001:0 0 4 = 63020000
ffff8d536c3e0240 575410197 S Ci:2:001:0 s a3 00 0000 0005 0004 4 <
ffff8d536c3e0240 575410219 C Ci:2:001:0 0 4 = a0020000
ffff8d536c3e0240 575410224 S Ci:2:001:0 s a3 00 0000 0006 0004 4 <
ffff8d536c3e0240 575410246 C Ci:2:001:0 0 4 = a0020000
ffff8d53427440c0 575410252 S Ii:2:001:1 -115:2048 4 <
ffff8d536c3e0240 575410267 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d536c3e0240 575410303 C Ci:2:001:0 0 4 = 63020000
ffff8d5396492cc0 575410323 S Co:2:001:0 s 23 03 0005 0004 0000 0
ffff8d5396492cc0 575429571 C Co:2:001:0 0 0
ffff8d53427440c0 575429574 C Ii:2:001:1 0:2048 1 = 10
ffff8d53427440c0 575429584 S Ii:2:001:1 -115:2048 4 <
ffff8d536c3e0c00 575471045 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d536c3e0c00 575471095 C Ci:2:001:0 0 4 = 03024000
ffff8d5396492180 575471118 S Co:2:001:0 s 23 01 0019 0004 0000 0
ffff8d5396492180 575471155 C Co:2:001:0 0 0
ffff8d5396492e40 575483043 S Ci:2:005:0 s 80 00 0000 0000 0002 2 <
ffff8d5396492e40 575483936 C Ci:2:005:0 0 2 = 0500
ffff8d5396492e40 575483970 S Ci:2:005:0 s 81 00 0000 0000 0002 2 <
ffff8d5396492e40 575484718 C Ci:2:005:0 0 2 = 0300
ffff8d5396492e40 575484796 S Co:2:005:0 s 01 03 0000 0000 0000 0
ffff8d5396492e40 575485210 C Co:2:005:0 0 0
ffff8d5346314480 575485313 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d53856d4000 575485320 S Ii:2:005:1 -115:8 64 <
ffff8d5346314480 575485353 C Ci:2:001:0 0 4 = 23020000
ffff8d53856d4d80 575485387 S Co:2:005:0 s 21 00 0000 0000 0010 16 = 01000000 10000000 02000000 00020000
ffff8d53856d4d80 575486195 C Co:2:005:0 0 16 >
ffff8d53856d4000 575488648 C Ii:2:005:1 0:8 8 = a1010000 00000000
ffff8d53856d4a80 575488671 S Ci:2:005:0 s a1 01 0000 0000 0200 512 <
ffff8d53856d4000 575488702 S Ii:2:005:1 -115:8 64 <
ffff8d53856d4a80 575489568 C Ci:2:005:0 0 68 = 03000080 44000000 28000000 01000000 00000000 a289cc33 bcbb8b4f b6b0133e
ffff8d53856d4000 577985290 C Ii:2:005:1 -2:8 0
ffff8d536c3e0600 577985364 S Co:2:005:0 s 01 03 0000 0300 0000 0
ffff8d536c3e0600 577985969 C Co:2:005:0 0 0
ffff8d536c3e0600 577985999 S Co:2:001:0 s 23 03 0005 0304 0000 0
ffff8d536c3e0600 577994060 C Co:2:001:0 0 0
ffff8d536c3e0600 578006073 S Co:2:001:0 s 23 03 001b 0701 0000 0
ffff8d536c3e0600 578006108 C Co:2:001:0 0 0
ffff8d536c3e0600 578006112 S Co:2:001:0 s 23 03 001b 0702 0000 0
ffff8d536c3e0600 578006135 C Co:2:001:0 0 0
ffff8d536c3e0600 578006137 S Co:2:001:0 s 23 03 001b 0703 0000 0
ffff8d536c3e0600 578006160 C Co:2:001:0 0 0
ffff8d536c3e0600 578006162 S Co:2:001:0 s 23 03 001b 0704 0000 0
ffff8d536c3e0600 578006185 C Co:2:001:0 0 0
ffff8d536c3e0600 578006187 S Co:2:001:0 s 23 03 001b 0705 0000 0
ffff8d536c3e0600 578006209 C Co:2:001:0 0 0
ffff8d536c3e0600 578006211 S Co:2:001:0 s 23 03 001b 0706 0000 0
ffff8d536c3e0600 578006234 C Co:2:001:0 0 0
ffff8d53427440c0 578006239 C Ii:2:001:1 -2:2048 0
ffff8d5396492480 580415033 S Ci:2:001:0 s a3 00 0000 0001 0004 4 <
ffff8d5396492480 580415079 C Ci:2:001:0 0 4 = a0020000
ffff8d5396492480 580415088 S Ci:2:001:0 s a3 00 0000 0002 0004 4 <
ffff8d5396492480 580415109 C Ci:2:001:0 0 4 = a0020000
ffff8d5396492480 580415114 S Ci:2:001:0 s a3 00 0000 0003 0004 4 <
ffff8d5396492480 580415134 C Ci:2:001:0 0 4 = a0020000
ffff8d5396492480 580415139 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5396492480 580415160 C Ci:2:001:0 0 4 = 63020000
ffff8d5396492480 580415165 S Ci:2:001:0 s a3 00 0000 0005 0004 4 <
ffff8d5396492480 580415185 C Ci:2:001:0 0 4 = a0020000
ffff8d5396492480 580415189 S Ci:2:001:0 s a3 00 0000 0006 0004 4 <
ffff8d5396492480 580415209 C Ci:2:001:0 0 4 = a0020000
ffff8d53427440c0 580415213 S Ii:2:001:1 -115:2048 4 <
ffff8d5396492480 580415227 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5396492480 580415256 C Ci:2:001:0 0 4 = 63020000
ffff8d5396492480 580415262 S Co:2:001:0 s 23 03 0005 0004 0000 0
ffff8d5396492480 580434554 C Co:2:001:0 0 0
ffff8d53427440c0 580434559 C Ii:2:001:1 0:2048 1 = 10
ffff8d53427440c0 580434571 S Ii:2:001:1 -115:2048 4 <
ffff8d5396492000 580476066 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5396492000 580476116 C Ci:2:001:0 0 4 = 03024000
ffff8d5396492000 580476130 S Co:2:001:0 s 23 01 0019 0004 0000 0
ffff8d5396492000 580476156 C Co:2:001:0 0 0
ffff8d5396492000 580489028 S Ci:2:005:0 s 80 00 0000 0000 0002 2 <
ffff8d5396492000 580489904 C Ci:2:005:0 0 2 = 0500
ffff8d5396492000 580489925 S Ci:2:005:0 s 81 00 0000 0000 0002 2 <
ffff8d5396492000 580490678 C Ci:2:005:0 0 2 = 0300
ffff8d5396492000 580490721 S Co:2:005:0 s 01 03 0000 0000 0000 0
ffff8d5396492000 580491146 C Co:2:005:0 0 0
ffff8d53463143c0 580491186 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d53856d4000 580491194 S Ii:2:005:1 -115:8 64 <
ffff8d53463143c0 580491220 C Ci:2:001:0 0 4 = 23020000
ffff8d53856d4d80 580491250 S Co:2:005:0 s 21 00 0000 0000 0010 16 = 01000000 10000000 03000000 00020000
ffff8d53856d4d80 580492082 C Co:2:005:0 0 16 >
ffff8d53856d4000 580494658 C Ii:2:005:1 0:8 8 = a1010000 00000000
ffff8d53856d4a80 580494681 S Ci:2:005:0 s a1 01 0000 0000 0200 512 <
ffff8d53856d4000 580494710 S Ii:2:005:1 -115:8 64 <
ffff8d53856d4a80 580495718 C Ci:2:005:0 0 260 = 03000080 04010000 29000000 01000000 00000000 a289cc33 bcbb8b4f b6b0133e
ffff8d53856d4000 582991230 C Ii:2:005:1 -2:8 0
ffff8d5396492000 582991250 S Co:2:005:0 s 01 03 0000 0300 0000 0
ffff8d5396492000 582991868 C Co:2:005:0 0 0
ffff8d5396492000 582991882 S Co:2:001:0 s 23 03 0005 0304 0000 0
ffff8d5396492000 583000067 C Co:2:001:0 0 0
ffff8d5396492000 583012040 S Co:2:001:0 s 23 03 001b 0701 0000 0
ffff8d5396492000 583012080 C Co:2:001:0 0 0
ffff8d5396492000 583012087 S Co:2:001:0 s 23 03 001b 0702 0000 0
ffff8d5396492000 583012113 C Co:2:001:0 0 0
ffff8d5396492000 583012116 S Co:2:001:0 s 23 03 001b 0703 0000 0
ffff8d5396492000 583012142 C Co:2:001:0 0 0
ffff8d5396492000 583012145 S Co:2:001:0 s 23 03 001b 0704 0000 0
ffff8d5396492000 583012169 C Co:2:001:0 0 0
ffff8d5396492000 583012172 S Co:2:001:0 s 23 03 001b 0705 0000 0
ffff8d5396492000 583012198 C Co:2:001:0 0 0
ffff8d5396492000 583012202 S Co:2:001:0 s 23 03 001b 0706 0000 0
ffff8d5396492000 583012228 C Co:2:001:0 0 0
ffff8d53427440c0 583012237 C Ii:2:001:1 -2:2048 0
ffff8d5346314180 585422027 S Ci:2:001:0 s a3 00 0000 0001 0004 4 <
ffff8d5346314180 585422073 C Ci:2:001:0 0 4 = a0020000
ffff8d5346314180 585422083 S Ci:2:001:0 s a3 00 0000 0002 0004 4 <
ffff8d5346314180 585422107 C Ci:2:001:0 0 4 = a0020000
ffff8d5346314180 585422113 S Ci:2:001:0 s a3 00 0000 0003 0004 4 <
ffff8d5346314180 585422134 C Ci:2:001:0 0 4 = a0020000
ffff8d5346314180 585422139 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5346314180 585422160 C Ci:2:001:0 0 4 = 63020000
ffff8d5346314180 585422165 S Ci:2:001:0 s a3 00 0000 0005 0004 4 <
ffff8d5346314180 585422187 C Ci:2:001:0 0 4 = a0020000
ffff8d5340c1d780 585422207 S Ci:2:001:0 s a3 00 0000 0006 0004 4 <
ffff8d5340c1d780 585422240 C Ci:2:001:0 0 4 = a0020000
ffff8d53427440c0 585422310 S Ii:2:001:1 -115:2048 4 <
ffff8d5346314b40 585422329 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5346314b40 585422364 C Ci:2:001:0 0 4 = 63020000
ffff8d5340c1d780 585422378 S Co:2:001:0 s 23 03 0005 0004 0000 0
ffff8d5340c1d780 585441640 C Co:2:001:0 0 0
ffff8d53427440c0 585441643 C Ii:2:001:1 0:2048 1 = 10
ffff8d53427440c0 585441651 S Ii:2:001:1 -115:2048 4 <
ffff8d5340c1d780 585483022 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5340c1d780 585483060 C Ci:2:001:0 0 4 = 03024000
ffff8d5346314900 585483094 S Co:2:001:0 s 23 01 0019 0004 0000 0
ffff8d5346314900 585483124 C Co:2:001:0 0 0
ffff8d5396492000 585495033 S Ci:2:005:0 s 80 00 0000 0000 0002 2 <
ffff8d5396492000 585495857 C Ci:2:005:0 0 2 = 0500
ffff8d5396492000 585495870 S Ci:2:005:0 s 81 00 0000 0000 0002 2 <
ffff8d5396492000 585496644 C Ci:2:005:0 0 2 = 0300
ffff8d5396492000 585496694 S Co:2:005:0 s 01 03 0000 0000 0000 0
ffff8d5396492000 585497131 C Co:2:005:0 0 0
ffff8d5346314e40 585497218 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d53856d4000 585497224 S Ii:2:005:1 -115:8 64 <
ffff8d5346314e40 585497248 C Ci:2:001:0 0 4 = 23020000
ffff8d53856d4d80 585497282 S Co:2:005:0 s 21 00 0000 0000 0010 16 = 01000000 10000000 04000000 00020000
ffff8d53856d4d80 585498065 C Co:2:005:0 0 16 >
ffff8d53856d4000 585500650 C Ii:2:005:1 0:8 8 = a1010000 00000000
ffff8d53856d4a80 585500675 S Ci:2:005:0 s a1 01 0000 0000 0200 512 <
ffff8d53856d4000 585500702 S Ii:2:005:1 -115:8 64 <
ffff8d53856d4a80 585501577 C Ci:2:005:0 0 56 = 03000080 38000000 2a000000 01000000 00000000 a289cc33 bcbb8b4f b6b0133e
ffff8d53856d4000 587997179 C Ii:2:005:1 -2:8 0
ffff8d5396492000 587997203 S Co:2:005:0 s 01 03 0000 0300 0000 0
ffff8d5396492000 587997793 C Co:2:005:0 0 0
ffff8d5396492000 587997819 S Co:2:001:0 s 23 03 0005 0304 0000 0
ffff8d5396492000 588006045 C Co:2:001:0 0 0
ffff8d5396492000 588019035 S Co:2:001:0 s 23 03 001b 0701 0000 0
ffff8d5396492000 588019074 C Co:2:001:0 0 0
ffff8d5396492000 588019080 S Co:2:001:0 s 23 03 001b 0702 0000 0
ffff8d5396492000 588019104 C Co:2:001:0 0 0
ffff8d5396492000 588019107 S Co:2:001:0 s 23 03 001b 0703 0000 0
ffff8d5396492000 588019131 C Co:2:001:0 0 0
ffff8d5396492000 588019134 S Co:2:001:0 s 23 03 001b 0704 0000 0
ffff8d5396492000 588019158 C Co:2:001:0 0 0
ffff8d5396492000 588019161 S Co:2:001:0 s 23 03 001b 0705 0000 0
ffff8d5396492000 588019184 C Co:2:001:0 0 0
ffff8d5396492000 588019187 S Co:2:001:0 s 23 03 001b 0706 0000 0
ffff8d5396492000 588019210 C Co:2:001:0 0 0
ffff8d53427440c0 588019217 C Ii:2:001:1 -2:2048 0
ffff8d5340c1de40 590426046 S Ci:2:001:0 s a3 00 0000 0001 0004 4 <
ffff8d5340c1de40 590426178 C Ci:2:001:0 0 4 = a0020000
ffff8d5340c1de40 590426195 S Ci:2:001:0 s a3 00 0000 0002 0004 4 <
ffff8d5340c1de40 590426222 C Ci:2:001:0 0 4 = a0020000
ffff8d5340c1de40 590426278 S Ci:2:001:0 s a3 00 0000 0003 0004 4 <
ffff8d5340c1de40 590426304 C Ci:2:001:0 0 4 = a0020000
ffff8d5340c1de40 590426312 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5340c1de40 590426335 C Ci:2:001:0 0 4 = 63020000
ffff8d5346314e40 590426372 S Ci:2:001:0 s a3 00 0000 0005 0004 4 <
ffff8d5346314e40 590426413 C Ci:2:001:0 0 4 = a0020000
ffff8d5346314e40 590426430 S Ci:2:001:0 s a3 00 0000 0006 0004 4 <
ffff8d5346314e40 590426456 C Ci:2:001:0 0 4 = a0020000
ffff8d53427440c0 590426464 S Ii:2:001:1 -115:2048 4 <
ffff8d5346314e40 590426483 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5346314e40 590426521 C Ci:2:001:0 0 4 = 63020000
ffff8d5346314e40 590426535 S Co:2:001:0 s 23 03 0005 0004 0000 0
ffff8d53427440c0 590445886 C Ii:2:001:1 0:2048 1 = 10
ffff8d5346314e40 590445891 C Co:2:001:0 0 0
ffff8d53427440c0 590445901 S Ii:2:001:1 -115:2048 4 <
ffff8d5340c1d0c0 590488013 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5340c1d0c0 590488045 C Ci:2:001:0 0 4 = 03024000
ffff8d53463140c0 590488080 S Co:2:001:0 s 23 01 0019 0004 0000 0
ffff8d53463140c0 590488108 C Co:2:001:0 0 0
ffff8d5340c1d0c0 590501023 S Ci:2:005:0 s 80 00 0000 0000 0002 2 <
ffff8d5340c1d0c0 590501834 C Ci:2:005:0 0 2 = 0500
ffff8d5340c1d0c0 590501851 S Ci:2:005:0 s 81 00 0000 0000 0002 2 <
ffff8d5340c1d0c0 590502566 C Ci:2:005:0 0 2 = 0300
ffff8d5340c1d0c0 590502621 S Co:2:005:0 s 01 03 0000 0000 0000 0
ffff8d5340c1d0c0 590503198 C Co:2:005:0 0 0
ffff8d53856d4000 590503332 S Ii:2:005:1 -115:8 64 <
ffff8d5346314000 590503347 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5346314000 590503362 C Ci:2:001:0 0 4 = 23020000
ffff8d53856d4d80 590503372 S Co:2:005:0 s 21 00 0000 0000 0010 16 = 01000000 10000000 05000000 00020000
ffff8d53856d4d80 590504156 C Co:2:005:0 0 16 >
ffff8d53856d4000 590506679 C Ii:2:005:1 0:8 8 = a1010000 00000000
ffff8d53856d4a80 590506703 S Ci:2:005:0 s a1 01 0000 0000 0200 512 <
ffff8d53856d4000 590506729 S Ii:2:005:1 -115:8 64 <
ffff8d53856d4a80 590507797 C Ci:2:005:0 0 296 = 03000080 28010000 2b000000 01000000 00000000 a289cc33 bcbb8b4f b6b0133e
ffff8d53856d4000 593003344 C Ii:2:005:1 -2:8 0
ffff8d5340c1d0c0 593003364 S Co:2:005:0 s 01 03 0000 0300 0000 0
ffff8d5340c1d0c0 593003948 C Co:2:005:0 0 0
ffff8d5340c1d0c0 593003960 S Co:2:001:0 s 23 03 0005 0304 0000 0
ffff8d5340c1d0c0 593012114 C Co:2:001:0 0 0
ffff8d5340c1d0c0 593024112 S Co:2:001:0 s 23 03 001b 0701 0000 0
ffff8d5340c1d0c0 593024170 C Co:2:001:0 0 0
ffff8d5340c1d0c0 593024181 S Co:2:001:0 s 23 03 001b 0702 0000 0
ffff8d5340c1d0c0 593024208 C Co:2:001:0 0 0
ffff8d5340c1d0c0 593024222 S Co:2:001:0 s 23 03 001b 0703 0000 0
ffff8d5340c1d0c0 593024252 C Co:2:001:0 0 0
ffff8d5340c1d0c0 593024257 S Co:2:001:0 s 23 03 001b 0704 0000 0
ffff8d5340c1d0c0 593024282 C Co:2:001:0 0 0
ffff8d5340c1d0c0 593024286 S Co:2:001:0 s 23 03 001b 0705 0000 0
ffff8d5340c1d0c0 593024312 C Co:2:001:0 0 0
ffff8d5340c1d0c0 593024316 S Co:2:001:0 s 23 03 001b 0706 0000 0
ffff8d5340c1d0c0 593024342 C Co:2:001:0 0 0
ffff8d53427440c0 593024353 C Ii:2:001:1 -2:2048 0
ffff8d5340c1d0c0 595433086 S Ci:2:001:0 s a3 00 0000 0001 0004 4 <
ffff8d5340c1d0c0 595433201 C Ci:2:001:0 0 4 = a0020000
ffff8d5340c1d0c0 595433216 S Ci:2:001:0 s a3 00 0000 0002 0004 4 <
ffff8d5340c1d0c0 595433239 C Ci:2:001:0 0 4 = a0020000
ffff8d5340c1d0c0 595433284 S Ci:2:001:0 s a3 00 0000 0003 0004 4 <
ffff8d5340c1d0c0 595433309 C Ci:2:001:0 0 4 = a0020000
ffff8d5340c1d0c0 595433341 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5340c1d0c0 595433367 C Ci:2:001:0 0 4 = 63020000
ffff8d5340c1d0c0 595433401 S Ci:2:001:0 s a3 00 0000 0005 0004 4 <
ffff8d5340c1d0c0 595433426 C Ci:2:001:0 0 4 = a0020000
ffff8d5340c1d0c0 595433458 S Ci:2:001:0 s a3 00 0000 0006 0004 4 <
ffff8d5340c1d0c0 595433483 C Ci:2:001:0 0 4 = a0020000
ffff8d53427440c0 595433514 S Ii:2:001:1 -115:2048 4 <
ffff8d5340c1d0c0 595433532 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5340c1d0c0 595433577 C Ci:2:001:0 0 4 = 63020000
ffff8d5340c1d0c0 595433623 S Co:2:001:0 s 23 03 0005 0004 0000 0
ffff8d53427440c0 595452906 C Ii:2:001:1 0:2048 1 = 10
ffff8d5340c1d0c0 595452913 C Co:2:001:0 0 0
ffff8d53427440c0 595452920 S Ii:2:001:1 -115:2048 4 <
ffff8d5340c1d0c0 595495031 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5340c1d0c0 595495153 C Ci:2:001:0 0 4 = 03024000
ffff8d5340c1d0c0 595495167 S Co:2:001:0 s 23 01 0019 0004 0000 0
ffff8d5340c1d0c0 595495193 C Co:2:001:0 0 0
ffff8d5396492000 595507042 S Ci:2:005:0 s 80 00 0000 0000 0002 2 <
ffff8d5396492000 595507901 C Ci:2:005:0 0 2 = 0500
ffff8d5340c1d0c0 595507973 S Ci:2:005:0 s 81 00 0000 0000 0002 2 <
ffff8d5340c1d0c0 595508689 C Ci:2:005:0 0 2 = 0300
ffff8d5340c1d0c0 595508753 S Co:2:005:0 s 01 03 0000 0000 0000 0
ffff8d5340c1d0c0 595509212 C Co:2:005:0 0 0
ffff8d53856d4000 595509274 S Ii:2:005:1 -115:8 64 <
ffff8d53856d4d80 595509339 S Co:2:005:0 s 21 00 0000 0000 0010 16 = 01000000 10000000 06000000 00020000
ffff8d5346314000 595509480 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5346314000 595509528 C Ci:2:001:0 0 4 = 03020000
ffff8d53856d4d80 595510141 C Co:2:005:0 0 16 >
ffff8d53856d4000 595512679 C Ii:2:005:1 0:8 8 = a1010000 00000000
ffff8d53856d4a80 595512716 S Ci:2:005:0 s a1 01 0000 0000 0200 512 <
ffff8d53856d4000 595512748 S Ii:2:005:1 -115:8 64 <
ffff8d53856d4a80 595513561 C Ci:2:005:0 0 16 = 01000080 10000000 01000000 00000000
ffff8d53856d4000 598009207 C Ii:2:005:1 -2:8 0
ffff8d5346314000 598009225 S Co:2:005:0 s 01 03 0000 0300 0000 0
ffff8d5346314000 598009778 C Co:2:005:0 0 0
ffff8d5346314000 598009793 S Co:2:001:0 s 23 03 0005 0304 0000 0
ffff8d5346314000 598018051 C Co:2:001:0 0 0
ffff8d5346314000 598030036 S Co:2:001:0 s 23 03 001b 0701 0000 0
ffff8d5346314000 598030072 C Co:2:001:0 0 0
ffff8d5346314000 598030077 S Co:2:001:0 s 23 03 001b 0702 0000 0
ffff8d5346314000 598030101 C Co:2:001:0 0 0
ffff8d5346314000 598030103 S Co:2:001:0 s 23 03 001b 0703 0000 0
ffff8d5346314000 598030126 C Co:2:001:0 0 0
ffff8d5346314000 598030129 S Co:2:001:0 s 23 03 001b 0704 0000 0
ffff8d5346314000 598030153 C Co:2:001:0 0 0
ffff8d5346314000 598030156 S Co:2:001:0 s 23 03 001b 0705 0000 0
ffff8d5346314000 598030179 C Co:2:001:0 0 0
ffff8d5346314000 598030181 S Co:2:001:0 s 23 03 001b 0706 0000 0
ffff8d5346314000 598030204 C Co:2:001:0 0 0
ffff8d53427440c0 598030211 C Ii:2:001:1 -2:2048 0
ffff8d5396492f00 600438080 S Ci:2:001:0 s a3 00 0000 0001 0004 4 <
ffff8d5396492f00 600438116 C Ci:2:001:0 0 4 = a0020000
ffff8d5396492f00 600438124 S Ci:2:001:0 s a3 00 0000 0002 0004 4 <
ffff8d5396492f00 600438166 C Ci:2:001:0 0 4 = a0020000
ffff8d5340c1d780 600438201 S Ci:2:001:0 s a3 00 0000 0003 0004 4 <
ffff8d5340c1d780 600438215 C Ci:2:001:0 0 4 = a0020000
ffff8d5396492540 600438260 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5396492540 600438286 C Ci:2:001:0 0 4 = 63020000
ffff8d5396492540 600438293 S Ci:2:001:0 s a3 00 0000 0005 0004 4 <
ffff8d5396492540 600438314 C Ci:2:001:0 0 4 = a0020000
ffff8d5396492540 600438318 S Ci:2:001:0 s a3 00 0000 0006 0004 4 <
ffff8d5396492540 600438339 C Ci:2:001:0 0 4 = a0020000
ffff8d53427440c0 600438345 S Ii:2:001:1 -115:2048 4 <
ffff8d5396492540 600438356 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d5396492540 600438378 C Ci:2:001:0 0 4 = 63020000
ffff8d5396492540 600438394 S Co:2:001:0 s 23 03 0005 0004 0000 0
ffff8d5396492540 600457679 C Co:2:001:0 0 0
ffff8d53427440c0 600457687 C Ii:2:001:1 0:2048 1 = 10
ffff8d53427440c0 600457690 S Ii:2:001:1 -115:2048 4 <
ffff8d536c3e0480 600499045 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d536c3e0480 600499082 C Ci:2:001:0 0 4 = 03024000
ffff8d5396492540 600499097 S Co:2:001:0 s 23 01 0019 0004 0000 0
ffff8d5396492540 600499125 C Co:2:001:0 0 0
ffff8d536c3e0a80 600511032 S Ci:2:005:0 s 80 00 0000 0000 0002 2 <
ffff8d536c3e0a80 600511948 C Ci:2:005:0 0 2 = 0500
ffff8d536c3e0a80 600511967 S Ci:2:005:0 s 81 00 0000 0000 0002 2 <
ffff8d536c3e0a80 600512780 C Ci:2:005:0 0 2 = 0300
ffff8d536c3e0a80 600512837 S Co:2:005:0 s 01 03 0000 0000 0000 0
ffff8d536c3e0a80 600513284 C Co:2:005:0 0 0
ffff8d53463146c0 600513344 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff8d53463146c0 600513378 C Ci:2:001:0 0 4 = 23020000
ffff8d536c3e0a80 602522095 S Co:2:001:0 s 23 03 0005 0304 0000 0
ffff8d536c3e0a80 602530096 C Co:2:001:0 0 0
ffff8d536c3e0a80 602542503 S Co:2:001:0 s 23 03 001b 0701 0000 0
ffff8d536c3e0a80 602542600 C Co:2:001:0 0 0
ffff8d536c3e0a80 602542619 S Co:2:001:0 s 23 03 001b 0702 0000 0
ffff8d536c3e0a80 602542646 C Co:2:001:0 0 0
ffff8d536c3e0a80 602542664 S Co:2:001:0 s 23 03 001b 0703 0000 0
ffff8d536c3e0a80 602542689 C Co:2:001:0 0 0
ffff8d536c3e0a80 602542705 S Co:2:001:0 s 23 03 001b 0704 0000 0
ffff8d536c3e0a80 602542730 C Co:2:001:0 0 0
ffff8d536c3e0a80 602542746 S Co:2:001:0 s 23 03 001b 0705 0000 0
ffff8d536c3e0a80 602542770 C Co:2:001:0 0 0
ffff8d536c3e0a80 602542784 S Co:2:001:0 s 23 03 001b 0706 0000 0
ffff8d536c3e0a80 602542809 C Co:2:001:0 0 0
ffff8d53427440c0 602542829 C Ii:2:001:1 -2:2048 0

[-- Attachment #9: usbmon-l850-reverted.txt --]
[-- Type: text/plain, Size: 4783 bytes --]

ffff9f1f95301cc0 241962041 S Ci:2:001:0 s a3 00 0000 0001 0004 4 <
ffff9f1f95301cc0 241962105 C Ci:2:001:0 0 4 = a0020000
ffff9f1f95301cc0 241962120 S Ci:2:001:0 s a3 00 0000 0002 0004 4 <
ffff9f1f95301cc0 241962146 C Ci:2:001:0 0 4 = a0020000
ffff9f1f95301cc0 241962155 S Ci:2:001:0 s a3 00 0000 0003 0004 4 <
ffff9f1f95301cc0 241962180 C Ci:2:001:0 0 4 = a0020000
ffff9f1f95301cc0 241962187 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff9f1f95301cc0 241962211 C Ci:2:001:0 0 4 = 63020000
ffff9f1f95301cc0 241962219 S Ci:2:001:0 s a3 00 0000 0005 0004 4 <
ffff9f1f95301cc0 241962243 C Ci:2:001:0 0 4 = a0020000
ffff9f1f95301cc0 241962250 S Ci:2:001:0 s a3 00 0000 0006 0004 4 <
ffff9f1f95301cc0 241962273 C Ci:2:001:0 0 4 = a0020000
ffff9f1f42634600 241962280 S Ii:2:001:1 -115:2048 4 <
ffff9f1f95301cc0 241962299 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff9f1f95301cc0 241962338 C Ci:2:001:0 0 4 = 63020000
ffff9f1f95301cc0 241962348 S Co:2:001:0 s 23 03 0005 0004 0000 0
ffff9f1f42634600 241981671 C Ii:2:001:1 0:2048 1 = 10
ffff9f1f95301cc0 241981679 C Co:2:001:0 0 0
ffff9f1f42634600 241981695 S Ii:2:001:1 -115:2048 4 <
ffff9f1f42634600 242008057 C Ii:2:001:1 0:2048 1 = 10
ffff9f1f42634600 242008079 S Ii:2:001:1 -115:2048 4 <
ffff9f1f9522c780 242023027 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff9f1f9522c780 242023110 C Ci:2:001:0 0 4 = 03024000
ffff9f1f9522c780 242023119 S Co:2:001:0 s 23 01 0019 0004 0000 0
ffff9f1f9522c780 242023141 C Co:2:001:0 0 0
ffff9f1f9522ccc0 242035023 S Ci:2:003:0 s 80 00 0000 0000 0002 2 <
ffff9f1f9522ccc0 242035954 C Ci:2:003:0 0 2 = 0500
ffff9f1f9522ccc0 242036038 S Ci:2:003:0 s 81 00 0000 0000 0002 2 <
ffff9f1f9522ccc0 242036750 C Ci:2:003:0 0 2 = 0100
ffff9f1f9522ccc0 242036842 S Co:2:003:0 s 01 03 0000 0000 0000 0
ffff9f1f9522ccc0 242037208 C Co:2:003:0 0 0
ffff9f1f94bfb900 242037315 S Ii:2:003:1 -115:8 64 <
ffff9f1f953010c0 242037346 S Ci:2:001:0 s a3 00 0000 0004 0004 4 <
ffff9f1f953010c0 242037365 C Ci:2:001:0 0 4 = 23020000
ffff9f1f94bfbc00 242037631 S Co:2:003:0 s 21 00 0000 0000 0010 16 = 01000000 10000000 01000000 00020000
ffff9f1f94bfbc00 242038445 C Co:2:003:0 0 16 >
ffff9f1f94bfb900 242040665 C Ii:2:003:1 0:8 8 = a1010000 00000000
ffff9f1f94bfba80 242040696 S Ci:2:003:0 s a1 01 0000 0000 0200 512 <
ffff9f1f94bfb900 242040728 S Ii:2:003:1 -115:8 64 <
ffff9f1f94bfba80 242041545 C Ci:2:003:0 0 16 = 01000080 10000000 01000000 00000000
ffff9f1f94bfbc00 242042293 S Co:2:003:0 s 21 00 0000 0000 0030 48 = 03000000 30000000 02000000 01000000 00000000 a289cc33 bcbb8b4f b6b0133e
ffff9f1f94bfbc00 242043117 C Co:2:003:0 0 48 >
ffff9f1f94bfb900 242054652 C Ii:2:003:1 0:8 8 = a1010000 00000000
ffff9f1f94bfba80 242054679 S Ci:2:003:0 s a1 01 0000 0000 0200 512 <
ffff9f1f94bfb900 242054708 S Ii:2:003:1 -115:8 64 <
ffff9f1f94bfba80 242055463 C Ci:2:003:0 0 512 = 03000080 00020000 02000000 03000000 00000000 a289cc33 bcbb8b4f b6b0133e
ffff9f1f94bfb900 242055643 C Ii:2:003:1 0:8 8 = a1010000 00000000
ffff9f1f94bfba80 242055661 S Ci:2:003:0 s a1 01 0000 0000 0200 512 <
ffff9f1f94bfb900 242055699 S Ii:2:003:1 -115:8 64 <
ffff9f1f94bfba80 242056306 C Ci:2:003:0 0 512 = 03000080 00020000 02000000 03000000 01000000 02000000 01000000 03000000
ffff9f1f94bfb900 242056632 C Ii:2:003:1 0:8 8 = a1010000 00000000
ffff9f1f94bfba80 242056647 S Ci:2:003:0 s a1 01 0000 0000 0200 512 <
ffff9f1f94bfb900 242056673 S Ii:2:003:1 -115:8 64 <
ffff9f1f94bfba80 242057051 C Ci:2:003:0 0 64 = 03000080 40000000 02000000 03000000 02000000 3d01dcc5 fef54d05 0d3abef7
ffff9f1f94bfbc00 242058384 S Co:2:003:0 s 21 00 0000 0000 000c 12 = 02000000 0c000000 03000000
ffff9f1f94bfbc00 242058805 C Co:2:003:0 0 12 >
ffff9f1f94bfb900 242059688 C Ii:2:003:1 0:8 8 = a1010000 00000000
ffff9f1f94bfba80 242059711 S Ci:2:003:0 s a1 01 0000 0000 0200 512 <
ffff9f1f94bfb900 242059739 S Ii:2:003:1 -115:8 64 <
ffff9f1f94bfba80 242060132 C Ci:2:003:0 0 16 = 02000080 10000000 03000000 00000000
ffff9f1f94bfb900 242060453 C Ii:2:003:1 -2:8 0
ffff9f1f98e82f00 244537221 S Co:2:001:0 s 23 03 0005 0304 0000 0
ffff9f1f98e82f00 244545052 C Co:2:001:0 0 0
ffff9f1f98e82540 244557050 S Co:2:001:0 s 23 03 001b 0701 0000 0
ffff9f1f98e82540 244557093 C Co:2:001:0 0 0
ffff9f1f98e82540 244557101 S Co:2:001:0 s 23 03 001b 0702 0000 0
ffff9f1f98e82540 244557127 C Co:2:001:0 0 0
ffff9f1f98e82540 244557131 S Co:2:001:0 s 23 03 001b 0703 0000 0
ffff9f1f98e82540 244557155 C Co:2:001:0 0 0
ffff9f1f98e82540 244557158 S Co:2:001:0 s 23 03 001b 0704 0000 0
ffff9f1f98e82540 244557183 C Co:2:001:0 0 0
ffff9f1f98e82540 244557186 S Co:2:001:0 s 23 03 001b 0705 0000 0
ffff9f1f98e82540 244557211 C Co:2:001:0 0 0
ffff9f1f98e82540 244557215 S Co:2:001:0 s 23 03 001b 0706 0000 0
ffff9f1f98e82540 244557239 C Co:2:001:0 0 0
ffff9f1f42634600 244557248 C Ii:2:001:1 -2:2048 0

^ permalink raw reply	[flat|nested] 12+ messages in thread

* [PATCH] usb: cdc-wdm: close race between read and workqueue
@ 2024-03-14 11:50 Oliver Neukum
  0 siblings, 0 replies; 12+ messages in thread
From: Oliver Neukum @ 2024-03-14 11:50 UTC (permalink / raw)
  To: gregkh, linux-usb; +Cc: Oliver Neukum

wdm_read() cannot race with itself. However, in
service_outstanding_interrupt() it can race with the
workqueue, which can be triggered by error handling.

Hence we need to make sure that the WDM_RESPONDING
flag is not just only set but tested.

Fixes: afba937e540c9 ("USB: CDC WDM driver")
Signed-off-by: Oliver Neukum <oneukum@suse.com>
---
 drivers/usb/class/cdc-wdm.c | 6 +++++-
 1 file changed, 5 insertions(+), 1 deletion(-)

diff --git a/drivers/usb/class/cdc-wdm.c b/drivers/usb/class/cdc-wdm.c
index c553decb5461..c8262e2f2917 100644
--- a/drivers/usb/class/cdc-wdm.c
+++ b/drivers/usb/class/cdc-wdm.c
@@ -485,6 +485,7 @@ static ssize_t wdm_write
 static int service_outstanding_interrupt(struct wdm_device *desc)
 {
 	int rv = 0;
+	int used;
 
 	/* submit read urb only if the device is waiting for it */
 	if (!desc->resp_count || !--desc->resp_count)
@@ -499,7 +500,10 @@ static int service_outstanding_interrupt(struct wdm_device *desc)
 		goto out;
 	}
 
-	set_bit(WDM_RESPONDING, &desc->flags);
+	used = test_and_set_bit(WDM_RESPONDING, &desc->flags);
+	if (used)
+		goto out;
+
 	spin_unlock_irq(&desc->iuspin);
 	rv = usb_submit_urb(desc->response, GFP_KERNEL);
 	spin_lock_irq(&desc->iuspin);
-- 
2.44.0


^ permalink raw reply related	[flat|nested] 12+ messages in thread

end of thread, other threads:[~2024-04-15 12:08 UTC | newest]

Thread overview: 12+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
     [not found] <20240314115132.3907-1-oneukum () suse ! com>
2024-04-15  5:42 ` [PATCH] usb: cdc-wdm: close race between read and workqueue Aleksander Morgado
2024-04-15  6:47   ` Bjørn Mork
2024-04-15  9:06     ` Oliver Neukum
2024-04-15  9:26       ` Bjørn Mork
2024-04-15  9:52         ` Greg KH
2024-04-15 10:07           ` Oliver Neukum
2024-04-15 10:14         ` Oliver Neukum
2024-04-15 10:53           ` Bjørn Mork
2024-04-15 11:08             ` Oliver Neukum
2024-04-15  8:42   ` Oliver Neukum
2024-04-15 12:08     ` Aleksander Morgado
2024-03-14 11:50 Oliver Neukum

This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.