Error message - normal or some problem inside qmicli in parallel usage?

Martin Maurer martin.maurer at mmeacs.de
Tue Sep 5 14:00:05 UTC 2023


I have collected and attached two logfiles of the parallel execution to 
this email:

I added "-v" to each qmicli call to get more internal info.

You can find

error: couldn't create client for the 'wds' service: CID allocation 
failed in the CTL client: Service mismatch (requested 'wds', got 'dms')

in file connect_5g_telekom.log

line 176


Am 05.09.2023 um 11:18 schrieb Martin Maurer:
> Hello,
>
> I am using two modules (Quectel 4G and 5G, both time Telekom prepaid 
> SIM card) at the same time on a Raspberry PI 4.
>
> I am controlling both at the same time from different ssh session.
>
> Today I got multiple times:
>
> error: couldn't create client for the 'dms' service: CID allocation 
> failed in the CTL client: Service mismatch (requested 'dms', got 'wds')
>
> I am using the following commands:
>
> (for one of the modules)
>
> sudo qmicli --device=/dev/cdc-wdm0 --device-open-proxy 
> --dms-get-manufacturer
> sudo qmicli --device=/dev/cdc-wdm0 --device-open-proxy --dms-get-model
> sudo qmicli --device=/dev/cdc-wdm0 --device-open-proxy --dms-get-revision
> sudo qmicli --device=/dev/cdc-wdm0 --device-open-proxy --dms-get-ids
> sudo qmicli --device=/dev/cdc-wdm0 --device-open-proxy 
> --uim-get-card-status
>
> as well as connection establishment like
>
> sudo qmicli --device=/dev/cdc-wdm0 --device-open-proxy 
> --wds-start-network="ip-type=4,apn=internet.telekom" 
> --client-no-release-cid
>
> and parallel
>
> (for the other of the modules)
>
> sudo qmicli --device=/dev/cdc-wdm1 --device-open-proxy 
> --dms-get-manufacturer
> sudo qmicli --device=/dev/cdc-wdm1 --device-open-proxy --dms-get-model
> sudo qmicli --device=/dev/cdc-wdm1 --device-open-proxy --dms-get-revision
> sudo qmicli --device=/dev/cdc-wdm1 --device-open-proxy --dms-get-ids
> sudo qmicli --device=/dev/cdc-wdm1 --device-open-proxy 
> --uim-get-card-status
>
> as well as connection establishment like
>
> sudo qmicli --device=/dev/cdc-wdm1 --device-open-proxy 
> --wds-start-network="ip-type=4,apn=internet.telekom" 
> --client-no-release-cid
>
> I assume some actions querying infos collide with collection 
> establishment?
>
> Is there some log I can provide/analyse in case I see such an error?
>
> Best regards,
>
> Martin
>
-------------- next part --------------
trio at radio:~ $ ./connectme_4g_telekom.sh
Y
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] automatically selecting QMI mode
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] created endpoint
[05 Sep 2023, 15:51:35] [Debug] cannot connect to proxy: Could not connect: Connection refused
[05 Sep 2023, 15:51:35] [Debug] spawning new qmi-proxy (try 1)...
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:31

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:31
<<<<<<   translated = /dev/cdc-wdm1

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:51:35] [Debug] QMI Device at '/dev/cdc-wdm1' ready
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] Assuming service 'dms' is supported...
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] Allocating new client ID...
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:02

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = dms

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:01

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] Registered 'dms' (version unknown) client with ID '1'
[05 Sep 2023, 15:51:35] [Debug] Asynchronously getting revision...
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:01:00:23:00:00:00

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Revision" (0x0023)

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 39
<<<<<<   data   = 01:26:00:80:02:01:02:01:00:23:00:1A:00:02:04:00:00:00:00:00:01:10:00:45:4D:30:35:45:46:41:52:30:36:41:30:36:4D:34:47

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 38
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 26
<<<<<<   message     = "Get Revision" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Revision" (0x01)
<<<<<<   length     = 16
<<<<<<   value      = 45:4D:30:35:45:46:41:52:30:36:41:30:36:4D:34:47
<<<<<<   translated = EM05EFAR06A06M4G

