SIM Card PIN Change Timeout (MM 1.18.2)
Amol Lad
Amol.Lad at 4rf.com
Sat Nov 6 06:54:34 UTC 2021
Hi Aleksander,
If we attempt to change SIM Card PIN when the SIM is in locked state then "Timeout is reached" error is reported BUT the PIN is actually changed. Below are some details.
# mmcli -m 0
-----------------------------
General | path: /org/freedesktop/ModemManager1/Modem/0
| device id: 02c606168e3d349049dd25edafba09756e8ca26d
-----------------------------
Hardware | manufacturer: Sierra Wireless, Incorporated
| model: Sierra Wireless EM7455 Qualcomm(r) Snapdragon(tm) X7 LTE-A
| firmware revision: SWI9X30C_02.24.05.06
| carrier config: default
| h/w revision: EM7455
| supported: gsm-umts, lte
| current: gsm-umts, lte
| equipment id: 359073061147272
-----------------------------
System | device: /sys/devices/platform/soc/soc:internal-regs/f10f0000.usb3/usb2/2-1
| drivers: qcserial, cdc_mbim
| plugin: sierra
| primary port: cdc-wdm0
| ports: cdc-wdm0 (mbim), ttyUSB0 (qcdm), ttyUSB1 (gps),
| ttyUSB2 (at), wwan0 (net)
-----------------------------
Status | state: locked
| power state: on
| signal quality: 0% (cached)
-----------------------------
Modes | supported: allowed: 3g; preferred: none
| allowed: 4g; preferred: none
| allowed: 3g, 4g; preferred: 4g
| allowed: 3g, 4g; preferred: 3g
| current: allowed: 4g; preferred: none
-----------------------------
Bands | supported: utran-1, utran-3, utran-4, utran-5, utran-8, utran-2,
| eutran-1, eutran-2, eutran-3, eutran-4, eutran-5, eutran-7, eutran-8,
| eutran-12, eutran-13, eutran-20, eutran-25, eutran-26, eutran-29,
| eutran-30, eutran-41
| current: eutran-1, eutran-2, eutran-3, eutran-4, eutran-5, eutran-7,
| eutran-8, eutran-12, eutran-13, eutran-20, eutran-25, eutran-26,
| eutran-29, eutran-30, eutran-41
-----------------------------
IP | supported: ipv4, ipv6, ipv4v6
-----------------------------
3GPP | enabled locks: sim, fixed-dialing
-----------------------------
SIM | primary sim path: /org/freedesktop/ModemManager1/SIM/0
# mmcli -i 0 --change-pin=1234 --pin=0000
error: couldn't change PIN code in the SIM: 'Timeout was reached'
<NOTE: The PIN actually got changed after above operation>
Below are the MM logs when "-change-pin" operation was in progress:
debug 2021-11-06T06:46:22+00:00 : <debug> [1636181182.916042] [modem0/sim0] changing PIN...
debug 2021-11-06T06:46:22+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 88
<<<<<< data = 03:00:00:00:58:00:00:00:15:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:01:00:00:00:28:00:00:00:02:00:00:00:03:00:00:00:18:00:00:00:08:00:00:00:20:00:00:00:08:00:00:00:30:00:30:00:30:00:30:00:31:00:32:00:33:00:34:00
debug 2021-11-06T06:46:22+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 88
<<<<<< type = command (0x00000003)
<<<<<< transaction = 21
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'pin' (0x00000004)
<<<<<< type = 'set' (0x00000001)
<<<<<< Fields:
<<<<<< PinType = 'pin1'
<<<<<< PinOperation = 'change'
<<<<<< Pin = '0000'
<<<<<< NewPin = '1234'
debug 2021-11-06T06:46:22+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 60
>>>>>> data = 03:00:00:80:3C:00:00:00:15:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:0C:00:00:00:03:00:00:00:01:00:00:00:03:00:00:00
debug 2021-11-06T06:46:22+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 60
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 21
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'pin' (0x00000004)
>>>>>> Fields:
>>>>>> PinType = 'pin2'
>>>>>> PinState = 'locked'
>>>>>> RemainingAttempts = '3'
debug 2021-11-06T06:46:22+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:16:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:22+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 22
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:23+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:16:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:23+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 22
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:23+00:00 : <debug> [1636181183.048410] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:23+00:00 : <debug> [1636181183.048457] [modem0] retrying (1) unlock required check
debug 2021-11-06T06:46:24+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:17:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:24+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 23
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:24+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:17:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:24+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 23
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:24+00:00 : <debug> [1636181184.808494] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:24+00:00 : <debug> [1636181184.808543] [modem0] retrying (2) unlock required check
debug 2021-11-06T06:46:26+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:18:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:26+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 24
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:26+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:18:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:26+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 24
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:26+00:00 : <debug> [1636181186.824419] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:26+00:00 : <debug> [1636181186.824467] [modem0] retrying (3) unlock required check
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 78
>>>>>> data = 07:00:00:80:4E:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:22:00:00:00:01:21:00:80:03:04:04:01:00:24:00:15:00:01:06:00:02:02:02:00:01:00:11:01:00:00:22:05:00:00:03:00:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 78
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
>>>>>> cid = 'msg' (0x00000001)
>>>>>> Fields:
>>>>>> QMUX = '01:21:00:80:03:04:04:01:00:24:00:15:00:01:06:00:02:02:02:00:01:00:11:01:00:00:22:05:00:00:03:00:00:00'
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
<<<<<< RAW:
<<<<<< length = 34
<<<<<< data = 01:21:00:80:03:04:04:01:00:24:00:15:00:01:06:00:02:02:02:00:01:00:11:01:00:00:22:05:00:00:03:00:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received generic indication (translated)...
<<<<<< QMUX:
<<<<<< length = 33
<<<<<< flags = 0x80
<<<<<< service = "nas"
<<<<<< client = 4
<<<<<< QMI:
<<<<<< flags = "indication"
<<<<<< transaction = 1
<<<<<< tlv_length = 21
<<<<<< message = "Serving System" (0x0024)
<<<<<< TLV:
<<<<<< type = "Serving System" (0x01)
<<<<<< length = 6
<<<<<< value = 02:02:02:00:01:00
<<<<<< translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = 'unknown' radio_interfaces = '{ [0] = 'none '}' ]
<<<<<< TLV:
<<<<<< type = "Data Service Capability" (0x11)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = {}
<<<<<< TLV:
<<<<<< type = "Detailed Service Status" (0x22)
<<<<<< length = 5
<<<<<< value = 00:03:00:00:00
<<<<<< translated = [ status = 'none' capability = 'cs-ps' hdr_status = 'none' hdr_hybrid
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 94
>>>>>> data = 07:00:00:80:5E:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:32:00:00:00:01:31:00:80:03:04:04:02:00:24:00:25:00:01:06:00:02:02:02:02:01:08:11:01:00:00:12:05:00:94:01:2D:00:00:22:05:00:01:02:00:00:00:29:05:00:94:01:2D:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 94
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
>>>>>> cid = 'msg' (0x00000001)
>>>>>> Fields:
>>>>>> QMUX = '01:31:00:80:03:04:04:02:00:24:00:25:00:01:06:00:02:02:02:02:01:08:11:01:00:00:12:05:00:94:01:2d:00:00:22:05:00:01:02:00:00:00:29:05:00:94:01:2d:00:00'
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
<<<<<< RAW:
<<<<<< length = 50
<<<<<< data = 01:31:00:80:03:04:04:02:00:24:00:25:00:01:06:00:02:02:02:02:01:08:11:01:00:00:12:05:00:94:01:2D:00:00:22:05:00:01:02:00:00:00:29:05:00:94:01:2D:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received generic indication (translated)...
<<<<<< QMUX:
<<<<<< length = 49
<<<<<< flags = 0x80
<<<<<< service = "nas"
<<<<<< client = 4
<<<<<< QMI:
<<<<<< flags = "indication"
<<<<<< transaction = 2
<<<<<< tlv_length = 37
<<<<<< message = "Serving System" (0x0024)
<<<<<< TLV:
<<<<<< type = "Serving System" (0x01)
<<<<<< length = 6
<<<<<< value = 02:02:02:02:01:08
<<<<<< translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'lte '}' ]
<<<<<< TLV:
<<<<<< type = "Data Service Capability" (0x11)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = {}
<<<<<< TLV:
<<<<<< type = "Current PLMN" (0x12)
<<<<<< length = 5
<<<<<< value = 94:01:2D:00:00
<<<<<< translated = [ mcc = '404' mnc = '45' description = '' ]
<<<<<< TLV:
<<<<<< type = "Detai
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:19:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 25
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 88
>>>>>> data = 07:00:00:80:58:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:2C:00:00:00:01:2B:00:80:03:04:04:03:00:24:00:1F:00:01:06:00:02:02:02:02:01:08:10:01:00:00:11:01:00:00:15:03:00:01:08:00:22:05:00:01:03:00:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 88
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
>>>>>> cid = 'msg' (0x00000001)
>>>>>> Fields:
>>>>>> QMUX = '01:2b:00:80:03:04:04:03:00:24:00:1f:00:01:06:00:02:02:02:02:01:08:10:01:00:00:11:01:00:00:15:03:00:01:08:00:22:05:00:01:03:00:00:00'
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
<<<<<< RAW:
<<<<<< length = 44
<<<<<< data = 01:2B:00:80:03:04:04:03:00:24:00:1F:00:01:06:00:02:02:02:02:01:08:10:01:00:00:11:01:00:00:15:03:00:01:08:00:22:05:00:01:03:00:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received generic indication (translated)...
<<<<<< QMUX:
<<<<<< length = 43
<<<<<< flags = 0x80
<<<<<< service = "nas"
<<<<<< client = 4
<<<<<< QMI:
<<<<<< flags = "indication"
<<<<<< transaction = 3
<<<<<< tlv_length = 31
<<<<<< message = "Serving System" (0x0024)
<<<<<< TLV:
<<<<<< type = "Serving System" (0x01)
<<<<<< length = 6
<<<<<< value = 02:02:02:02:01:08
<<<<<< translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'lte '}' ]
<<<<<< TLV:
<<<<<< type = "Roaming Indicator" (0x10)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = on
<<<<<< TLV:
<<<<<< type = "Data Service Capability" (0x11)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = {}
<<<<<< TLV:
<<<<<< type = "Roaming Indicator List" (0x15)
<<<<<< length =
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:19:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 25
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:28+00:00 : <debug> [1636181188.904341] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:28+00:00 : <debug> [1636181188.904390] [modem0] retrying (4) unlock required check
debug 2021-11-06T06:46:30+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:30+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 26
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:30+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:1A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:30+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 26
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:30+00:00 : <debug> [1636181190.824407] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:30+00:00 : <debug> [1636181190.824456] [modem0] retrying (5) unlock required check
debug 2021-11-06T06:46:32+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:32+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 27
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:32+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:1B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:32+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 27
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:32+00:00 : <debug> [1636181192.808521] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:32+00:00 : <debug> [1636181192.808573] [modem0] retrying (6) unlock required check
debug 2021-11-06T06:46:34+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:34+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 28
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:34+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:1C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:34+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 28
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:34+00:00 : <debug> [1636181194.824480] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:34+00:00 : <debug> [1636181194.824530] [modem0] retrying (7) unlock required check
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.546433] [modem0/ttyUSB2/at] device open count is 2 (open)
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.546587] [modem0/ttyUSB2/at] --> 'AT!GSTATUS?<CR>'
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.565226] [modem0/ttyUSB2/at] <-- '<CR><LF>!GSTATUS: <CR><LF>Current Time: 278\9\9Temperature: 35<CR><LF>Reset Counter: 2\9\9Mode: ONLINE <CR><LF>System mode: LTE \9PS state: Not attached <CR><LF>LTE band: B3 \9\9LTE bw: 15 MHz <CR><LF>LTE Rx chan: 1299\9\9LTE Tx chan: 4294967295<CR><LF>LTE CA state: NOT ASSIGNED<CR><LF>EMM state: Deregistered \9Limited Service<CR><LF>RRC state: RRC Idle <CR><LF>IMS reg state: No Srv \9\9<CR><LF><CR><LF>PCC RxM RSSI: -59\9\9RSRP (dBm): -94<CR><LF>PCC RxD RSSI: -95\9\9RSRP (dBm): -140<CR><LF>Tx Power: 0\9\9TAC: 2039 (8249)<CR><LF>RSRQ (dB): -16.0\9\9Cell ID: 00029B0C (170764)<CR><LF>SINR (dB): 0<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.565644] [modem0/ttyUSB2/at] device open count is 1 (close)
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.590575] [modem0/ttyUSB2/at] device open count is 2 (open)
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.590728] [modem0/ttyUSB2/at] --> 'AT!LTEINFO?<CR>'
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.606970] [modem0/ttyUSB2/at] <-- '<CR><LF>!LTEINFO: <CR><LF>Serving: EARFCN MCC MNC TAC CID Bd D U SNR PCI RSRQ RSRP RSSI RXLV<CR><LF> 1299 404 45 8249 00029B0C 3 4 4 0 167 -16.0 -94.3 -59.6 0<CR><LF><CR><LF>IntraFreq: PCI RSRQ RSRP RSSI RXLV<CR><LF> 167 -16.0 -94.3 -59.6 0<CR><LF><CR><LF>InterFreq: EARFCN ThresholdLow ThresholdHi Priority PCI RSRQ RSRP RSSI RXLV<CR><LF> 3646 0 0 255 166 -9.4 -77.9 -51.7 0<CR><LF> 3646 0 0 255 0 0.0 0.0 0.0 0<CR><LF> 3646 0 0 255 0 0.0 0.0 0.0 0<CR><LF><CR><LF>GSM: ThreshL ThreshH Prio NCC ARFCN 1900 valid BSIC RSSI RXLV<CR><LF><CR><LF>WCDMA: UARFCN ThreshL ThreshH Prio PSC RSCP ECN0 RXLV<CR><LF><CR><LF>CDMA 1x: Chan BC Offset Phase S
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.607493] [modem0/ttyUSB2/at] device open count is 1 (close)
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.716256] [modem0/ttyUSB2/at] device open count is 2 (open)
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.716408] [modem0/ttyUSB2/at] --> 'AT!PCTEMP?<CR>'
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.731335] [modem0/ttyUSB2/at] <-- '<CR><LF>Temp state: Normal<CR><LF>Temperature: 36.00 C<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.731740] [modem0/ttyUSB2/at] device open count is 1 (close)
debug 2021-11-06T06:46:36+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:36+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 29
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:36+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:1D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:36+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 29
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.840602] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.840654] [modem0] retrying (8) unlock required check
debug 2021-11-06T06:46:38+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:38+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 30
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:38+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:1E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:38+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 30
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:38+00:00 : <debug> [1636181198.824464] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:38+00:00 : <debug> [1636181198.824515] [modem0] retrying (9) unlock required check
debug 2021-11-06T06:46:40+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:40+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 31
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:40+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:1F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:40+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 31
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:40+00:00 : <debug> [1636181200.808516] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:40+00:00 : <debug> [1636181200.808568] [modem0] retrying (10) unlock required check
debug 2021-11-06T06:46:42+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:20:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:42+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 32
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:42+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:20:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:42+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 32
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:42+00:00 : <debug> [1636181202.824620] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:42+00:00 : <debug> [1636181202.824677] [modem0] retrying (11) unlock required check
debug 2021-11-06T06:46:44+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:21:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:44+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 33
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:44+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:21:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:44+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 33
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:44+00:00 : <debug> [1636181204.808473] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:44+00:00 : <debug> [1636181204.808523] [modem0] retrying (12) unlock required check
debug 2021-11-06T06:46:46+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:22:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:46+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 34
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:46+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:22:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:46+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 34
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:46+00:00 : <debug> [1636181206.824519] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:46+00:00 : <debug> [1636181206.824570] [modem0] retrying (13) unlock required check
debug 2021-11-06T06:46:48+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:23:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:48+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 35
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:48+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:23:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:48+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 35
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:48+00:00 : <debug> [1636181208.808455] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:48+00:00 : <debug> [1636181208.808504] [modem0] retrying (14) unlock required check
debug 2021-11-06T06:46:50+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:24:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:50+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 36
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:50+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:24:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:50+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 36
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:50+00:00 : <debug> [1636181210.824469] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:50+00:00 : <debug> [1636181210.824520] [modem0] retrying (15) unlock required check
debug 2021-11-06T06:46:52+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:25:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:52+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 37
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:52+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:25:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:52+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 37
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:52+00:00 : <debug> [1636181212.808590] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:52+00:00 : <debug> [1636181212.808642] [modem0] retrying (16) unlock required check
debug 2021-11-06T06:46:54+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:26:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:54+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 38
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:54+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:26:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:54+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 38
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:54+00:00 : <debug> [1636181214.824636] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:54+00:00 : <debug> [1636181214.824686] [modem0] retrying (17) unlock required check
debug 2021-11-06T06:46:56+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:27:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:56+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 39
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:56+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:27:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:56+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 39
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:56+00:00 : <debug> [1636181216.808517] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:56+00:00 : <debug> [1636181216.808566] [modem0] retrying (18) unlock required check
debug 2021-11-06T06:46:58+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:28:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:58+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 40
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:58+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:28:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:58+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 40
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:58+00:00 : <debug> [1636181218.824538] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:58+00:00 : <debug> [1636181218.824587] [modem0] retrying (19) unlock required check
debug 2021-11-06T06:47:00+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:29:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:00+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 41
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:00+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:29:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:00+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 41
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:00+00:00 : <debug> [1636181220.808477] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:00+00:00 : <debug> [1636181220.808527] [modem0] retrying (20) unlock required check
debug 2021-11-06T06:47:02+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:2A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:02+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 42
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:02+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:2A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:02+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 42
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:02+00:00 : <debug> [1636181222.824601] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:02+00:00 : <debug> [1636181222.824651] [modem0] retrying (21) unlock required check
debug 2021-11-06T06:47:04+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:2B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:04+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 43
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:04+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:2B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:04+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 43
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:04+00:00 : <debug> [1636181224.808516] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:04+00:00 : <debug> [1636181224.808568] [modem0] retrying (22) unlock required check
debug 2021-11-06T06:47:06+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:2C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:06+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 44
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:06+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:2C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:06+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 44
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:06+00:00 : <debug> [1636181226.824409] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:06+00:00 : <debug> [1636181226.824457] [modem0] retrying (23) unlock required check
debug 2021-11-06T06:47:08+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:2D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:08+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 45
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:08+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:2D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:08+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 45
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:08+00:00 : <debug> [1636181228.808519] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:08+00:00 : <debug> [1636181228.808571] [modem0] retrying (24) unlock required check
debug 2021-11-06T06:47:10+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:2E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:10+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 46
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:10+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:2E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:10+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 46
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:10+00:00 : <debug> [1636181230.824459] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:10+00:00 : <debug> [1636181230.824506] [modem0] retrying (25) unlock required check
debug 2021-11-06T06:47:12+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:2F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:12+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 47
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:12+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:2F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:12+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 47
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:12+00:00 : <debug> [1636181232.808531] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:12+00:00 : <debug> [1636181232.808582] [modem0] retrying (26) unlock required check
debug 2021-11-06T06:47:14+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:30:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:14+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 48
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:14+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:30:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:14+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 48
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:14+00:00 : <debug> [1636181234.824665] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:14+00:00 : <debug> [1636181234.824724] [modem0] retrying (27) unlock required check
debug 2021-11-06T06:47:16+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:31:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:16+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 49
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:16+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:31:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:16+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 49
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:16+00:00 : <debug> [1636181236.808477] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:16+00:00 : <debug> [1636181236.808525] [modem0] retrying (28) unlock required check
debug 2021-11-06T06:47:18+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:32:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:18+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 50
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:18+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:32:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:18+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 50
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:18+00:00 : <debug> [1636181238.824433] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:18+00:00 : <debug> [1636181238.824482] [modem0] retrying (29) unlock required check
debug 2021-11-06T06:47:20+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:33:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:20+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 51
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:20+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:33:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:20+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 51
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:20+00:00 : <debug> [1636181240.808426] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:20+00:00 : <debug> [1636181240.808474] [modem0] retrying (30) unlock required check
debug 2021-11-06T06:47:22+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:34:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:22+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 52
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:22+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:34:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:22+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 52
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:22+00:00 : <debug> [1636181242.824564] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.833634] [modem0/ttyUSB2/at] device open count is 2 (open)
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.833777] [modem0/ttyUSB2/at] --> 'AT!GSTATUS?<CR>'
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.853016] [modem0/ttyUSB2/at] <-- '<CR><LF>!GSTATUS: <CR><LF>Current Time: 339\9\9Temperature: 35<CR><LF>Reset Counter: 2\9\9Mode: ONLINE <CR><LF>System mode: LTE \9PS state: Not attached <CR><LF>LTE band: B3 \9\9LTE bw: 15 MHz <CR><LF>LTE Rx chan: 1299\9\9LTE Tx chan: 4294967295<CR><LF>LTE CA state: NOT ASSIGNED<CR><LF>EMM state: Deregistered \9Limited Service<CR><LF>RRC state: RRC Idle <CR><LF>IMS reg state: No Srv \9\9<CR><LF><CR><LF>PCC RxM RSSI: -59\9\9RSRP (dBm): -94<CR><LF>PCC RxD RSSI: -94\9\9RSRP (dBm): -138<CR><LF>Tx Power: 0\9\9TAC: 2039 (8249)<CR><LF>RSRQ (dB): -15.8\9\9Cell ID: 00029B0C (170764)<CR><LF>SINR (dB): -9.4<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.853449] [modem0/ttyUSB2/at] device open count is 1 (close)
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.878631] [modem0/ttyUSB2/at] device open count is 2 (open)
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.878772] [modem0/ttyUSB2/at] --> 'AT!LTEINFO?<CR>'
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.901410] [modem0/ttyUSB2/at] <-- '<CR><LF>!LTEINFO: <CR><LF>Serving: EARFCN MCC MNC TAC CID Bd D U SNR PCI RSRQ RSRP RSSI RXLV<CR><LF> 1299 404 45 8249 00029B0C 3 4 4 -9 167 -15.8 -94.1 -59.1 29<CR><LF><CR><LF>IntraFreq: PCI RSRQ RSRP RSSI RXLV<CR><LF> 167 -15.8 -94.1 -59.1 29<CR><LF> 165 -11.0 -93.5 -73.9 29<CR><LF><CR><LF>InterFreq: EARFCN ThresholdLow ThresholdHi Priority PCI RSRQ RSRP RSSI RXLV<CR><LF> 3646 0 22 3 166 -14.5 -78.8 -55.3 45<CR><LF><CR><LF>GSM: ThreshL ThreshH Prio NCC ARFCN 1900 valid BSIC RSSI RXLV<CR><LF><CR><LF>WCDMA: UARFCN ThreshL ThreshH Prio PSC RSCP ECN0 RXLV<CR><LF><CR><LF>CDMA 1x: Chan BC Offset Phase Str<CR><LF><CR><LF>CDMA HRPD: Chan BC Offset Phase Str<CR><LF><CR><LF><CR><LF>OK<CR><LF>
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.901832] [modem0/ttyUSB2/at] device open count is 1 (close)
debug 2021-11-06T06:47:37+00:00 : <debug> [1636181257.007465] [modem0/ttyUSB2/at] device open count is 2 (open)
debug 2021-11-06T06:47:37+00:00 : <debug> [1636181257.007635] [modem0/ttyUSB2/at] --> 'AT!PCTEMP?<CR>'
debug 2021-11-06T06:47:37+00:00 : <debug> [1636181257.023941] [modem0/ttyUSB2/at] <-- '<CR><LF>Temp state: Normal<CR><LF>Temperature: 36.00 C<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
debug 2021-11-06T06:47:37+00:00 : <debug> [1636181257.024333] [modem0/ttyUSB2/at] device open count is 1 (close)
-------------- next part --------------
Hi Aleksander,
If we attempt to change SIM Card PIN when the SIM is in locked state then "Timeout is reached" error is reported BUT the PIN is actually changed. Below are some details.
# mmcli -m 0
-----------------------------
General | path: /org/freedesktop/ModemManager1/Modem/0
| device id: 02c606168e3d349049dd25edafba09756e8ca26d
-----------------------------
Hardware | manufacturer: Sierra Wireless, Incorporated
| model: Sierra Wireless EM7455 Qualcomm(r) Snapdragon(tm) X7 LTE-A
| firmware revision: SWI9X30C_02.24.05.06
| carrier config: default
| h/w revision: EM7455
| supported: gsm-umts, lte
| current: gsm-umts, lte
| equipment id: 359073061147272
-----------------------------
System | device: /sys/devices/platform/soc/soc:internal-regs/f10f0000.usb3/usb2/2-1
| drivers: qcserial, cdc_mbim
| plugin: sierra
| primary port: cdc-wdm0
| ports: cdc-wdm0 (mbim), ttyUSB0 (qcdm), ttyUSB1 (gps),
| ttyUSB2 (at), wwan0 (net)
-----------------------------
Status | state: locked
| power state: on
| signal quality: 0% (cached)
-----------------------------
Modes | supported: allowed: 3g; preferred: none
| allowed: 4g; preferred: none
| allowed: 3g, 4g; preferred: 4g
| allowed: 3g, 4g; preferred: 3g
| current: allowed: 4g; preferred: none
-----------------------------
Bands | supported: utran-1, utran-3, utran-4, utran-5, utran-8, utran-2,
| eutran-1, eutran-2, eutran-3, eutran-4, eutran-5, eutran-7, eutran-8,
| eutran-12, eutran-13, eutran-20, eutran-25, eutran-26, eutran-29,
| eutran-30, eutran-41
| current: eutran-1, eutran-2, eutran-3, eutran-4, eutran-5, eutran-7,
| eutran-8, eutran-12, eutran-13, eutran-20, eutran-25, eutran-26,
| eutran-29, eutran-30, eutran-41
-----------------------------
IP | supported: ipv4, ipv6, ipv4v6
-----------------------------
3GPP | enabled locks: sim, fixed-dialing
-----------------------------
SIM | primary sim path: /org/freedesktop/ModemManager1/SIM/0
# mmcli -i 0 --change-pin=1234 --pin=0000
error: couldn't change PIN code in the SIM: 'Timeout was reached'
<NOTE: The PIN actually got changed after above operation>
Below are the MM logs when "-change-pin" operation was in progress:
debug 2021-11-06T06:46:22+00:00 : <debug> [1636181182.916042] [modem0/sim0] changing PIN...
debug 2021-11-06T06:46:22+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 88
<<<<<< data = 03:00:00:00:58:00:00:00:15:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:01:00:00:00:28:00:00:00:02:00:00:00:03:00:00:00:18:00:00:00:08:00:00:00:20:00:00:00:08:00:00:00:30:00:30:00:30:00:30:00:31:00:32:00:33:00:34:00
debug 2021-11-06T06:46:22+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 88
<<<<<< type = command (0x00000003)
<<<<<< transaction = 21
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'pin' (0x00000004)
<<<<<< type = 'set' (0x00000001)
<<<<<< Fields:
<<<<<< PinType = 'pin1'
<<<<<< PinOperation = 'change'
<<<<<< Pin = '0000'
<<<<<< NewPin = '1234'
debug 2021-11-06T06:46:22+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 60
>>>>>> data = 03:00:00:80:3C:00:00:00:15:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:0C:00:00:00:03:00:00:00:01:00:00:00:03:00:00:00
debug 2021-11-06T06:46:22+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 60
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 21
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'pin' (0x00000004)
>>>>>> Fields:
>>>>>> PinType = 'pin2'
>>>>>> PinState = 'locked'
>>>>>> RemainingAttempts = '3'
debug 2021-11-06T06:46:22+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:16:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:22+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 22
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:23+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:16:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:23+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 22
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:23+00:00 : <debug> [1636181183.048410] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:23+00:00 : <debug> [1636181183.048457] [modem0] retrying (1) unlock required check
debug 2021-11-06T06:46:24+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:17:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:24+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 23
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:24+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:17:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:24+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 23
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:24+00:00 : <debug> [1636181184.808494] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:24+00:00 : <debug> [1636181184.808543] [modem0] retrying (2) unlock required check
debug 2021-11-06T06:46:26+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:18:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:26+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 24
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:26+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:18:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:26+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 24
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:26+00:00 : <debug> [1636181186.824419] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:26+00:00 : <debug> [1636181186.824467] [modem0] retrying (3) unlock required check
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 78
>>>>>> data = 07:00:00:80:4E:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:22:00:00:00:01:21:00:80:03:04:04:01:00:24:00:15:00:01:06:00:02:02:02:00:01:00:11:01:00:00:22:05:00:00:03:00:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 78
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
>>>>>> cid = 'msg' (0x00000001)
>>>>>> Fields:
>>>>>> QMUX = '01:21:00:80:03:04:04:01:00:24:00:15:00:01:06:00:02:02:02:00:01:00:11:01:00:00:22:05:00:00:03:00:00:00'
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
<<<<<< RAW:
<<<<<< length = 34
<<<<<< data = 01:21:00:80:03:04:04:01:00:24:00:15:00:01:06:00:02:02:02:00:01:00:11:01:00:00:22:05:00:00:03:00:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received generic indication (translated)...
<<<<<< QMUX:
<<<<<< length = 33
<<<<<< flags = 0x80
<<<<<< service = "nas"
<<<<<< client = 4
<<<<<< QMI:
<<<<<< flags = "indication"
<<<<<< transaction = 1
<<<<<< tlv_length = 21
<<<<<< message = "Serving System" (0x0024)
<<<<<< TLV:
<<<<<< type = "Serving System" (0x01)
<<<<<< length = 6
<<<<<< value = 02:02:02:00:01:00
<<<<<< translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = 'unknown' radio_interfaces = '{ [0] = 'none '}' ]
<<<<<< TLV:
<<<<<< type = "Data Service Capability" (0x11)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = {}
<<<<<< TLV:
<<<<<< type = "Detailed Service Status" (0x22)
<<<<<< length = 5
<<<<<< value = 00:03:00:00:00
<<<<<< translated = [ status = 'none' capability = 'cs-ps' hdr_status = 'none' hdr_hybrid
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 94
>>>>>> data = 07:00:00:80:5E:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:32:00:00:00:01:31:00:80:03:04:04:02:00:24:00:25:00:01:06:00:02:02:02:02:01:08:11:01:00:00:12:05:00:94:01:2D:00:00:22:05:00:01:02:00:00:00:29:05:00:94:01:2D:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 94
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
>>>>>> cid = 'msg' (0x00000001)
>>>>>> Fields:
>>>>>> QMUX = '01:31:00:80:03:04:04:02:00:24:00:25:00:01:06:00:02:02:02:02:01:08:11:01:00:00:12:05:00:94:01:2d:00:00:22:05:00:01:02:00:00:00:29:05:00:94:01:2d:00:00'
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
<<<<<< RAW:
<<<<<< length = 50
<<<<<< data = 01:31:00:80:03:04:04:02:00:24:00:25:00:01:06:00:02:02:02:02:01:08:11:01:00:00:12:05:00:94:01:2D:00:00:22:05:00:01:02:00:00:00:29:05:00:94:01:2D:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received generic indication (translated)...
<<<<<< QMUX:
<<<<<< length = 49
<<<<<< flags = 0x80
<<<<<< service = "nas"
<<<<<< client = 4
<<<<<< QMI:
<<<<<< flags = "indication"
<<<<<< transaction = 2
<<<<<< tlv_length = 37
<<<<<< message = "Serving System" (0x0024)
<<<<<< TLV:
<<<<<< type = "Serving System" (0x01)
<<<<<< length = 6
<<<<<< value = 02:02:02:02:01:08
<<<<<< translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'lte '}' ]
<<<<<< TLV:
<<<<<< type = "Data Service Capability" (0x11)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = {}
<<<<<< TLV:
<<<<<< type = "Current PLMN" (0x12)
<<<<<< length = 5
<<<<<< value = 94:01:2D:00:00
<<<<<< translated = [ mcc = '404' mnc = '45' description = '' ]
<<<<<< TLV:
<<<<<< type = "Detai
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:19:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 25
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 88
>>>>>> data = 07:00:00:80:58:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:2C:00:00:00:01:2B:00:80:03:04:04:03:00:24:00:1F:00:01:06:00:02:02:02:02:01:08:10:01:00:00:11:01:00:00:15:03:00:01:08:00:22:05:00:01:03:00:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 88
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
>>>>>> cid = 'msg' (0x00000001)
>>>>>> Fields:
>>>>>> QMUX = '01:2b:00:80:03:04:04:03:00:24:00:1f:00:01:06:00:02:02:02:02:01:08:10:01:00:00:11:01:00:00:15:03:00:01:08:00:22:05:00:01:03:00:00:00'
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
<<<<<< RAW:
<<<<<< length = 44
<<<<<< data = 01:2B:00:80:03:04:04:03:00:24:00:1F:00:01:06:00:02:02:02:02:01:08:10:01:00:00:11:01:00:00:15:03:00:01:08:00:22:05:00:01:03:00:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received generic indication (translated)...
<<<<<< QMUX:
<<<<<< length = 43
<<<<<< flags = 0x80
<<<<<< service = "nas"
<<<<<< client = 4
<<<<<< QMI:
<<<<<< flags = "indication"
<<<<<< transaction = 3
<<<<<< tlv_length = 31
<<<<<< message = "Serving System" (0x0024)
<<<<<< TLV:
<<<<<< type = "Serving System" (0x01)
<<<<<< length = 6
<<<<<< value = 02:02:02:02:01:08
<<<<<< translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'lte '}' ]
<<<<<< TLV:
<<<<<< type = "Roaming Indicator" (0x10)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = on
<<<<<< TLV:
<<<<<< type = "Data Service Capability" (0x11)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = {}
<<<<<< TLV:
<<<<<< type = "Roaming Indicator List" (0x15)
<<<<<< length =
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:19:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:28+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 25
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:28+00:00 : <debug> [1636181188.904341] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:28+00:00 : <debug> [1636181188.904390] [modem0] retrying (4) unlock required check
debug 2021-11-06T06:46:30+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:30+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 26
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:30+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:1A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:30+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 26
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:30+00:00 : <debug> [1636181190.824407] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:30+00:00 : <debug> [1636181190.824456] [modem0] retrying (5) unlock required check
debug 2021-11-06T06:46:32+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:32+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 27
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:32+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:1B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:32+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 27
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:32+00:00 : <debug> [1636181192.808521] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:32+00:00 : <debug> [1636181192.808573] [modem0] retrying (6) unlock required check
debug 2021-11-06T06:46:34+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:34+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 28
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:34+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:1C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:34+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 28
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:34+00:00 : <debug> [1636181194.824480] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:34+00:00 : <debug> [1636181194.824530] [modem0] retrying (7) unlock required check
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.546433] [modem0/ttyUSB2/at] device open count is 2 (open)
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.546587] [modem0/ttyUSB2/at] --> 'AT!GSTATUS?<CR>'
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.565226] [modem0/ttyUSB2/at] <-- '<CR><LF>!GSTATUS: <CR><LF>Current Time: 278\9\9Temperature: 35<CR><LF>Reset Counter: 2\9\9Mode: ONLINE <CR><LF>System mode: LTE \9PS state: Not attached <CR><LF>LTE band: B3 \9\9LTE bw: 15 MHz <CR><LF>LTE Rx chan: 1299\9\9LTE Tx chan: 4294967295<CR><LF>LTE CA state: NOT ASSIGNED<CR><LF>EMM state: Deregistered \9Limited Service<CR><LF>RRC state: RRC Idle <CR><LF>IMS reg state: No Srv \9\9<CR><LF><CR><LF>PCC RxM RSSI: -59\9\9RSRP (dBm): -94<CR><LF>PCC RxD RSSI: -95\9\9RSRP (dBm): -140<CR><LF>Tx Power: 0\9\9TAC: 2039 (8249)<CR><LF>RSRQ (dB): -16.0\9\9Cell ID: 00029B0C (170764)<CR><LF>SINR (dB): 0<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.565644] [modem0/ttyUSB2/at] device open count is 1 (close)
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.590575] [modem0/ttyUSB2/at] device open count is 2 (open)
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.590728] [modem0/ttyUSB2/at] --> 'AT!LTEINFO?<CR>'
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.606970] [modem0/ttyUSB2/at] <-- '<CR><LF>!LTEINFO: <CR><LF>Serving: EARFCN MCC MNC TAC CID Bd D U SNR PCI RSRQ RSRP RSSI RXLV<CR><LF> 1299 404 45 8249 00029B0C 3 4 4 0 167 -16.0 -94.3 -59.6 0<CR><LF><CR><LF>IntraFreq: PCI RSRQ RSRP RSSI RXLV<CR><LF> 167 -16.0 -94.3 -59.6 0<CR><LF><CR><LF>InterFreq: EARFCN ThresholdLow ThresholdHi Priority PCI RSRQ RSRP RSSI RXLV<CR><LF> 3646 0 0 255 166 -9.4 -77.9 -51.7 0<CR><LF> 3646 0 0 255 0 0.0 0.0 0.0 0<CR><LF> 3646 0 0 255 0 0.0 0.0 0.0 0<CR><LF><CR><LF>GSM: ThreshL ThreshH Prio NCC ARFCN 1900 valid BSIC RSSI RXLV<CR><LF><CR><LF>WCDMA: UARFCN ThreshL ThreshH Prio PSC RSCP ECN0 RXLV<CR><LF><CR><LF>CDMA 1x: Chan BC Offset Phase S
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.607493] [modem0/ttyUSB2/at] device open count is 1 (close)
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.716256] [modem0/ttyUSB2/at] device open count is 2 (open)
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.716408] [modem0/ttyUSB2/at] --> 'AT!PCTEMP?<CR>'
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.731335] [modem0/ttyUSB2/at] <-- '<CR><LF>Temp state: Normal<CR><LF>Temperature: 36.00 C<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.731740] [modem0/ttyUSB2/at] device open count is 1 (close)
debug 2021-11-06T06:46:36+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:36+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 29
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:36+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:1D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:36+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 29
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.840602] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:36+00:00 : <debug> [1636181196.840654] [modem0] retrying (8) unlock required check
debug 2021-11-06T06:46:38+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:38+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 30
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:38+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:1E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:38+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 30
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:38+00:00 : <debug> [1636181198.824464] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:38+00:00 : <debug> [1636181198.824515] [modem0] retrying (9) unlock required check
debug 2021-11-06T06:46:40+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:40+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 31
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:40+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:1F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:40+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 31
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:40+00:00 : <debug> [1636181200.808516] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:40+00:00 : <debug> [1636181200.808568] [modem0] retrying (10) unlock required check
debug 2021-11-06T06:46:42+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:20:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:42+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 32
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:42+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:20:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:42+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 32
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:42+00:00 : <debug> [1636181202.824620] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:42+00:00 : <debug> [1636181202.824677] [modem0] retrying (11) unlock required check
debug 2021-11-06T06:46:44+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:21:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:44+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 33
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:44+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:21:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:44+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 33
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:44+00:00 : <debug> [1636181204.808473] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:44+00:00 : <debug> [1636181204.808523] [modem0] retrying (12) unlock required check
debug 2021-11-06T06:46:46+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:22:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:46+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 34
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:46+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:22:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:46+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 34
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:46+00:00 : <debug> [1636181206.824519] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:46+00:00 : <debug> [1636181206.824570] [modem0] retrying (13) unlock required check
debug 2021-11-06T06:46:48+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:23:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:48+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 35
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:48+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:23:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:48+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 35
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:48+00:00 : <debug> [1636181208.808455] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:48+00:00 : <debug> [1636181208.808504] [modem0] retrying (14) unlock required check
debug 2021-11-06T06:46:50+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:24:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:50+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 36
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:50+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:24:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:50+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 36
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:50+00:00 : <debug> [1636181210.824469] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:50+00:00 : <debug> [1636181210.824520] [modem0] retrying (15) unlock required check
debug 2021-11-06T06:46:52+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:25:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:52+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 37
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:52+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:25:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:52+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 37
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:52+00:00 : <debug> [1636181212.808590] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:52+00:00 : <debug> [1636181212.808642] [modem0] retrying (16) unlock required check
debug 2021-11-06T06:46:54+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:26:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:54+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 38
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:54+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:26:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:54+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 38
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:54+00:00 : <debug> [1636181214.824636] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:54+00:00 : <debug> [1636181214.824686] [modem0] retrying (17) unlock required check
debug 2021-11-06T06:46:56+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:27:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:56+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 39
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:56+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:27:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:56+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 39
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:56+00:00 : <debug> [1636181216.808517] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:56+00:00 : <debug> [1636181216.808566] [modem0] retrying (18) unlock required check
debug 2021-11-06T06:46:58+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:28:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:46:58+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 40
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:46:58+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:28:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:46:58+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 40
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:46:58+00:00 : <debug> [1636181218.824538] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:46:58+00:00 : <debug> [1636181218.824587] [modem0] retrying (19) unlock required check
debug 2021-11-06T06:47:00+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:29:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:00+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 41
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:00+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:29:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:00+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 41
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:00+00:00 : <debug> [1636181220.808477] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:00+00:00 : <debug> [1636181220.808527] [modem0] retrying (20) unlock required check
debug 2021-11-06T06:47:02+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:2A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:02+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 42
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:02+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:2A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:02+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 42
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:02+00:00 : <debug> [1636181222.824601] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:02+00:00 : <debug> [1636181222.824651] [modem0] retrying (21) unlock required check
debug 2021-11-06T06:47:04+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:2B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:04+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 43
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:04+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:2B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:04+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 43
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:04+00:00 : <debug> [1636181224.808516] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:04+00:00 : <debug> [1636181224.808568] [modem0] retrying (22) unlock required check
debug 2021-11-06T06:47:06+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:2C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:06+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 44
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:06+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:2C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:06+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 44
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:06+00:00 : <debug> [1636181226.824409] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:06+00:00 : <debug> [1636181226.824457] [modem0] retrying (23) unlock required check
debug 2021-11-06T06:47:08+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:2D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:08+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 45
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:08+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:2D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:08+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 45
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:08+00:00 : <debug> [1636181228.808519] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:08+00:00 : <debug> [1636181228.808571] [modem0] retrying (24) unlock required check
debug 2021-11-06T06:47:10+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:2E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:10+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 46
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:10+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:2E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:10+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 46
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:10+00:00 : <debug> [1636181230.824459] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:10+00:00 : <debug> [1636181230.824506] [modem0] retrying (25) unlock required check
debug 2021-11-06T06:47:12+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:2F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:12+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 47
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:12+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:2F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:12+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 47
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:12+00:00 : <debug> [1636181232.808531] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:12+00:00 : <debug> [1636181232.808582] [modem0] retrying (26) unlock required check
debug 2021-11-06T06:47:14+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:30:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:14+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 48
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:14+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:30:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:14+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 48
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:14+00:00 : <debug> [1636181234.824665] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:14+00:00 : <debug> [1636181234.824724] [modem0] retrying (27) unlock required check
debug 2021-11-06T06:47:16+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:31:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:16+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 49
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:16+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:31:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:16+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 49
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:16+00:00 : <debug> [1636181236.808477] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:16+00:00 : <debug> [1636181236.808525] [modem0] retrying (28) unlock required check
debug 2021-11-06T06:47:18+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:32:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:18+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 50
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:18+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:32:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:18+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 50
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:18+00:00 : <debug> [1636181238.824433] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:18+00:00 : <debug> [1636181238.824482] [modem0] retrying (29) unlock required check
debug 2021-11-06T06:47:20+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:33:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:20+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 51
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:20+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:33:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:20+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 51
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:20+00:00 : <debug> [1636181240.808426] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:20+00:00 : <debug> [1636181240.808474] [modem0] retrying (30) unlock required check
debug 2021-11-06T06:47:22+00:00 : [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:34:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
debug 2021-11-06T06:47:22+00:00 : [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 52
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
debug 2021-11-06T06:47:22+00:00 : [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:34:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:02:00:00:00:00:00:00:00
debug 2021-11-06T06:47:22+00:00 : [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 52
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
debug 2021-11-06T06:47:22+00:00 : <debug> [1636181242.824564] [modem0] couldn't check if unlock required: Failure
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.833634] [modem0/ttyUSB2/at] device open count is 2 (open)
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.833777] [modem0/ttyUSB2/at] --> 'AT!GSTATUS?<CR>'
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.853016] [modem0/ttyUSB2/at] <-- '<CR><LF>!GSTATUS: <CR><LF>Current Time: 339\9\9Temperature: 35<CR><LF>Reset Counter: 2\9\9Mode: ONLINE <CR><LF>System mode: LTE \9PS state: Not attached <CR><LF>LTE band: B3 \9\9LTE bw: 15 MHz <CR><LF>LTE Rx chan: 1299\9\9LTE Tx chan: 4294967295<CR><LF>LTE CA state: NOT ASSIGNED<CR><LF>EMM state: Deregistered \9Limited Service<CR><LF>RRC state: RRC Idle <CR><LF>IMS reg state: No Srv \9\9<CR><LF><CR><LF>PCC RxM RSSI: -59\9\9RSRP (dBm): -94<CR><LF>PCC RxD RSSI: -94\9\9RSRP (dBm): -138<CR><LF>Tx Power: 0\9\9TAC: 2039 (8249)<CR><LF>RSRQ (dB): -15.8\9\9Cell ID: 00029B0C (170764)<CR><LF>SINR (dB): -9.4<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.853449] [modem0/ttyUSB2/at] device open count is 1 (close)
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.878631] [modem0/ttyUSB2/at] device open count is 2 (open)
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.878772] [modem0/ttyUSB2/at] --> 'AT!LTEINFO?<CR>'
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.901410] [modem0/ttyUSB2/at] <-- '<CR><LF>!LTEINFO: <CR><LF>Serving: EARFCN MCC MNC TAC CID Bd D U SNR PCI RSRQ RSRP RSSI RXLV<CR><LF> 1299 404 45 8249 00029B0C 3 4 4 -9 167 -15.8 -94.1 -59.1 29<CR><LF><CR><LF>IntraFreq: PCI RSRQ RSRP RSSI RXLV<CR><LF> 167 -15.8 -94.1 -59.1 29<CR><LF> 165 -11.0 -93.5 -73.9 29<CR><LF><CR><LF>InterFreq: EARFCN ThresholdLow ThresholdHi Priority PCI RSRQ RSRP RSSI RXLV<CR><LF> 3646 0 22 3 166 -14.5 -78.8 -55.3 45<CR><LF><CR><LF>GSM: ThreshL ThreshH Prio NCC ARFCN 1900 valid BSIC RSSI RXLV<CR><LF><CR><LF>WCDMA: UARFCN ThreshL ThreshH Prio PSC RSCP ECN0 RXLV<CR><LF><CR><LF>CDMA 1x: Chan BC Offset Phase Str<CR><LF><CR><LF>CDMA HRPD: Chan BC Offset Phase Str<CR><LF><CR><LF><CR><LF>OK<CR><LF>
debug 2021-11-06T06:47:36+00:00 : <debug> [1636181256.901832] [modem0/ttyUSB2/at] device open count is 1 (close)
debug 2021-11-06T06:47:37+00:00 : <debug> [1636181257.007465] [modem0/ttyUSB2/at] device open count is 2 (open)
debug 2021-11-06T06:47:37+00:00 : <debug> [1636181257.007635] [modem0/ttyUSB2/at] --> 'AT!PCTEMP?<CR>'
debug 2021-11-06T06:47:37+00:00 : <debug> [1636181257.023941] [modem0/ttyUSB2/at] <-- '<CR><LF>Temp state: Normal<CR><LF>Temperature: 36.00 C<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
debug 2021-11-06T06:47:37+00:00 : <debug> [1636181257.024333] [modem0/ttyUSB2/at] device open count is 1 (close)
More information about the ModemManager-devel
mailing list