[/dev/cdc-wdm1] Device revision retrieved:
        Revision: 'EM05EFAR06A06M4G'
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] Releasing 'dms' client with flags 'release-cid'...
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] Unregistered 'dms' client with ID '1'
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:03:23:00:05:00:01:02:00:02:01

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:03:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:01

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:51:35] [Debug] Client released
[05 Sep 2023, 15:51:35] [Debug] Closed
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] automatically selecting QMI mode
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] created endpoint
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:31

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:31
<<<<<<   translated = /dev/cdc-wdm1

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:51:35] [Debug] QMI Device at '/dev/cdc-wdm1' ready
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] Assuming service 'wds' is supported...
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] Allocating new client ID...
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:01

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = wds

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:01:14

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 01:14
<<<<<<   translated = [ service = 'wds' cid = '20' ]

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] Registered 'wds' (version unknown) client with ID '20'
[05 Sep 2023, 15:51:35] [Debug] Network start parameters set (apn: 'internet.telekom', 3gpp_profile: '0', 3gpp2_profile: '0', auth: 'unspecified', ip-type: '4', username: 'unspecified', password: 'unspecified', autoconnect: 'unspecified')
[05 Sep 2023, 15:51:35] [Debug] Asynchronously starting network...
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 36
<<<<<<   data   = 01:23:00:00:01:14:00:01:00:20:00:17:00:19:01:00:04:14:10:00:69:6E:74:65:72:6E:65:74:2E:74:65:6C:65:6B:6F:6D

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 35
<<<<<<   flags   = 0x00
<<<<<<   service = "wds"
<<<<<<   client  = 20
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 23
<<<<<<   message     = "Start Network" (0x0020)
<<<<<< TLV:
<<<<<<   type       = "IP Family Preference" (0x19)
<<<<<<   length     = 1
<<<<<<   value      = 04
<<<<<<   translated = ipv4
<<<<<< TLV:
<<<<<<   type       = "APN" (0x14)
<<<<<<   length     = 16
<<<<<<   value      = 69:6E:74:65:72:6E:65:74:2E:74:65:6C:65:6B:6F:6D
<<<<<<   translated = internet.telekom

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 27
<<<<<<   data   = 01:1A:00:80:01:14:02:01:00:20:00:0E:00:02:04:00:00:00:00:00:01:04:00:40:A2:29:87

[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 26
<<<<<<   flags   = 0x80
<<<<<<   service = "wds"
<<<<<<   client  = 20
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 14
<<<<<<   message     = "Start Network" (0x0020)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Packet Data Handle" (0x01)
<<<<<<   length     = 4
<<<<<<   value      = 40:A2:29:87
<<<<<<   translated = 2267652672

[/dev/cdc-wdm1] Network started
        Packet data handle: '2267652672'
[/dev/cdc-wdm1] Client ID not released:
        Service: 'wds'
            CID: '20'
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] Releasing 'wds' client with flags 'none'...
[05 Sep 2023, 15:51:35] [Debug] [/dev/cdc-wdm1] Unregistered 'wds' client with ID '20'
[05 Sep 2023, 15:51:35] [Debug] Client released
[05 Sep 2023, 15:51:35] [Debug] Closed
udhcpc: started, v1.30.1
No resolv.conf for interface wwan1.udhcpc
udhcpc: sending discover
udhcpc: sending select for 10.175.144.179
udhcpc: lease of 10.175.144.179 obtained, lease time 7200
/etc/udhcpc/default.script: Resetting default routes
SIOCDELRT: No such process
/etc/udhcpc/default.script: Adding DNS 10.74.210.210
/etc/udhcpc/default.script: Adding DNS 10.74.210.211
PING 8.8.8.8 (8.8.8.8) from 10.175.144.179 wwan1: 56(84) bytes of data.
64 bytes from 8.8.8.8: icmp_seq=1 ttl=112 time=145 ms
64 bytes from 8.8.8.8: icmp_seq=2 ttl=112 time=37.7 ms
64 bytes from 8.8.8.8: icmp_seq=3 ttl=112 time=33.8 ms
64 bytes from 8.8.8.8: icmp_seq=4 ttl=112 time=44.1 ms
64 bytes from 8.8.8.8: icmp_seq=5 ttl=112 time=49.4 ms
64 bytes from 8.8.8.8: icmp_seq=6 ttl=112 time=39.3 ms
64 bytes from 8.8.8.8: icmp_seq=7 ttl=112 time=39.9 ms
64 bytes from 8.8.8.8: icmp_seq=8 ttl=112 time=39.0 ms
64 bytes from 8.8.8.8: icmp_seq=9 ttl=112 time=35.9 ms
64 bytes from 8.8.8.8: icmp_seq=10 ttl=112 time=42.7 ms
64 bytes from 8.8.8.8: icmp_seq=11 ttl=112 time=34.8 ms
64 bytes from 8.8.8.8: icmp_seq=12 ttl=112 time=50.1 ms
64 bytes from 8.8.8.8: icmp_seq=13 ttl=112 time=42.1 ms
64 bytes from 8.8.8.8: icmp_seq=14 ttl=112 time=38.7 ms
64 bytes from 8.8.8.8: icmp_seq=15 ttl=112 time=35.5 ms
64 bytes from 8.8.8.8: icmp_seq=16 ttl=112 time=37.4 ms
64 bytes from 8.8.8.8: icmp_seq=17 ttl=112 time=35.5 ms
64 bytes from 8.8.8.8: icmp_seq=18 ttl=112 time=51.0 ms
64 bytes from 8.8.8.8: icmp_seq=19 ttl=112 time=49.4 ms
64 bytes from 8.8.8.8: icmp_seq=20 ttl=112 time=41.1 ms
64 bytes from 8.8.8.8: icmp_seq=21 ttl=112 time=47.4 ms
64 bytes from 8.8.8.8: icmp_seq=22 ttl=112 time=36.8 ms
64 bytes from 8.8.8.8: icmp_seq=23 ttl=112 time=37.6 ms
64 bytes from 8.8.8.8: icmp_seq=24 ttl=112 time=33.6 ms
64 bytes from 8.8.8.8: icmp_seq=25 ttl=112 time=50.6 ms
64 bytes from 8.8.8.8: icmp_seq=26 ttl=112 time=38.5 ms
64 bytes from 8.8.8.8: icmp_seq=27 ttl=112 time=46.6 ms
64 bytes from 8.8.8.8: icmp_seq=28 ttl=112 time=38.6 ms
^C
--- 8.8.8.8 ping statistics ---
28 packets transmitted, 28 received, 0% packet loss, time 27040ms
rtt min/avg/max/mdev = 33.582/44.728/145.144/20.059 ms
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] automatically selecting QMI mode
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] created endpoint
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:31

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:31
<<<<<<   translated = /dev/cdc-wdm1

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:52:03] [Debug] QMI Device at '/dev/cdc-wdm1' ready
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Assuming service 'dms' is supported...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Allocating new client ID...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:02

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = dms

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:01

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Registered 'dms' (version unknown) client with ID '1'
[05 Sep 2023, 15:52:03] [Debug] Asynchronously getting manufacturer...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:01:00:21:00:00:00

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Manufacturer" (0x0021)

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 44
<<<<<<   data   = 01:2B:00:80:02:01:02:01:00:21:00:1F:00:02:04:00:00:00:00:00:01:15:00:51:55:41:4C:43:4F:4D:4D:20:49:4E:43:4F:52:50:4F:52:41:54:45:44

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 43
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 31
<<<<<<   message     = "Get Manufacturer" (0x0021)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Manufacturer" (0x01)
<<<<<<   length     = 21
<<<<<<   value      = 51:55:41:4C:43:4F:4D:4D:20:49:4E:43:4F:52:50:4F:52:41:54:45:44
<<<<<<   translated = QUALCOMM INCORPORATED

[/dev/cdc-wdm1] Device manufacturer retrieved:
        Manufacturer: 'QUALCOMM INCORPORATED'
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Releasing 'dms' client with flags 'release-cid'...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Unregistered 'dms' client with ID '1'
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:03:23:00:05:00:01:02:00:02:01

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:03:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:01

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:52:03] [Debug] Client released
[05 Sep 2023, 15:52:03] [Debug] Closed
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] automatically selecting QMI mode
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] created endpoint
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:31

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:31
<<<<<<   translated = /dev/cdc-wdm1

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:52:03] [Debug] QMI Device at '/dev/cdc-wdm1' ready
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Assuming service 'dms' is supported...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Allocating new client ID...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:02

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = dms

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:01

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Registered 'dms' (version unknown) client with ID '1'
[05 Sep 2023, 15:52:03] [Debug] Asynchronously getting model...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:01:00:22:00:00:00

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Model" (0x0022)

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 54
<<<<<<   data   = 01:35:00:80:02:01:02:01:00:22:00:29:00:02:04:00:00:00:00:00:01:1F:00:51:55:45:43:54:45:4C:20:4D:6F:62:69:6C:65:20:42:72:6F:61:64:62:61:6E:64:20:4D:6F:64:75:6C:65

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 53
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 41
<<<<<<   message     = "Get Model" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Model" (0x01)
<<<<<<   length     = 31
<<<<<<   value      = 51:55:45:43:54:45:4C:20:4D:6F:62:69:6C:65:20:42:72:6F:61:64:62:61:6E:64:20:4D:6F:64:75:6C:65
<<<<<<   translated = QUECTEL Mobile Broadband Module

[/dev/cdc-wdm1] Device model retrieved:
        Model: 'QUECTEL Mobile Broadband Module'
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Releasing 'dms' client with flags 'release-cid'...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Unregistered 'dms' client with ID '1'
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:03:23:00:05:00:01:02:00:02:01

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:03:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:01

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:52:03] [Debug] Client released
[05 Sep 2023, 15:52:03] [Debug] Closed
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] automatically selecting QMI mode
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] created endpoint
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:31

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:31
<<<<<<   translated = /dev/cdc-wdm1

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:52:03] [Debug] QMI Device at '/dev/cdc-wdm1' ready
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Assuming service 'dms' is supported...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Allocating new client ID...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:02

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = dms

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:01

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Registered 'dms' (version unknown) client with ID '1'
[05 Sep 2023, 15:52:03] [Debug] Asynchronously getting revision...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:01:00:23:00:00:00

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Revision" (0x0023)

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 39
<<<<<<   data   = 01:26:00:80:02:01:02:01:00:23:00:1A:00:02:04:00:00:00:00:00:01:10:00:45:4D:30:35:45:46:41:52:30:36:41:30:36:4D:34:47

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 38
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 26
<<<<<<   message     = "Get Revision" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Revision" (0x01)
<<<<<<   length     = 16
<<<<<<   value      = 45:4D:30:35:45:46:41:52:30:36:41:30:36:4D:34:47
<<<<<<   translated = EM05EFAR06A06M4G

[/dev/cdc-wdm1] Device revision retrieved:
        Revision: 'EM05EFAR06A06M4G'
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Releasing 'dms' client with flags 'release-cid'...
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] Unregistered 'dms' client with ID '1'
[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:03:23:00:05:00:01:02:00:02:01

[05 Sep 2023, 15:52:03] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:03:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:01

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:52:04] [Debug] Client released
[05 Sep 2023, 15:52:04] [Debug] Closed
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] automatically selecting QMI mode
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] created endpoint
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:31

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:31
<<<<<<   translated = /dev/cdc-wdm1

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:52:04] [Debug] QMI Device at '/dev/cdc-wdm1' ready
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] Assuming service 'dms' is supported...
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] Allocating new client ID...
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:02

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = dms

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:01

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] Registered 'dms' (version unknown) client with ID '1'
[05 Sep 2023, 15:52:04] [Debug] Asynchronously getting IDs...
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:01:00:25:00:00:00

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get IDs" (0x0025)

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 71
<<<<<<   data   = 01:46:00:80:02:01:02:01:00:25:00:3A:00:02:04:00:00:00:00:00:10:08:00:38:30:45:38:44:35:30:30:11:0F:00:38:36:37:37:35:32:30:35:30:31:31:37:35:35:33:12:0E:00:41:31:30:30:30:30:32:34:37:43:39:45:45:31:13:02:00:31:41

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 70
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 58
<<<<<<   message     = "Get IDs" (0x0025)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Esn" (0x10)
<<<<<<   length     = 8
<<<<<<   value      = 38:30:45:38:44:35:30:30
<<<<<<   translated = 80E8D500
<<<<<< TLV:
<<<<<<   type       = "Imei" (0x11)
<<<<<<   length     = 15
<<<<<<   value      = 38:36:37:37:35:32:30:35:30:31:31:37:35:35:33
<<<<<<   translated = 867752050117553
<<<<<< TLV:
<<<<<<   type       = "Meid" (0x12)
<<<<<<   length     = 14
<<<<<<   value      = 41:31:30:30:30:30:32:34:37:43:39:45:45:31
<<<<<<   translated = A10000247C9EE1
<<<<<< TLV:
<<<<<<   type       = "Imei Software Version" (0x13)
<<<<<<   length     = 2
<<<<<<   value      = 31:41
<<<<<<   translated = 1A

[/dev/cdc-wdm1] Device IDs retrieved:
            ESN: '80E8D500'
           IMEI: '867752050117553'
           MEID: 'A10000247C9EE1'
        IMEI SV: '1A'
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] Releasing 'dms' client with flags 'release-cid'...
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] Unregistered 'dms' client with ID '1'
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:03:23:00:05:00:01:02:00:02:01

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:03:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:01

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]

[05 Sep 2023, 15:52:04] [Debug] Client released
[05 Sep 2023, 15:52:04] [Debug] Closed
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] automatically selecting QMI mode
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] created endpoint
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:31

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:31
<<<<<<   translated = /dev/cdc-wdm1

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:52:04] [Debug] QMI Device at '/dev/cdc-wdm1' ready
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] Assuming service 'uim' is supported...
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] Allocating new client ID...
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:0B

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 0B
<<<<<<   translated = uim

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:0B:01

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 0B:01
<<<<<<   translated = [ service = 'uim' cid = '1' ]

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] Registered 'uim' (version unknown) client with ID '1'
[05 Sep 2023, 15:52:04] [Debug] Asynchronously getting card status...
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:0B:01:00:01:00:2F:00:00:00

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "uim"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Card Status" (0x002F)

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 78
<<<<<<   data   = 01:4D:00:80:0B:01:02:01:00:2F:00:41:00:02:04:00:00:00:00:00:15:02:00:01:00:11:02:00:01:00:10:2D:00:00:00:FF:FF:FF:FF:FF:FF:01:01:00:00:00:00:01:02:07:02:0B:00:00:10:A0:00:00:00:87:10:02:FF:49:94:20:89:03:10:00:00:00:03:03:0A:01:01:0A

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 77
<<<<<<   flags   = 0x80
<<<<<<   service = "uim"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 65
<<<<<<   message     = "Get Card Status" (0x002F)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type   = 0x15
<<<<<<   length = 2
<<<<<<   value  = 01:00
<<<<<< TLV:
<<<<<<   type   = 0x11
<<<<<<   length = 2
<<<<<<   value  = 01:00
<<<<<< TLV:
<<<<<<   type       = "Card Status" (0x10)
<<<<<<   length     = 45
<<<<<<   value      = 00:00:FF:FF:FF:FF:FF:FF:01:01:00:00:00:00:01:02:07:02:0B:00:00:10:A0:00:00:00:87:10:02:FF:49:94:20:89:03:10:00:00:00:03:03:0A:01:01:0A
<<<<<<   translated = [ index_gw_primary = '0' index_1x_primary = '65535' index_gw_secondary = '65535' index_1x_secondary = '65535' cards = '{ [0] = '[ card_state = 'present' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'unknown' applications = '{ [0] = '[ type = 'usim' state = 'ready' personalization_state = 'ready' personalization_feature = 'unknown' personalization_retries = '0' personalization_unblock_retries = '0' application_identifier_value = '{ [0] = '160 ' [1] = '0 ' [2] = '0 ' [3] = '0 ' [4] = '135 ' [5] = '16 ' [6] = '2 ' [7] = '255 ' [8] = '73 ' [9] = '148 ' [10] = '32 ' [11] = '137 ' [12] = '3 ' [13] = '16 ' [14] = '0 ' [15] = '0 '}' upin_replaces_pin1 = 'no' pin1_state = 'disabled' pin1_retries = '3' puk1_retries = '10' pin2_state = 'enabled-not-verified' pin2_retries = '1' puk2_retries = '10' ] '}' ] '}' ]

[/dev/cdc-wdm1] Successfully got card status
Provisioning applications:
        Primary GW:   slot '1', application '1'
        Primary 1X:   session doesn't exist
        Secondary GW: session doesn't exist
        Secondary 1X: session doesn't exist
Slot [1]:
        Card state: 'present'
        UPIN state: 'not-initialized'
                UPIN retries: '0'
                UPUK retries: '0'
        Application [1]:
                Application type:  'usim (2)'
                Application state: 'ready'
                Application ID:
                        A0:00:00:00:87:10:02:FF:49:94:20:89:03:10:00:00
                Personalization state: 'ready'
                UPIN replaces PIN1: 'no'
                PIN1 state: 'disabled'
                        PIN1 retries: '3'
                        PUK1 retries: '10'
                PIN2 state: 'enabled-not-verified'
                        PIN2 retries: '1'
                        PUK2 retries: '10'
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] Releasing 'uim' client with flags 'release-cid'...
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] Unregistered 'uim' client with ID '1'
[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:03:23:00:05:00:01:02:00:0B:01

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 0B:01
<<<<<<   translated = [ service = 'uim' cid = '1' ]

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:03:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:0B:01

[05 Sep 2023, 15:52:04] [Debug] [/dev/cdc-wdm1] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 0B:01
<<<<<<   translated = [ service = 'uim' cid = '1' ]

[05 Sep 2023, 15:52:04] [Debug] Client released
[05 Sep 2023, 15:52:04] [Debug] Closed
trio at radio:~ $
-------------- next part --------------
trio at radio:~ $ ./connectme_5g_telekom.sh
Y
[05 Sep 2023, 15:51:36] [Debug] [/dev/cdc-wdm0] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:51:36] [Debug] [/dev/cdc-wdm0] automatically selecting QMI mode
[05 Sep 2023, 15:51:36] [Debug] [/dev/cdc-wdm0] created endpoint
[05 Sep 2023, 15:51:36] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30

[05 Sep 2023, 15:51:36] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
<<<<<<   translated = /dev/cdc-wdm0

[05 Sep 2023, 15:51:36] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:51:36] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:51:36] [Debug] QMI Device at '/dev/cdc-wdm0' ready
[05 Sep 2023, 15:51:36] [Debug] [/dev/cdc-wdm0] Assuming service 'dms' is supported...
[05 Sep 2023, 15:51:36] [Debug] [/dev/cdc-wdm0] Allocating new client ID...
[05 Sep 2023, 15:51:36] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:02

[05 Sep 2023, 15:51:36] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = dms

[05 Sep 2023, 15:51:46] [Debug] transaction 0x2 aborted, but message is not abortable
error: couldn't create client for the 'dms' service: CID allocation failed in the CTL client: Transaction timed out
[05 Sep 2023, 15:51:46] [Debug] [/dev/cdc-wdm0] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:51:46] [Debug] [/dev/cdc-wdm0] automatically selecting QMI mode
[05 Sep 2023, 15:51:46] [Debug] [/dev/cdc-wdm0] created endpoint
[05 Sep 2023, 15:51:46] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30

[05 Sep 2023, 15:51:46] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
<<<<<<   translated = /dev/cdc-wdm0

[05 Sep 2023, 15:51:46] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:51:46] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:51:46] [Debug] QMI Device at '/dev/cdc-wdm0' ready
[05 Sep 2023, 15:51:46] [Debug] [/dev/cdc-wdm0] Assuming service 'wds' is supported...
[05 Sep 2023, 15:51:46] [Debug] [/dev/cdc-wdm0] Allocating new client ID...
[05 Sep 2023, 15:51:46] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:01

[05 Sep 2023, 15:51:46] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = wds

[05 Sep 2023, 15:51:47] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:04

[05 Sep 2023, 15:51:47] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:04
<<<<<<   translated = [ service = 'dms' cid = '4' ]

error: couldn't create client for the 'wds' service: CID allocation failed in the CTL client: Service mismatch (requested 'wds', got 'dms')
udhcpc: started, v1.30.1
No resolv.conf for interface wwan0.udhcpc
udhcpc: sending discover
udhcpc: sending discover
udhcpc: sending discover
/etc/udhcpc/default.script: Lease failed:
udhcpc: no lease, failing
ping: Warning: source address might be selected on device other than: wwan0
PING 8.8.8.8 (8.8.8.8) from 192.168.180.1 wwan0: 56(84) bytes of data.
^C
--- 8.8.8.8 ping statistics ---
7 packets transmitted, 0 received, 100% packet loss, time 9229ms

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] automatically selecting QMI mode
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] created endpoint
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
<<<<<<   translated = /dev/cdc-wdm0

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:52:07] [Debug] QMI Device at '/dev/cdc-wdm0' ready
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Assuming service 'dms' is supported...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Allocating new client ID...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:02

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = dms

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:05

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:05
<<<<<<   translated = [ service = 'dms' cid = '5' ]

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Registered 'dms' (version unknown) client with ID '5'
[05 Sep 2023, 15:52:07] [Debug] Asynchronously getting manufacturer...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:05:00:01:00:21:00:00:00

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 5
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Manufacturer" (0x0021)

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 30
<<<<<<   data   = 01:1D:00:80:02:05:02:01:00:21:00:11:00:02:04:00:00:00:00:00:01:07:00:51:75:65:63:74:65:6C

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 29
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 5
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 17
<<<<<<   message     = "Get Manufacturer" (0x0021)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Manufacturer" (0x01)
<<<<<<   length     = 7
<<<<<<   value      = 51:75:65:63:74:65:6C
<<<<<<   translated = Quectel

[/dev/cdc-wdm0] Device manufacturer retrieved:
        Manufacturer: 'Quectel'
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Releasing 'dms' client with flags 'release-cid'...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Unregistered 'dms' client with ID '5'
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:03:23:00:05:00:01:02:00:02:05

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:05
<<<<<<   translated = [ service = 'dms' cid = '5' ]

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:03:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:05

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:05
<<<<<<   translated = [ service = 'dms' cid = '5' ]

[05 Sep 2023, 15:52:07] [Debug] Client released
[05 Sep 2023, 15:52:07] [Debug] Closed
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] automatically selecting QMI mode
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] created endpoint
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
<<<<<<   translated = /dev/cdc-wdm0

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:52:07] [Debug] QMI Device at '/dev/cdc-wdm0' ready
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Assuming service 'dms' is supported...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Allocating new client ID...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:02

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = dms

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:05

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:05
<<<<<<   translated = [ service = 'dms' cid = '5' ]

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Registered 'dms' (version unknown) client with ID '5'
[05 Sep 2023, 15:52:07] [Debug] Asynchronously getting model...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:05:00:01:00:22:00:00:00

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 5
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Model" (0x0022)

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 32
<<<<<<   data   = 01:1F:00:80:02:05:02:01:00:22:00:13:00:02:04:00:00:00:00:00:01:09:00:52:4D:35:32:30:4E:2D:47:4C

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 31
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 5
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 19
<<<<<<   message     = "Get Model" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Model" (0x01)
<<<<<<   length     = 9
<<<<<<   value      = 52:4D:35:32:30:4E:2D:47:4C
<<<<<<   translated = RM520N-GL

[/dev/cdc-wdm0] Device model retrieved:
        Model: 'RM520N-GL'
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Releasing 'dms' client with flags 'release-cid'...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Unregistered 'dms' client with ID '5'
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:03:23:00:05:00:01:02:00:02:05

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:05
<<<<<<   translated = [ service = 'dms' cid = '5' ]

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:03:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:05

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:05
<<<<<<   translated = [ service = 'dms' cid = '5' ]

[05 Sep 2023, 15:52:07] [Debug] Client released
[05 Sep 2023, 15:52:07] [Debug] Closed
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] automatically selecting QMI mode
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] created endpoint
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
<<<<<<   translated = /dev/cdc-wdm0

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:52:07] [Debug] QMI Device at '/dev/cdc-wdm0' ready
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Assuming service 'dms' is supported...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Allocating new client ID...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:02

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = dms

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:05

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:05
<<<<<<   translated = [ service = 'dms' cid = '5' ]

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Registered 'dms' (version unknown) client with ID '5'
[05 Sep 2023, 15:52:07] [Debug] Asynchronously getting revision...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:05:00:01:00:23:00:00:00

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 5
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Revision" (0x0023)

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 42
<<<<<<   data   = 01:29:00:80:02:05:02:01:00:23:00:1D:00:02:04:00:00:00:00:00:01:13:00:52:4D:35:32:30:4E:47:4C:41:41:52:30:31:41:30:37:4D:34:47

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 41
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 5
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 29
<<<<<<   message     = "Get Revision" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Revision" (0x01)
<<<<<<   length     = 19
<<<<<<   value      = 52:4D:35:32:30:4E:47:4C:41:41:52:30:31:41:30:37:4D:34:47
<<<<<<   translated = RM520NGLAAR01A07M4G

[/dev/cdc-wdm0] Device revision retrieved:
        Revision: 'RM520NGLAAR01A07M4G'
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Releasing 'dms' client with flags 'release-cid'...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Unregistered 'dms' client with ID '5'
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:03:23:00:05:00:01:02:00:02:05

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:05
<<<<<<   translated = [ service = 'dms' cid = '5' ]

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:03:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:05

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:05
<<<<<<   translated = [ service = 'dms' cid = '5' ]

[05 Sep 2023, 15:52:07] [Debug] Client released
[05 Sep 2023, 15:52:07] [Debug] Closed
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] automatically selecting QMI mode
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] created endpoint
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
<<<<<<   translated = /dev/cdc-wdm0

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:52:07] [Debug] QMI Device at '/dev/cdc-wdm0' ready
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Assuming service 'dms' is supported...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Allocating new client ID...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:02

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = dms

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:05

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:05
<<<<<<   translated = [ service = 'dms' cid = '5' ]

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Registered 'dms' (version unknown) client with ID '5'
[05 Sep 2023, 15:52:07] [Debug] Asynchronously getting IDs...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:05:00:01:00:25:00:00:00

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 5
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get IDs" (0x0025)

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 47
<<<<<<   data   = 01:2E:00:80:02:05:02:01:00:25:00:22:00:02:04:00:00:00:00:00:10:01:00:30:11:0F:00:38:36:38:33:37:31:30:35:30:35:35:35:33:37:30:13:02:00:32:39

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 46
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 5
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 34
<<<<<<   message     = "Get IDs" (0x0025)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Esn" (0x10)
<<<<<<   length     = 1
<<<<<<   value      = 30
<<<<<<   translated = 0
<<<<<< TLV:
<<<<<<   type       = "Imei" (0x11)
<<<<<<   length     = 15
<<<<<<   value      = 38:36:38:33:37:31:30:35:30:35:35:35:33:37:30
<<<<<<   translated = 868371050555370
<<<<<< TLV:
<<<<<<   type       = "Imei Software Version" (0x13)
<<<<<<   length     = 2
<<<<<<   value      = 32:39
<<<<<<   translated = 29

[/dev/cdc-wdm0] Device IDs retrieved:
            ESN: '0'
           IMEI: '868371050555370'
           MEID: 'unknown'
        IMEI SV: '29'
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Releasing 'dms' client with flags 'release-cid'...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Unregistered 'dms' client with ID '5'
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:03:23:00:05:00:01:02:00:02:05

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:05
<<<<<<   translated = [ service = 'dms' cid = '5' ]

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:03:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:05

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:05
<<<<<<   translated = [ service = 'dms' cid = '5' ]

[05 Sep 2023, 15:52:07] [Debug] Client released
[05 Sep 2023, 15:52:07] [Debug] Closed
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Opening device with flags 'proxy, auto'...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] automatically selecting QMI mode
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] created endpoint
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
<<<<<<   translated = /dev/cdc-wdm0

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[05 Sep 2023, 15:52:07] [Debug] QMI Device at '/dev/cdc-wdm0' ready
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Assuming service 'uim' is supported...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Allocating new client ID...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:0B

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 0B
<<<<<<   translated = uim

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:0B:02

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 0B:02
<<<<<<   translated = [ service = 'uim' cid = '2' ]

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] Registered 'uim' (version unknown) client with ID '2'
[05 Sep 2023, 15:52:07] [Debug] Asynchronously getting card status...
[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:0B:02:00:01:00:2F:00:00:00

[05 Sep 2023, 15:52:07] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Card Status" (0x002F)

[05 Sep 2023, 15:52:08] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 130
<<<<<<   data   = 01:81:00:80:0B:02:02:01:00:2F:00:75:00:02:04:00:00:00:00:00:18:02:00:01:00:17:24:00:01:0A:98:94:20:04:00:91:91:15:16:71:16:3B:9F:96:80:1F:C6:80:31:E0:73:FE:21:1B:66:D0:02:3B:FE:14:10:01:C2:00:16:05:00:01:00:00:00:00:15:02:00:01:00:11:02:00:01:00:10:2D:00:00:00:FF:FF:FF:FF:FF:FF:01:01:00:00:00:00:01:02:07:02:0B:00:00:10:A0:00:00:00:87:10:02:FF:49:94:20:89:03:10:00:00:00:03:03:0A:01:03:0A

[05 Sep 2023, 15:52:08] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 129
<<<<<<   flags   = 0x80
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 117
<<<<<<   message     = "Get Card Status" (0x002F)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type   = 0x18
<<<<<<   length = 2
<<<<<<   value  = 01:00
<<<<<< TLV:
<<<<<<   type   = 0x17
<<<<<<   length = 36
<<<<<<   value  = 01:0A:98:94:20:04:00:91:91:15:16:71:16:3B:9F:96:80:1F:C6:80:31:E0:73:FE:21:1B:66:D0:02:3B:FE:14:10:01:C2:00
<<<<<< TLV:
<<<<<<   type   = 0x16
<<<<<<   length = 5
<<<<<<   value  = 01:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x15
<<<<<<   length = 2
<<<<<<   value  = 01:00
<<<<<< TLV:
<<<<<<   type   = 0x11
<<<<<<   length = 2
<<<<<<   value  = 01:00
<<<<<< TLV:
<<<<<<   type       = "Card Status" (0x10)
<<<<<<   length     = 45
<<<<<<   value      = 00:00:FF:FF:FF:FF:FF:FF:01:01:00:00:00:00:01:02:07:02:0B:00:00:10:A0:00:00:00:87:10:02:FF:49:94:20:89:03:10:00:00:00:03:03:0A:01:03:0A
<<<<<<   translated = [ index_gw_primary = '0' index_1x_primary = '65535' index_gw_secondary = '65535' index_1x_secondary = '65535' cards = '{ [0] = '[ card_state = 'present' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'unknown' applications = '{ [0] = '[ type = 'usim' state = 'ready' personalization_state = 'ready' personalization_feature = 'unknown' personalization_retries = '0' personalization_unblock_retries = '0' application_identifier_value = '{ [0] = '160 ' [1] = '0 ' [2] = '0 ' [3] = '0 ' [4] = '135 ' [5] = '16 ' [6] = '2 ' [7] = '255 ' [8] = '73 ' [9] = '148 ' [10] = '32 ' [11] = '137 ' [12] = '3 ' [13] = '16 ' [14] = '0 ' [15] = '0 '}' upin_replaces_pin1 = 'no' pin1_state = 'disabled' pin1_retries = '3' puk1_retries = '10' pin2_state = 'enabled-not-verified' pin2_retries = '3' puk2_retries = '10' ] '}' ] '}' ]

[/dev/cdc-wdm0] Successfully got card status
Provisioning applications:
        Primary GW:   slot '1', application '1'
        Primary 1X:   session doesn't exist
        Secondary GW: session doesn't exist
        Secondary 1X: session doesn't exist
Slot [1]:
        Card state: 'present'
        UPIN state: 'not-initialized'
                UPIN retries: '0'
                UPUK retries: '0'
        Application [1]:
                Application type:  'usim (2)'
                Application state: 'ready'
                Application ID:
                        A0:00:00:00:87:10:02:FF:49:94:20:89:03:10:00:00
                Personalization state: 'ready'
                UPIN replaces PIN1: 'no'
                PIN1 state: 'disabled'
                        PIN1 retries: '3'
                        PUK1 retries: '10'
                PIN2 state: 'enabled-not-verified'
                        PIN2 retries: '3'
                        PUK2 retries: '10'
[05 Sep 2023, 15:52:08] [Debug] [/dev/cdc-wdm0] Releasing 'uim' client with flags 'release-cid'...
[05 Sep 2023, 15:52:08] [Debug] [/dev/cdc-wdm0] Unregistered 'uim' client with ID '2'
[05 Sep 2023, 15:52:08] [Debug] [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:03:23:00:05:00:01:02:00:0B:02

[05 Sep 2023, 15:52:08] [Debug] [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 0B:02
<<<<<<   translated = [ service = 'uim' cid = '2' ]

[05 Sep 2023, 15:52:08] [Debug] [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:03:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:0B:02

[05 Sep 2023, 15:52:08] [Debug] [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 0B:02
<<<<<<   translated = [ service = 'uim' cid = '2' ]

[05 Sep 2023, 15:52:08] [Debug] Client released
[05 Sep 2023, 15:52:08] [Debug] Closed
trio at radio:~ $


More information about the libqmi-devel mailing list