modem has to stay in registered state for a second before it can connect
Arkadiusz Drabczyk
arkadiusz at drabczyk.org
Tue Oct 25 18:37:08 UTC 2022
I use ModemManager 1.18.6 on OpenWRT 22.03.0-rc4. I run it:
root at OpenWrt:~# ModemManager
[6778]: <info> ModemManager (version 1.18.6) starting in system bus...
Report kernel events:
root at OpenWrt:~# mmcli --report-kernel-event=action=add,name=ttyACM2,subsystem=tty
successfully reported kernel event
root at OpenWrt:~# mmcli --report-kernel-event=action=add,name=wwan0,subsystem=net
successfully reported kernel event
Modem is found:
root at OpenWrt:~# mmcli -m any
--------------------------------
General | path: /org/freedesktop/ModemManager1/Modem/0
| device id: <REDACTED>
--------------------------------
Hardware | manufacturer: Cinterion
| model: PLAS9-W
| firmware revision: REVISION 01.004
| supported: gsm-umts, lte
| current: gsm-umts, lte
| equipment id: <REDACTED>
--------------------------------
System | device: /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1
| drivers: cdc_acm, cdc_ether
| plugin: cinterion
| primary port: ttyACM2
| ports: ttyACM2 (at), wwan0 (net)
--------------------------------
Status | unlock retries: ph-fsim-pin (0), sim-puk2 (10), sim-puk (10), sim-pin2 (3),
| ph-net-puk (0), sim-pin (3), ph-net-pin (0), ph-fsim-puk (0)
| state: disabled
| power state: on
| signal quality: 0% (cached)
--------------------------------
Modes | supported: allowed: 2g; preferred: none
| allowed: 3g; preferred: none
| allowed: 4g; preferred: none
| allowed: 2g, 3g, 4g; preferred: none
| current: allowed: any; preferred: none
--------------------------------
Bands | supported: egsm, dcs, utran-1, utran-3, utran-6, utran-5, utran-8,
| eutran-1, eutran-3, eutran-5, eutran-7, eutran-8, eutran-18,
| eutran-19, eutran-20, eutran-26, eutran-28, eutran-38, eutran-39,
| eutran-40, eutran-41
| current: egsm, dcs, utran-1, utran-3, utran-6, utran-5, utran-8,
| eutran-1, eutran-3, eutran-5, eutran-7, eutran-8, eutran-18,
| eutran-19, eutran-20, eutran-26, eutran-28, eutran-38, eutran-39,
| eutran-40, eutran-41
--------------------------------
IP | supported: ipv4, ipv6, ipv4v6
--------------------------------
3GPP | imei: <REDACTED>
--------------------------------
3GPP EPS | ue mode of operation: csps-2
| initial bearer apn: internet
--------------------------------
SIM | primary sim path: /org/freedesktop/ModemManager1/SIM/0
I'm using Telenor card in international roaming and I get the
following error with --simple-connect:
root at OpenWrt:~# mmcli -m any --timeout 120 --simple-connect=apn=telenor.smart,ip-type=ipv4
error: couldn't connect the modem: 'GDBus.Error:org.freedesktop.ModemManager1.Error.MobileEquipment.GprsUnknown: Unspecified GPRS error'
And a few seconds later mmcli reports 'state: searching'. But if I
kill ModemManager, start it and report kernel events again and enable
the modem and wait a few seconds before connecting like that:
root at OpenWrt:~# mmcli -m any -e && sleep 1 && mmcli -m any --timeout 120 --simple-connect=apn=telenor.smart,ip-type=ipv4
successfully enabled the modem
successfully connected the modem
it works (or at least worked every time so far). To my untrained eye
it looks like modem has to be in registered state for at least 1
second before it can connect to non-default bearer. Have you seen
anything like that before? What could it be - ModemManager issue,
buggy modem fw (I saw this
https://gitlab.freedesktop.org/mobile-broadband/ModemManager/-/issues/639),
network issue?
I attach debug log for unsuccessful connection attempt:
root at OpenWrt:~# ModemManager --debug
[7273]: <info> [1666700572.304968] ModemManager (version 1.18.6) starting in system bus...
[7273]: <debug> [1666700572.305278] [charsets] detecting platform iconv() support...
[7273]: <debug> [1666700572.305525] [charsets] UTF-8: iconv conversion to/from charset is supported
[7273]: <debug> [1666700572.305778] [charsets] UCS-2BE: iconv conversion to charset not supported
[7273]: <debug> [1666700572.306048] [charsets] ASCII: iconv conversion to/from charset is supported
[7273]: <debug> [1666700572.306263] [charsets] ISO8859-1: iconv conversion to charset not supported
[7273]: <debug> [1666700572.306462] [charsets] CP437: iconv conversion to charset not supported
[7273]: <debug> [1666700572.306648] [charsets] CP850: iconv conversion to charset not supported
[7273]: <debug> [1666700572.306822] [charsets] UTF-16BE: iconv conversion to/from charset is supported
[7273]: <debug> [1666700572.318939] bus acquired, creating manager...
[7273]: <debug> [1666700572.320476] [auth-provider] singleton created
[7273]: <debug> [1666700572.321640] [filter] created
[7273]: <debug> [1666700572.321871] [filter] explicit whitelist: yes
[7273]: <debug> [1666700572.322046] [filter] explicit blacklist: yes
[7273]: <debug> [1666700572.322219] [filter] plugin whitelist: yes
[7273]: <debug> [1666700572.322382] [filter] qrtr devices allowed: yes
[7273]: <debug> [1666700572.322547] [filter] virtual devices forbidden: yes
[7273]: <debug> [1666700572.322709] [filter] net devices allowed: yes
[7273]: <debug> [1666700572.322869] [filter] usbmisc devices allowed: yes
[7273]: <debug> [1666700572.323028] [filter] rpmsg devices allowed: yes
[7273]: <debug> [1666700572.323184] [filter] wwan devices allowed: yes
[7273]: <debug> [1666700572.323390] [filter] tty devices:
[7273]: <debug> [1666700572.323597] [filter] platform driver check: yes
[7273]: <debug> [1666700572.323822] [filter] driver check: yes
[7273]: <debug> [1666700572.324113] [filter] cdc-acm interface check: yes
[7273]: <debug> [1666700572.324345] [filter] with net check: yes
[7273]: <debug> [1666700572.324575] [filter] default: forbidden
[7273]: <debug> [1666700572.325327] [plugin-manager] looking for plugins in '/usr/lib/ModemManager'
[7273]: <debug> [1666700572.327681] [plugin-manager] loaded shared 'Xmm' utils from '/usr/lib/ModemManager/libmm-shared-xmm.so'
[7273]: <debug> [1666700572.329388] [plugin-manager] loaded shared 'Telit' utils from '/usr/lib/ModemManager/libmm-shared-telit.so'
[7273]: <debug> [1666700572.331094] [plugin-manager] loaded shared 'Sierra' utils from '/usr/lib/ModemManager/libmm-shared-sierra.so'
[7273]: <debug> [1666700572.332393] [plugin-manager] loaded shared 'Option' utils from '/usr/lib/ModemManager/libmm-shared-option.so'
[7273]: <debug> [1666700572.333723] [plugin-manager] loaded shared 'Novatel' utils from '/usr/lib/ModemManager/libmm-shared-novatel.so'
[7273]: <debug> [1666700572.335551] [plugin-manager] loaded shared 'Icera' utils from '/usr/lib/ModemManager/libmm-shared-icera.so'
[7273]: <debug> [1666700572.336928] [plugin-manager] loaded shared 'Foxconn' utils from '/usr/lib/ModemManager/libmm-shared-foxconn.so'
[7273]: <debug> [1666700572.339954] [plugin-manager] loaded plugin 'zte' from '/usr/lib/ModemManager/libmm-plugin-zte.so'
[7273]: <debug> [1666700572.340314] [filter] registered plugin whitelist vendor id: 19d2
[7273]: <debug> [1666700572.342003] [plugin-manager] loaded plugin 'x22x' from '/usr/lib/ModemManager/libmm-plugin-x22x.so'
[7273]: <debug> [1666700572.342332] [filter] registered plugin whitelist tag: ID_MM_X22X_TAGGED
[7273]: <debug> [1666700572.342765] [filter] registered plugin whitelist vendor id: 1bbb
[7273]: <debug> [1666700572.342997] [filter] registered plugin whitelist vendor id: 0b3c
[7273]: <debug> [1666700572.344509] [plugin-manager] loaded plugin 'wavecom' from '/usr/lib/ModemManager/libmm-plugin-wavecom.so'
[7273]: <debug> [1666700572.344775] [filter] registered plugin whitelist vendor id: 114f
[7273]: <debug> [1666700572.345988] [plugin-manager] loaded plugin 'via' from '/usr/lib/ModemManager/libmm-plugin-via.so'
[7273]: <debug> [1666700572.347677] [plugin-manager] loaded plugin 'u-blox' from '/usr/lib/ModemManager/libmm-plugin-ublox.so'
[7273]: <debug> [1666700572.347947] [filter] registered plugin whitelist vendor id: 1546
[7273]: <debug> [1666700572.349087] [plugin-manager] loaded plugin 'tp-link' from '/usr/lib/ModemManager/libmm-plugin-tplink.so'
[7273]: <debug> [1666700572.349345] [filter] registered plugin whitelist vendor id: 2357
[7273]: <debug> [1666700572.350582] [plugin-manager] loaded plugin 'thuraya' from '/usr/lib/ModemManager/libmm-plugin-thuraya.so'
[7273]: <debug> [1666700572.350844] [filter] registered plugin whitelist vendor id: 1a26
[7273]: <debug> [1666700572.351990] [plugin-manager] loaded plugin 'telit' from '/usr/lib/ModemManager/libmm-plugin-telit.so'
[7273]: <debug> [1666700572.352252] [filter] registered plugin whitelist vendor id: 1bc7
[7273]: <debug> [1666700572.353671] [plugin-manager] loaded plugin 'simtech' from '/usr/lib/ModemManager/libmm-plugin-simtech.so'
[7273]: <debug> [1666700572.353969] [filter] registered plugin whitelist vendor id: 1e0e
[7273]: <debug> [1666700572.355127] [plugin-manager] loaded plugin 'sierra' from '/usr/lib/ModemManager/libmm-plugin-sierra.so'
[7273]: <debug> [1666700572.355387] [filter] registered plugin whitelist vendor id: 1199
[7273]: <debug> [1666700572.356649] [plugin-manager] loaded plugin 'sierra-legacy' from '/usr/lib/ModemManager/libmm-plugin-sierra-legacy.so'
[7273]: <debug> [1666700572.357814] [plugin-manager] loaded plugin 'samsung' from '/usr/lib/ModemManager/libmm-plugin-samsung.so'
[7273]: <debug> [1666700572.358076] [filter] registered plugin whitelist product id: 04e8:6872
[7273]: <debug> [1666700572.358260] [filter] registered plugin whitelist product id: 04e8:6906
[7273]: <debug> [1666700572.359627] [plugin-manager] loaded plugin 'quectel' from '/usr/lib/ModemManager/libmm-plugin-quectel.so'
[7273]: <debug> [1666700572.359896] [filter] registered plugin whitelist vendor id: 2c7c
[7273]: <debug> [1666700572.360078] [filter] registered plugin whitelist vendor id: 1eac
[7273]: <debug> [1666700572.361215] [plugin-manager] loaded plugin 'qcom-soc' from '/usr/lib/ModemManager/libmm-plugin-qcom-soc.so'
[7273]: <debug> [1666700572.361480] [filter] registered plugin whitelist tag: ID_MM_QCOM_SOC
[7273]: <debug> [1666700572.362675] [plugin-manager] loaded plugin 'pantech' from '/usr/lib/ModemManager/libmm-plugin-pantech.so'
[7273]: <debug> [1666700572.362939] [filter] registered plugin whitelist vendor id: 106c
[7273]: <debug> [1666700572.364109] [plugin-manager] loaded plugin 'option' from '/usr/lib/ModemManager/libmm-plugin-option.so'
[7273]: <debug> [1666700572.364366] [filter] registered plugin whitelist vendor id: 0af0
[7273]: <debug> [1666700572.364549] [filter] registered plugin whitelist vendor id: 1931
[7273]: <debug> [1666700572.366084] [plugin-manager] loaded plugin 'option-hso' from '/usr/lib/ModemManager/libmm-plugin-option-hso.so'
[7273]: <debug> [1666700572.367292] [plugin-manager] loaded plugin 'novatel' from '/usr/lib/ModemManager/libmm-plugin-novatel.so'
[7273]: <debug> [1666700572.367551] [filter] registered plugin whitelist vendor id: 1410
[7273]: <debug> [1666700572.368893] [plugin-manager] loaded plugin 'novatel-lte' from '/usr/lib/ModemManager/libmm-plugin-novatel-lte.so'
[7273]: <debug> [1666700572.369156] [filter] registered plugin whitelist product id: 1410:9010
[7273]: <debug> [1666700572.370475] [plugin-manager] loaded plugin 'nokia' from '/usr/lib/ModemManager/libmm-plugin-nokia.so'
[7273]: <debug> [1666700572.370739] [filter] registered plugin whitelist vendor id: 0421
[7273]: <debug> [1666700572.371863] [plugin-manager] loaded plugin 'nokia-icera' from '/usr/lib/ModemManager/libmm-plugin-nokia-icera.so'
[7273]: <debug> [1666700572.373245] [plugin-manager] loaded plugin 'mtk' from '/usr/lib/ModemManager/libmm-plugin-mtk.so'
[7273]: <debug> [1666700572.373507] [filter] registered plugin whitelist tag: ID_MM_MTK_TAGGED
[7273]: <debug> [1666700572.374704] [plugin-manager] loaded plugin 'motorola' from '/usr/lib/ModemManager/libmm-plugin-motorola.so'
[7273]: <debug> [1666700572.374968] [filter] registered plugin whitelist product id: 22b8:3802
[7273]: <debug> [1666700572.375156] [filter] registered plugin whitelist product id: 22b8:4902
[7273]: <debug> [1666700572.376429] [plugin-manager] loaded plugin 'longcheer' from '/usr/lib/ModemManager/libmm-plugin-longcheer.so'
[7273]: <debug> [1666700572.376691] [filter] registered plugin whitelist tag: ID_MM_LONGCHEER_TAGGED
[7273]: <debug> [1666700572.376883] [filter] registered plugin whitelist vendor id: 1c9e
[7273]: <debug> [1666700572.378168] [plugin-manager] loaded plugin 'linktop' from '/usr/lib/ModemManager/libmm-plugin-linktop.so'
[7273]: <debug> [1666700572.378430] [filter] registered plugin whitelist vendor id: 230d
[7273]: <debug> [1666700572.379752] [plugin-manager] loaded plugin 'iridium' from '/usr/lib/ModemManager/libmm-plugin-iridium.so'
[7273]: <debug> [1666700572.380011] [filter] registered plugin whitelist vendor id: 1edd
[7273]: <debug> [1666700572.381944] [plugin-manager] loaded plugin 'huawei' from '/usr/lib/ModemManager/libmm-plugin-huawei.so'
[7273]: <debug> [1666700572.382219] [filter] registered plugin whitelist vendor id: 12d1
[7273]: <debug> [1666700572.383338] [plugin-manager] loaded plugin 'haier' from '/usr/lib/ModemManager/libmm-plugin-haier.so'
[7273]: <debug> [1666700572.383599] [filter] registered plugin whitelist vendor id: 201e
[7273]: <debug> [1666700572.384815] [plugin-manager] loaded plugin 'gosuncn' from '/usr/lib/ModemManager/libmm-plugin-gosuncn.so'
[7273]: <debug> [1666700572.385081] [filter] registered plugin whitelist vendor id: 305a
[7273]: <debug> [1666700572.386211] [plugin-manager] loaded plugin 'generic' from '/usr/lib/ModemManager/libmm-plugin-generic.so'
[7273]: <debug> [1666700572.387458] [plugin-manager] loaded plugin 'foxconn' from '/usr/lib/ModemManager/libmm-plugin-foxconn.so'
[7273]: <debug> [1666700572.387717] [filter] registered plugin whitelist vendor id: 0489
[7273]: <debug> [1666700572.387904] [filter] registered plugin whitelist vendor id: 105b
[7273]: <debug> [1666700572.389077] [plugin-manager] loaded plugin 'fibocom' from '/usr/lib/ModemManager/libmm-plugin-fibocom.so'
[7273]: <debug> [1666700572.389339] [filter] registered plugin whitelist vendor id: 2cb7
[7273]: <debug> [1666700572.391015] [plugin-manager] loaded plugin 'ericsson-mbm' from '/usr/lib/ModemManager/libmm-plugin-ericsson-mbm.so'
[7273]: <debug> [1666700572.391291] [filter] registered plugin whitelist tag: ID_MM_ERICSSON_MBM
[7273]: <debug> [1666700572.392445] [plugin-manager] loaded plugin 'd-link' from '/usr/lib/ModemManager/libmm-plugin-dlink.so'
[7273]: <debug> [1666700572.392710] [filter] registered plugin whitelist vendor id: 2001
[7273]: <debug> [1666700572.394206] [plugin-manager] loaded plugin 'dell' from '/usr/lib/ModemManager/libmm-plugin-dell.so'
[7273]: <debug> [1666700572.394505] [filter] registered plugin whitelist vendor id: 413c
[7273]: <debug> [1666700572.396331] [plugin-manager] loaded plugin 'cinterion' from '/usr/lib/ModemManager/libmm-plugin-cinterion.so'
[7273]: <debug> [1666700572.396604] [filter] registered plugin whitelist vendor id: 1e2d
[7273]: <debug> [1666700572.396790] [filter] registered plugin whitelist vendor id: 0681
[7273]: <debug> [1666700572.397942] [plugin-manager] loaded plugin 'broadmobi' from '/usr/lib/ModemManager/libmm-plugin-broadmobi.so'
[7273]: <debug> [1666700572.398207] [filter] registered plugin whitelist vendor id: 2020
[7273]: <debug> [1666700572.399526] [plugin-manager] loaded plugin 'anydata' from '/usr/lib/ModemManager/libmm-plugin-anydata.so'
[7273]: <debug> [1666700572.399800] [filter] registered plugin whitelist vendor id: 16d5
[7273]: <debug> [1666700572.401334] [plugin-manager] loaded plugin 'altair-lte' from '/usr/lib/ModemManager/libmm-plugin-altair-lte.so'
[7273]: <debug> [1666700572.401604] [filter] registered plugin whitelist product id: 216f:0047
[7273]: <debug> [1666700572.401807] [plugin-manager] successfully loaded 39 plugins registering 6 subsystems: tty, net, usbmisc, wwan, rpmsg, qrtr
[7273]: <debug> [1666700572.446767] service name 'org.freedesktop.ModemManager1' was acquired
[7273]: <debug> [1666700572.446940] [base-manager] unsupported automatic device scan...
[7273]: <debug> [1666700574.630031] [base-manager] kernel event reported:
[7273]: <debug> [1666700574.630264] [base-manager] action: add
[7273]: <debug> [1666700574.630433] [base-manager] subsystem: tty
[7273]: <debug> [1666700574.630580] [base-manager] name: ttyACM2
[7273]: <debug> [1666700574.630734] [base-manager] uid: n/a
[7273]: <debug> [1666700574.686416] [ttyACM2] preloading contents and properties...
[7273]: <debug> [1666700574.686991] [ttyACM2] sysfs path: /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.2/tty/ttyACM2
[7273]: <debug> [1666700574.691560] [ttyACM2] port contents loaded:
[7273]: <debug> [1666700574.691738] [ttyACM2] bus: usb
[7273]: <debug> [1666700574.691967] [ttyACM2] interface: /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.2
[7273]: <debug> [1666700574.692103] [ttyACM2] interface class: 02
[7273]: <debug> [1666700574.692325] [ttyACM2] interface subclass: 02
[7273]: <debug> [1666700574.692456] [ttyACM2] interface protocol: 01
[7273]: <debug> [1666700574.692581] [ttyACM2] interface number: 02
[7273]: <debug> [1666700574.692700] [ttyACM2] interface description: CDC Abstract Control Model (ACM)
[7273]: <debug> [1666700574.692812] [ttyACM2] device: /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1
[7273]: <debug> [1666700574.692931] [ttyACM2] subsystems: tty, usb
[7273]: <debug> [1666700574.693041] [ttyACM2] drivers: cdc_acm, usb
[7273]: <debug> [1666700574.693144] [ttyACM2] vendor: 1e2d
[7273]: <debug> [1666700574.693246] [ttyACM2] product: 0061
[7273]: <debug> [1666700574.693366] [ttyACM2] revision: 0232
[7273]: <debug> [1666700574.693481] [ttyACM2] manufacturer: Cinterion
[7273]: <debug> [1666700574.693598] [ttyACM2] product: LTE Modem
[7273]: <debug> [1666700574.693714] [ttyACM2] ID_USB_INTERFACE_NUM: 0x02
[7273]: <debug> [1666700574.693920] [ttyACM2] ID_MODEL: LTE Modem
[7273]: <debug> [1666700574.694069] [ttyACM2] ID_VENDOR: Cinterion
[7273]: <debug> [1666700574.694206] [ttyACM2] ID_VENDOR_ID: 0x1e2d
[7273]: <debug> [1666700574.694342] [ttyACM2] ID_MODEL_ID: 0x0061
[7273]: <debug> [1666700574.694477] [ttyACM2] ID_REVISION: 0x0232
[7273]: <debug> [1666700574.694673] [ttyACM2] property added: .MM_USBIFNUM=02
[7273]: <debug> [1666700574.694889] [ttyACM2] property added: ID_MM_PORT_TYPE_AT_SECONDARY=1
[7273]: <debug> [1666700574.695844] [ttyACM2] property added: ID_MM_CANDIDATE=1
[7273]: <debug> [1666700574.696125] [base-manager] adding port ttyACM2 at sysfs path: /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.2/tty/ttyACM2
[7273]: <debug> [1666700574.696344] [filter] (tty/ttyACM2) port allowed: device is whitelisted by plugin (vid)
[7273]: <debug> [1666700574.696495] [base-manager] port ttyACM2 is first in device /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1
[7273]: <debug> [1666700574.697197] [plugin-manager] task 0: new support task for device: /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1
[7273]: <debug> [1666700574.697801] [plugin-manager] task 0: port grabbed: ttyACM2
[7273]: <debug> [1666700574.698008] [plugin-manager] task 0,ttyACM2: new support task for port
[7273]: <debug> [1666700574.698146] [plugin-manager] task 0,ttyACM2: deferred until min wait time elapsed
[7273]: <debug> [1666700574.752912] [base-manager] kernel event reported:
[7273]: <debug> [1666700574.753140] [base-manager] action: add
[7273]: <debug> [1666700574.753316] [base-manager] subsystem: net
[7273]: <debug> [1666700574.753473] [base-manager] name: wwan0
[7273]: <debug> [1666700574.753647] [base-manager] uid: n/a
[7273]: <debug> [1666700574.754032] [wwan0] preloading contents and properties...
[7273]: <debug> [1666700574.754735] [wwan0] sysfs path: /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.10/net/wwan0
[7273]: <debug> [1666700574.761098] [wwan0] port contents loaded:
[7273]: <debug> [1666700574.761322] [wwan0] bus: usb
[7273]: <debug> [1666700574.761499] [wwan0] interface: /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.10
[7273]: <debug> [1666700574.761682] [wwan0] interface class: 02
[7273]: <debug> [1666700574.761841] [wwan0] interface subclass: 06
[7273]: <debug> [1666700574.761999] [wwan0] interface protocol: 00
[7273]: <debug> [1666700574.762159] [wwan0] interface number: 0a
[7273]: <debug> [1666700574.762307] [wwan0] interface description: CDC Ethernet Control Model (ECM) for RmNet
[7273]: <debug> [1666700574.762490] [wwan0] device: /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1
[7273]: <debug> [1666700574.762670] [wwan0] subsystems: net, usb
[7273]: <debug> [1666700574.762844] [wwan0] drivers: cdc_ether, usb
[7273]: <debug> [1666700574.763008] [wwan0] vendor: 1e2d
[7273]: <debug> [1666700574.763170] [wwan0] product: 0061
[7273]: <debug> [1666700574.763328] [wwan0] revision: 0232
[7273]: <debug> [1666700574.763489] [wwan0] manufacturer: Cinterion
[7273]: <debug> [1666700574.763646] [wwan0] product: LTE Modem
[7273]: <debug> [1666700574.763802] [wwan0] ID_USB_INTERFACE_NUM: 0x0a
[7273]: <debug> [1666700574.764083] [wwan0] ID_MODEL: LTE Modem
[7273]: <debug> [1666700574.764267] [wwan0] ID_VENDOR: Cinterion
[7273]: <debug> [1666700574.764449] [wwan0] ID_VENDOR_ID: 0x1e2d
[7273]: <debug> [1666700574.764623] [wwan0] ID_MODEL_ID: 0x0061
[7273]: <debug> [1666700574.764797] [wwan0] ID_REVISION: 0x0232
[7273]: <debug> [1666700574.765211] [wwan0] property added: .MM_USBIFNUM=0a
[7273]: <debug> [1666700574.766279] [wwan0] property added: ID_MM_CANDIDATE=1
[7273]: <debug> [1666700574.766614] [base-manager] adding port wwan0 at sysfs path: /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.10/net/wwan0
[7273]: <debug> [1666700574.766904] [filter] (net/wwan0) port allowed: device is whitelisted by plugin (vid)
[7273]: <debug> [1666700574.767110] [base-manager] additional port wwan0 in device /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1
[7273]: <debug> [1666700574.767478] [plugin-manager] task 0: port grabbed: wwan0
[7273]: <debug> [1666700574.767755] [plugin-manager] task 0,wwan0: new support task for port
[7273]: <debug> [1666700574.767945] [plugin-manager] task 0,wwan0: deferred until min wait time elapsed
[7273]: <debug> [1666700576.200205] [plugin-manager] task 0: min wait time elapsed
[7273]: <debug> [1666700576.202244] [plugin/zte] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.204676] [plugin/x22x] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.206819] [plugin/wavecom] port wwan0 filtered by subsystem
[7273]: <debug> [1666700576.208785] [plugin/via] port wwan0 filtered by subsystem
[7273]: <debug> [1666700576.210685] [plugin/u-blox] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.212711] [plugin/tp-link] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.214922] [plugin/thuraya] port wwan0 filtered by subsystem
[7273]: <debug> [1666700576.216836] [plugin/telit] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.217918] [plugin/simtech] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.218080] [plugin/sierra] port wwan0 filtered by drivers
[7273]: <debug> [1666700576.218242] [plugin/sierra-legacy] port wwan0 filtered by drivers
[7273]: <debug> [1666700576.218401] [plugin/samsung] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.218553] [plugin/quectel] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.218709] [plugin/qcom-soc] port wwan0 filtered by udev tags
[7273]: <debug> [1666700576.218869] [plugin/pantech] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.219260] [plugin/option] port wwan0 filtered by subsystem
[7273]: <debug> [1666700576.219431] [plugin/option-hso] port wwan0 filtered by drivers
[7273]: <debug> [1666700576.219714] [plugin/novatel] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.219886] [plugin/novatel-lte] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.220077] [plugin/nokia] port wwan0 filtered by subsystem
[7273]: <debug> [1666700576.220463] [plugin/nokia-icera] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.220633] [plugin/mtk] port wwan0 filtered by subsystem
[7273]: <debug> [1666700576.220793] [plugin/motorola] port wwan0 filtered by subsystem
[7273]: <debug> [1666700576.220944] [plugin/longcheer] port wwan0 filtered by subsystem
[7273]: <debug> [1666700576.221087] [plugin/linktop] port wwan0 filtered by subsystem
[7273]: <debug> [1666700576.221238] [plugin/iridium] port wwan0 filtered by subsystem
[7273]: <debug> [1666700576.221388] [plugin/huawei] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.221547] [plugin/haier] port wwan0 filtered by subsystem
[7273]: <debug> [1666700576.221709] [plugin/gosuncn] port wwan0 filtered by drivers
[7273]: <debug> [1666700576.221873] [plugin/foxconn] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.222026] [plugin/fibocom] port wwan0 filtered by drivers
[7273]: <debug> [1666700576.222188] [plugin/ericsson-mbm] port wwan0 filtered by udev tags
[7273]: <debug> [1666700576.222345] [plugin/d-link] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.222503] [plugin/dell] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.222668] [plugin/broadmobi] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.222826] [plugin/anydata] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.222983] [plugin/altair-lte] port wwan0 filtered by vendor/product IDs
[7273]: <debug> [1666700576.223146] [plugin-manager] task 0,wwan0: found '2' plugins to try
[7273]: <debug> [1666700576.223284] [plugin-manager] task 0,wwan0: will try with plugin 'cinterion'
[7273]: <debug> [1666700576.223411] [plugin-manager] task 0,wwan0: will try with plugin 'generic'
[7273]: <debug> [1666700576.223572] [plugin-manager] task 0,wwan0: started
[7273]: <debug> [1666700576.223703] [plugin-manager] task 0,wwan0: checking with plugin 'cinterion'
[7273]: <debug> [1666700576.223919] [plugin/cinterion] probing of port wwan0 deferred until result suggested
[7273]: <debug> [1666700576.224165] [plugin/zte] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.224321] [plugin/x22x] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.224462] [plugin/wavecom] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.224613] [plugin/tp-link] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.224750] [plugin/thuraya] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.224897] [plugin/simtech] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.225028] [plugin/sierra] port ttyACM2 filtered by drivers
[7273]: <debug> [1666700576.225162] [plugin/sierra-legacy] port ttyACM2 filtered by drivers
[7273]: <debug> [1666700576.225290] [plugin/samsung] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.225430] [plugin/qcom-soc] port ttyACM2 filtered by subsystem
[7273]: <debug> [1666700576.225566] [plugin/pantech] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.225706] [plugin/option] port ttyACM2 filtered by drivers
[7273]: <debug> [1666700576.225836] [plugin/option-hso] port ttyACM2 filtered by drivers
[7273]: <debug> [1666700576.225967] [plugin/novatel] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.226097] [plugin/novatel-lte] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.226243] [plugin/nokia-icera] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.226383] [plugin/mtk] port ttyACM2 filtered by udev tags
[7273]: <debug> [1666700576.226519] [plugin/motorola] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.226654] [plugin/longcheer] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.226789] [plugin/linktop] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.226933] [plugin/huawei] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.227070] [plugin/haier] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.227208] [plugin/gosuncn] port ttyACM2 filtered by drivers
[7273]: <debug> [1666700576.227340] [plugin/foxconn] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.227468] [plugin/fibocom] port ttyACM2 filtered by drivers
[7273]: <debug> [1666700576.227604] [plugin/ericsson-mbm] port ttyACM2 filtered by udev tags
[7273]: <debug> [1666700576.227738] [plugin/d-link] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.227872] [plugin/dell] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.228004] [plugin/broadmobi] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.228140] [plugin/anydata] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.228274] [plugin/altair-lte] port ttyACM2 filtered by vendor/product IDs
[7273]: <debug> [1666700576.228412] [plugin-manager] task 0,ttyACM2: found '8' plugins to try
[7273]: <debug> [1666700576.228552] [plugin-manager] task 0,ttyACM2: will try with plugin 'cinterion'
[7273]: <debug> [1666700576.228680] [plugin-manager] task 0,ttyACM2: will try with plugin 'via'
[7273]: <debug> [1666700576.228925] [plugin-manager] task 0,ttyACM2: will try with plugin 'u-blox'
[7273]: <debug> [1666700576.229060] [plugin-manager] task 0,ttyACM2: will try with plugin 'telit'
[7273]: <debug> [1666700576.229299] [plugin-manager] task 0,ttyACM2: will try with plugin 'quectel'
[7273]: <debug> [1666700576.229426] [plugin-manager] task 0,ttyACM2: will try with plugin 'nokia'
[7273]: <debug> [1666700576.229548] [plugin-manager] task 0,ttyACM2: will try with plugin 'iridium'
[7273]: <debug> [1666700576.229679] [plugin-manager] task 0,ttyACM2: will try with plugin 'generic'
[7273]: <debug> [1666700576.229858] [plugin-manager] task 0,ttyACM2: started
[7273]: <debug> [1666700576.229993] [plugin-manager] task 0,ttyACM2: checking with plugin 'cinterion'
[7273]: <debug> [1666700576.230182] [plugin/cinterion] probes required for port ttyACM2: 'at'
[7273]: <debug> [1666700576.230352] [ttyACM2/probe] no QCDM/QMI/MBIM probing in possible AT port
[7273]: <debug> [1666700576.230504] [ttyACM2/probe] port is not QCDM-capable
[7273]: <debug> [1666700576.230622] [ttyACM2/probe] port is not QMI-capable
[7273]: <debug> [1666700576.230735] [ttyACM2/probe] port is not MBIM-capable
[7273]: <debug> [1666700576.230857] [ttyACM2/probe] launching port probing: 'at'
[7273]: <debug> [1666700576.231126] [plugin-manager] task 0,wwan0: deferring support check until result suggested
[7273]: <debug> [1666700576.232821] [ttyACM2/at] opening serial port...
[7273]: <debug> [1666700576.233777] [ttyACM2/at] setting up baudrate: 57600
[7273]: <debug> [1666700576.234013] [ttyACM2/at] no flow control explicitly requested for device
[7273]: <debug> [1666700576.234299] [ttyACM2/at] device open count is 1 (open)
[7273]: <debug> [1666700576.234821] [ttyACM2/at] --> 'AT^SQPORT?<CR>'
[7273]: <debug> [1666700577.198353] [plugin-manager] task 0: min probing time elapsed
[7273]: <debug> [1666700577.201116] [plugin-manager] task 0: still 2 running probes (1 active): wwan0, ttyACM2
[7273]: <debug> [1666700577.256819] [ttyACM2/at] <-- '<CR><LF>^SQPORT: Application<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700577.260129] [ttyACM2/probe] port is AT-capable
[7273]: <debug> [1666700577.262711] [plugin-manager] task 0,ttyACM2: found best plugin for port (cinterion)
[7273]: <debug> [1666700577.265187] [plugin-manager] task 0,ttyACM2: finished in '2.567171' seconds
[7273]: <debug> [1666700577.265485] [plugin-manager] task 0,ttyACM2: found best plugin: cinterion
[7273]: <debug> [1666700577.265727] [plugin-manager] task 0,wwan0: deferred task completed, got suggested plugin (cinterion)
[7273]: <debug> [1666700577.266016] [plugin-manager] task 0: still 1 running probes (1 active): wwan0
[7273]: <debug> [1666700577.266403] [ttyACM2/at] device open count is 0 (close)
[7273]: <debug> [1666700577.266657] [ttyACM2/at] closing serial port...
[7273]: <debug> [1666700577.268964] [ttyACM2/at] serial port closed
[7273]: <debug> [1666700577.269398] [ttyACM2/at] forced to close port
[7273]: <debug> [1666700577.269750] [plugin-manager] task 0,wwan0: checking with plugin 'cinterion'
[7273]: <debug> [1666700577.270073] [plugin/cinterion] probing of port wwan0 deferred until result suggested
[7273]: <debug> [1666700577.270430] [plugin-manager] task 0,wwan0: completed, got suggested plugin (cinterion)
[7273]: <debug> [1666700577.270682] [plugin-manager] task 0,wwan0: finished in '2.502938' seconds
[7273]: <debug> [1666700577.270947] [plugin-manager] task 0,wwan0: best plugin matches device reported one: cinterion
[7273]: <debug> [1666700577.271195] [plugin-manager] task 0: no more ports to probe
[7273]: <debug> [1666700577.271430] [plugin-manager] task 0: all port probings completed, but not reached extra probing time yet
[7273]: <debug> [1666700577.768461] [plugin-manager] task 0: extra probing time elapsed
[7273]: <debug> [1666700577.769063] [plugin-manager] task 0: no more ports to probe
[7273]: <debug> [1666700577.769613] [plugin-manager] task 0: finished in '3.072394' seconds
[7273]: <info> [1666700577.770397] [device /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1] creating modem with plugin 'cinterion' and '2' ports
[7273]: <debug> [1666700577.803253] [modem0] port 'net/wwan0' grabbed
[7273]: <debug> [1666700577.803918] [plugin/cinterion] (cinterion) port 'tty/ttyACM2' flagged as primary
[7273]: <debug> [1666700577.804735] [modem0] port 'tty/ttyACM2' grabbed
[7273]: <debug> [1666700577.804979] [modem0] tty/ttyACM2 at (primary)
[7273]: <debug> [1666700577.805160] [modem0] tty/ttyACM2 at (data primary)
[7273]: <debug> [1666700577.805330] [modem0] net/wwan0 net (data)
[7273]: <debug> [1666700577.807568] [modem0/ttyACM2/at] opening serial port...
[7273]: <debug> [1666700577.808586] [modem0/ttyACM2/at] setting up baudrate: 57600
[7273]: <debug> [1666700577.808807] [modem0/ttyACM2/at] no flow control explicitly requested for device
[7273]: <debug> [1666700577.809124] [modem0/ttyACM2/at] device open count is 1 (open)
[7273]: <debug> [1666700577.809325] [modem0/ttyACM2/at] running init sequence...
[7273]: <info> [1666700577.809665] [base-manager] modem for device '/sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1' successfully created
[7273]: <debug> [1666700577.813764] [modem0] loading current capabilities...
[7273]: <debug> [1666700577.814243] [modem0/ttyACM2/at] device open count is 2 (open)
[7273]: <debug> [1666700577.814675] [modem0/ttyACM2/at] --> 'ATE0<CR>'
[7273]: <debug> [1666700577.829465] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700577.830124] [modem0/ttyACM2/at] --> 'ATV1<CR>'
[7273]: <debug> [1666700577.845909] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700577.846564] [modem0/ttyACM2/at] --> 'AT+CMEE=1<CR>'
[7273]: <debug> [1666700577.868269] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700577.869645] [modem0/ttyACM2/at] --> 'ATX4<CR>'
[7273]: <debug> [1666700577.884784] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700577.886139] [modem0/ttyACM2/at] --> 'AT&C1<CR>'
[7273]: <debug> [1666700577.902969] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700577.904449] [modem0/ttyACM2/at] --> 'AT+GCAP<CR>'
[7273]: <debug> [1666700577.924814] [modem0/ttyACM2/at] <-- '<CR><LF>+GCAP: +CGSM<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700577.926284] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700577.927123] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700577.927961] [modem0/ttyACM2/at] --> 'AT+WS46=?<CR>'
[7273]: <debug> [1666700577.947221] [modem0/ttyACM2/at] <-- '<CR><LF>+WS46: (12,22,25,28,29)<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700577.949010] [modem0] setting EPS network as supported
[7273]: <debug> [1666700577.949686] [modem0] setting CDMA1x/EVDO networks as unsupported
[7273]: <debug> [1666700577.950957] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700577.951830] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700577.952677] [modem0/ttyACM2/at] --> 'AT+CSCS=?<CR>'
[7273]: <debug> [1666700577.974872] [modem0/ttyACM2/at] <-- '<CR><LF>+CSCS: ("IRA","GSM","UCS2")<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700577.976883] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700577.977761] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700577.978607] [modem0/ttyACM2/at] --> 'AT+CSCS="UCS2"<CR>'
[7273]: <debug> [1666700578.008206] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.009479] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.010317] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.011151] [modem0/ttyACM2/at] --> 'AT+CSCS?<CR>'
[7273]: <debug> [1666700578.032276] [modem0/ttyACM2/at] <-- '<CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.033680] [modem0] allowed up to 2 active bearers
[7273]: <debug> [1666700578.035198] [modem0] loading manufacturer...
[7273]: <debug> [1666700578.035856] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.036728] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.037569] [modem0/ttyACM2/at] --> 'AT+CGMI<CR>'
[7273]: <debug> [1666700578.056519] [modem0/ttyACM2/at] <-- '<CR><LF>Cinterion<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.057829] [modem0] loaded manufacturer: Cinterion
[7273]: <debug> [1666700578.058634] [modem0] loading model...
[7273]: <debug> [1666700578.059298] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.060087] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.060916] [modem0/ttyACM2/at] --> 'AT+CGMM<CR>'
[7273]: <debug> [1666700578.079494] [modem0/ttyACM2/at] <-- '<CR><LF>PLAS9-W<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.080820] [modem0] loaded model: PLAS9-W
[7273]: <debug> [1666700578.081617] [modem0] loading revision...
[7273]: <debug> [1666700578.082268] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.083049] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.083979] [modem0/ttyACM2/at] --> 'AT+CGMR<CR>'
[7273]: <debug> [1666700578.103770] [modem0/ttyACM2/at] <-- '<CR><LF>REVISION 01.004<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.105210] [modem0] loaded revision: REVISION 01.004
[7273]: <debug> [1666700578.106030] [modem0] loading equipment identifier...
[7273]: <debug> [1666700578.106709] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.107577] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.108300] [modem0/ttyACM2/at] --> 'AT+CGSN<CR>'
[7273]: <debug> [1666700578.125563] [modem0/ttyACM2/at] <-- '<CR><LF>357176090983831<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.126831] [modem0] loaded equipment identifier: 357176090983831
[7273]: <debug> [1666700578.127531] [modem0] loading device identifier...
[7273]: <debug> [1666700578.128086] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.128778] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.129513] [modem0/ttyACM2/at] --> 'ATI<CR>'
[7273]: <debug> [1666700578.139786] [modem0/ttyACM2/at] <-- '<CR><LF>Cinterion<CR><LF>PLAS9-W<CR><LF>REVISION 01.004<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.141589] [modem0] device identifier built: 00001e2d00000061CinterionPLAS9-WREVISION01.004357176090983831REVISION01.004PLAS9-WCinterion -> 0c0a44f8812505a27a6ede95dee0d447ac9fb067
[7273]: <debug> [1666700578.142139] [modem0] loaded device identifier: 0c0a44f8812505a27a6ede95dee0d447ac9fb067
[7273]: <debug> [1666700578.142922] [modem0] loading supported modes...
[7273]: <debug> [1666700578.143599] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.144407] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.144782] [modem0/ttyACM2/at] --> 'AT*CNTI=2<CR>'
[7273]: <debug> [1666700578.163605] [modem0/ttyACM2/at] <-- '<CR><LF>*CNTI: 2, GSM, GPRS, EDGE, UMTS, HSDPA, HSUPA<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.164238] [modem0] device allows (3GPP) 2G networks
[7273]: <debug> [1666700578.164443] [modem0] device allows (3GPP) 3G networks
[7273]: <debug> [1666700578.164692] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.165163] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.166471] [modem0] device allows (3GPP) mode combination: 2g
[7273]: <debug> [1666700578.166949] [modem0] device allows (3GPP) mode combination: 3g
[7273]: <debug> [1666700578.167367] [modem0] device allows (3GPP) mode combination: 4g
[7273]: <debug> [1666700578.167855] [modem0] device allows (3GPP) mode combination: 2g, 3g
[7273]: <debug> [1666700578.168363] [modem0] device allows (3GPP) mode combination: 2g, 3g, 4g
[7273]: <debug> [1666700578.168972] [modem0] filtering 4 supported mode combinations with 1 modes
[7273]: <debug> [1666700578.169441] [modem0] device supports 4 different mode combinations
[7273]: <debug> [1666700578.170516] [modem0] (cinterion) Using cinterion default modem family
[7273]: <debug> [1666700578.171183] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.172091] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.172913] [modem0/ttyACM2/at] --> 'AT^SCFG=?<CR>'
[7273]: <debug> [1666700578.197188] [modem0/ttyACM2/at] <-- '<CR><LF>^SCFG: "GPRS/Auth",("0","1","2")<CR><LF>^SCFG: "GPRS/AutoAttach",("disabled","enabled")<CR><LF>^SCFG: "MEopMode/CFUN",("0","1")<CR><LF>^SCFG: "MEopMode/CregRoam",("0","1")<CR><LF>^SCFG: "MEopMode/DTM/Mode",("0","1","2")<CR><LF>^SCFG: "MEopMode/ExpectDTR",("current","powerup"),("acm1","acm2","acm3","acm4","rmnet0","rmnet1")<CR><LF>^SCFG: "MEopMode/FGI/Split",("0","1")<CR><LF>^SCFG: "MEopMode/IMS",("0","1")<CR><LF>^SCFG: "MEopMode/NonBlock/Cops",("0","1")<CR><LF>^SCFG: "MEopMode/PowerMgmt/LCI",("disabled","enabled"),("GPIO1","GPIO2","GPIO3","GPIO4","GPIO5","GPIO6","GPIO7","GPIO8","GPIO9","GPIO10")<CR><LF>^SCFG: "MEopMode/Prov/AutoSelect",("on","off")<CR><LF>^SCFG: "MEopMode/Prov/Cfg",("fallbdceu*","cmcgdccn")<CR><LF>^SCFG: "MEopMode/PwrSave",("disabled","enabled"),("0-36000"),("0-36000"),("CPU-A","CPU-M"),("powerup","current")<CR><LF>^SCFG: "MEopMode/SRPOM",("0","1","99")<CR><LF>^SCFG: "MEopMode/USB/KeepData",("current","p
[7273]: <debug> [1666700578.199848] [modem0/ttyACM2/at] <-- 'et1")<CR><LF>^SCFG: "RemoteWakeUp/Pulse",("1"-"100")<CR><LF>^SCFG: "SIM/CS",("NO_SIM","SIM_1","SIM_2")<CR><LF>^SCFG: "SMS/AutoAck",("0","1")<CR><LF>^SCFG: "SMS/Retrm",(1-45)<CR><LF>^SCFG: "Trace/SIM",("none","raw","decoded")<CR><LF>^SCFG: "URC/DstIfc",("mdm","app")<CR><LF>^SCFG: "URC/Ringline",("off","local","asc0")<CR><LF>^SCFG: "URC/Ringline/ActiveTime",("2","on","off")<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.202903] [modem0] loading supported IP families...
[7273]: <debug> [1666700578.203299] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.203719] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.204194] [modem0/ttyACM2/at] --> 'AT+CGDCONT=?<CR>'
[7273]: <debug> [1666700578.235179] [modem0/ttyACM2/at] <-- '<CR><LF>+CGDCONT: (1-16),"IP",,,(0-2),(0-4),(0),(0,1),(0-2),(0,1)<CR><LF>+CGDCONT: (1-16),"PPP",,,(0-2),(0-4),(0),(0,1),(0-2),(0,1)<CR><LF>+CGDCONT: (1-16),"IPV6",,,(0-2),(0-4),(0),(0,1),(0-2),(0,1)<CR><LF>+CGDCONT: (1-16),"IPV4V6",,,(0-2),(0-4),(0),(0,1),(0-2),(0,1)<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.237587] [modem0] unhandled PDP type in CGDCONT=? reply: 'PPP'
[7273]: <debug> [1666700578.238823] [modem0] loading power state...
[7273]: <debug> [1666700578.239500] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.240365] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.241234] [modem0/ttyACM2/at] --> 'AT+CFUN?<CR>'
[7273]: <debug> [1666700578.259106] [modem0/ttyACM2/at] <-- '<CR><LF>+CFUN: 1<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.260964] [modem0] (cinterion) Enabling SCKS URCs for SIM hot swap detection
[7273]: <debug> [1666700578.261760] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.262645] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.263510] [modem0/ttyACM2/at] --> 'AT^SCKS=1<CR>'
[7273]: <debug> [1666700578.285454] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.286644] [modem0] (cinterion) SIM hot swap detect successfully enabled
[7273]: <debug> [1666700578.287307] [modem0] SIM hot swap setup succeeded
[7273]: <debug> [1666700578.288183] [modem0] checking if unlock required...
[7273]: <debug> [1666700578.288847] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.289713] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.290550] [modem0/ttyACM2/at] --> 'AT+CPIN?<CR>'
[7273]: <debug> [1666700578.309058] [modem0/ttyACM2/at] <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.310506] [modem0] SIM is ready, running after SIM unlock step...
[7273]: <debug> [1666700578.311287] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.312206] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.313054] [modem0/ttyACM2/at] --> 'AT^SIND=?<CR>'
[7273]: <debug> [1666700578.333657] [modem0/ttyACM2/at] <-- '<CR><LF>^SIND: ((signal,(99)),(service,(0-1)),(message,(0-1)),(roam,(0-1)),(smsfull,(0-1)),(simstatus,(0,1,3-5)),(simdata),(eons,(0-6)),(nitz,(17)),(sendsms,(0-1)),(psinfo,(0-10,16,17)),(simlocal,(0-1)),(lsta,(0-2)),(pacsp,(0-1,99)),(steerroam),(pagingcoor,(0-2,4,8,16,99)),(ceer,(0-8,99)),(iccid,(20)),(euiccid,(16)),(imsi,(15)),(ltebot,(0-1)),(prov,(63)),(simread,(0-7,255))),(0-2)<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.335996] [modem0] (cinterion) psinfo support? yes
[7273]: <debug> [1666700578.336854] [modem0] (cinterion) simstatus support? yes
[7273]: <debug> [1666700578.337574] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.338383] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.339236] [modem0/ttyACM2/at] --> 'AT^SIND="simstatus",2<CR>'
[7273]: <debug> [1666700578.376063] [modem0/ttyACM2/at] <-- '<CR><LF>^SIND: simstatus,0,5<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.378470] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.379414] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.380283] [modem0/ttyACM2/at] --> 'AT^SPIC="SC"<CR>'
[7273]: <debug> [1666700578.405264] [modem0/ttyACM2/at] <-- '<CR><LF>^SPIC: 3<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.407110] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.408346] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.409587] [modem0/ttyACM2/at] --> 'AT^SPIC="SC",1<CR>'
[7273]: <debug> [1666700578.437800] [modem0/ttyACM2/at] <-- '<CR><LF>^SPIC: 10<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.439606] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.440814] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.442067] [modem0/ttyACM2/at] --> 'AT^SPIC="P2"<CR>'
[7273]: <debug> [1666700578.470479] [modem0/ttyACM2/at] <-- '<CR><LF>^SPIC: 3<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.472296] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.473539] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.474905] [modem0/ttyACM2/at] --> 'AT^SPIC="P2",1<CR>'
[7273]: <debug> [1666700578.502760] [modem0/ttyACM2/at] <-- '<CR><LF>^SPIC: 10<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.504277] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.505154] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.506014] [modem0/ttyACM2/at] --> 'AT^SPIC="PS"<CR>'
[7273]: <debug> [1666700578.539265] [modem0/ttyACM2/at] <-- '<CR><LF>^SPIC: 0<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.540659] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.541516] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.542358] [modem0/ttyACM2/at] --> 'AT^SPIC="PS",1<CR>'
[7273]: <debug> [1666700578.579419] [modem0/ttyACM2/at] <-- '<CR><LF>^SPIC: 0<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.585104] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.588202] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.591419] [modem0/ttyACM2/at] --> 'AT^SPIC="PN"<CR>'
[7273]: <debug> [1666700578.629686] [modem0/ttyACM2/at] <-- '<CR><LF>^SPIC: 0<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.635388] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.638562] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.641364] [modem0/ttyACM2/at] --> 'AT^SPIC="PN",1<CR>'
[7273]: <debug> [1666700578.679151] [modem0/ttyACM2/at] <-- '<CR><LF>^SPIC: 0<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.702372] [modem0/sim0] loading SIM identifier...
[7273]: <debug> [1666700578.704880] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.705200] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.705476] [modem0/ttyACM2/at] --> 'AT+CRSM=176,12258,0,0,10<CR>'
[7273]: <debug> [1666700578.771309] [modem0/ttyACM2/at] <-- '<CR><LF>+CRSM: 144,0,"98740000814021362191"<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.776642] [modem0/sim0] loaded SIM identifier: 89470000180412631219
[7273]: <debug> [1666700578.777360] [modem0/sim0] loading IMSI...
[7273]: <debug> [1666700578.777940] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.778678] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.779399] [modem0/ttyACM2/at] --> 'AT+CIMI<CR>'
[7273]: <debug> [1666700578.799145] [modem0/ttyACM2/at] <-- '<CR><LF>242013082365094<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.800334] [modem0/sim0] loaded IMSI: 242013082365094
[7273]: <debug> [1666700578.801014] [modem0/sim0] loading operator ID...
[7273]: <debug> [1666700578.801597] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.802328] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.803051] [modem0/ttyACM2/at] --> 'AT+CRSM=176,28589,0,0,4<CR>'
[7273]: <debug> [1666700578.875019] [modem0/ttyACM2/at] <-- '<CR><LF>+CRSM: 144,0,"00000002"<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.876954] [modem0/sim0] loading operator name...
[7273]: <debug> [1666700578.877569] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.878297] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.879027] [modem0/ttyACM2/at] --> 'AT+CRSM=176,28486,0,0,17<CR>'
[7273]: <debug> [1666700578.951395] [modem0/ttyACM2/at] <-- '<CR><LF>+CRSM: 144,0,"0054656C656E6F72FFFFFFFFFFFFFFFFFF"<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700578.959338] [modem0/sim0] loading emergency numbers...
[7273]: <debug> [1666700578.961653] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700578.965240] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700578.965618] [modem0/ttyACM2/at] --> 'AT+CRSM=176,28599,0,0,15<CR>'
[7273]: <debug> [1666700579.034053] [modem0/ttyACM2/at] <-- '<CR><LF>+CRSM: 105,129,""<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <warn> [1666700579.035740] [modem0/sim0] couldn't load list of emergency numbers: Failed to parse CRSM query result '+CRSM: 105,129,""'
[7273]: <debug> [1666700579.036372] [modem0/sim0] selecting user-defined preferred network list...
[7273]: <debug> [1666700579.036905] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.037600] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.038306] [modem0/ttyACM2/at] --> 'AT+CPLS=0<CR>'
[7273]: <debug> [1666700579.057874] [modem0/ttyACM2/at] <-- '<CR><LF>+CME ERROR: 111<CR><LF>'
[7273]: <debug> [1666700579.058965] [modem0/ttyACM2/at] operation failure: 111 (PLMN not allowed)
[7273]: <debug> [1666700579.059577] [modem0/sim0] selecting user-defined preferred network list failed: 'PLMN not allowed'
[7273]: <debug> [1666700579.060043] [modem0/sim0] setting preferred networks format...
[7273]: <debug> [1666700579.060573] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.061235] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.061948] [modem0/ttyACM2/at] --> 'AT+CPOL=,2<CR>'
[7273]: <debug> [1666700579.084232] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.085282] [modem0/sim0] loading preferred networks...
[7273]: <debug> [1666700579.085837] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.086511] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.087223] [modem0/ttyACM2/at] --> 'AT+CPOL?<CR>'
[7273]: <debug> [1666700579.110840] [modem0/ttyACM2/at] <-- '<CR><LF>+CPOL: 1,2,"24201",1,0,1,1<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.113200] [modem0/sim0] loaded 1 preferred networks
[7273]: <debug> [1666700579.115063] [modem0] loading own numbers...
[7273]: <debug> [1666700579.115635] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.116407] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.117146] [modem0/ttyACM2/at] --> 'AT+CNUM<CR>'
[7273]: <debug> [1666700579.136483] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.137915] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.138684] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.139408] [modem0/ttyACM2/at] --> 'AT^SCFG?<CR>'
[7273]: <debug> [1666700579.166145] [modem0/ttyACM2/at] <-- '<CR><LF>^SCFG: "GPRS/Auth","2"<CR><LF>^SCFG: "GPRS/AutoAttach","enabled"<CR><LF>^SCFG: "MEopMode/CFUN","1","1"<CR><LF>^SCFG: "MEopMode/CregRoam","0"<CR><LF>^SCFG: "MEopMode/DTM/Mode","0"<CR><LF>^SCFG: "MEopMode/ExpectDTR","current","acm1","acm2","acm3","acm4","rmnet0","rmnet1"<CR><LF>^SCFG: "MEopMode/ExpectDTR","powerup","acm1","acm2","acm3","acm4","rmnet0","rmnet1"<CR><LF>^SCFG: "MEopMode/FGI/Split","1"<CR><LF>^SCFG: "MEopMode/IMS","0"<CR><LF>^SCFG: "MEopMode/NonBlock/Cops","0"<CR><LF>^SCFG: "MEopMode/PowerMgmt/LCI","disabled"<CR><LF>^SCFG: "MEopMode/Prov/AutoSelect","on"<CR><LF>^SCFG: "MEopMode/Prov/Cfg","cmcgdccn"<CR><LF>^SCFG: "MEopMode/PwrSave","enabled","0","0","CPU-A","powerup"<CR><LF>^SCFG: "MEopMode/PwrSave","enabled","0","0","CPU-A","current"<CR><LF>^SCFG: "MEopMode/PwrSave","enabled","0","0","CPU-M","powerup"<CR><LF>^SCFG: "MEopMode/PwrSave","enabled","0","0","CPU-M","current"<CR><LF>^SCFG: "MEopMode/SRPOM","0"<CR><LF
[7273]: <debug> [1666700579.178747] [modem0] loading enabled facility locks...
[7273]: <debug> [1666700579.179343] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.180125] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.181050] [modem0/ttyACM2/at] --> 'AT+CLCK=?<CR>'
[7273]: <debug> [1666700579.201092] [modem0/ttyACM2/at] <-- '<CR><LF>+CLCK: ("AB","AC","AG","AI","AO","IR","OI","OX","SC","FD","PS","PN")<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.201991] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.202275] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.202554] [modem0/ttyACM2/at] --> 'AT+CLCK="SC",2<CR>'
[7273]: <debug> [1666700579.231106] [modem0/ttyACM2/at] <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.232728] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.233426] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.234278] [modem0/ttyACM2/at] --> 'AT+CLCK="FD",2<CR>'
[7273]: <debug> [1666700579.258765] [modem0/ttyACM2/at] <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.260386] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.261093] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.261829] [modem0/ttyACM2/at] --> 'AT+CLCK="PS",2<CR>'
[7273]: <debug> [1666700579.301368] [modem0/ttyACM2/at] <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.307920] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.310549] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.315670] [modem0/ttyACM2/at] --> 'AT+CLCK="PN",2<CR>'
[7273]: <debug> [1666700579.349297] [modem0/ttyACM2/at] <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.356419] [modem0] loading IMEI...
[7273]: <debug> [1666700579.358566] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.361352] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.362718] [modem0] loaded IMEI: 357176090983831
[7273]: <debug> [1666700579.363039] [modem0] loading UE mode of operation for EPS...
[7273]: <debug> [1666700579.363304] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.363670] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.364120] [modem0/ttyACM2/at] --> 'AT+CEMODE?<CR>'
[7273]: <debug> [1666700579.385338] [modem0/ttyACM2/at] <-- '<CR><LF>+CEMODE: 2<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.387338] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.388177] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.389039] [modem0/ttyACM2/at] --> 'AT^SCFG="MEopMode/Prov/Cfg"<CR>'
[7273]: <debug> [1666700579.432066] [modem0/ttyACM2/at] <-- '<CR><LF>^SCFG: "MEopMode/Prov/Cfg","cmcgdccn"<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.433685] [modem0] (cinterion) current mno: cmcgdccn
[7273]: <debug> [1666700579.434456] [modem0] (cinterion) loaded EPS bearer context id from list of MNO profiles: 1
[7273]: <debug> [1666700579.435236] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.436124] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.436962] [modem0/ttyACM2/at] --> 'AT+CGCONTRDP<CR>'
[7273]: <debug> [1666700579.463931] [modem0/ttyACM2/at] <-- '<CR><LF>+CGCONTRDP: 1,5,"internet","176.75.17.155","","193.213.112.4","130.67.15.198"<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.466062] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.466900] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.467775] [modem0/ttyACM2/at] --> 'AT^SGAUTH?<CR>'
[7273]: <debug> [1666700579.494744] [modem0/ttyACM2/at] <-- '<CR><LF>^SGAUTH: 1,0<CR><LF>^SGAUTH: 2,0<CR><LF>^SGAUTH: 3,0<CR><LF>^SGAUTH: 4,0<CR><LF>^SGAUTH: 5,0<CR><LF>^SGAUTH: 6,2,"internet"<CR><LF>^SGAUTH: 7,0<CR><LF>^SGAUTH: 8,0<CR><LF>^SGAUTH: 9,0<CR><LF>^SGAUTH: 10,2,"internet"<CR><LF>^SGAUTH: 11,2,"internet"<CR><LF>^SGAUTH: 12,0<CR><LF>^SGAUTH: 13,0<CR><LF>^SGAUTH: 14,0<CR><LF>^SGAUTH: 15,0<CR><LF>^SGAUTH: 16,0<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.501130] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.502104] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.505816] [modem0/ttyACM2/at] device open count is 1 (close)
[7273]: <debug> [1666700579.510103] [modem0] (cinterion) no GPS data port found: no GPS capabilities
[7273]: <debug> [1666700579.513611] [modem0/ttyACM2/at] device open count is 2 (open)
[7273]: <debug> [1666700579.514546] [modem0/ttyACM2/at] --> 'AT+CNMI=?<CR>'
[7273]: <debug> [1666700579.533378] [modem0/ttyACM2/at] <-- '<CR><LF>+CNMI: (0,1,2),(0,1),(0,2),(0),(1)<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.534210] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.534750] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.535616] [modem0/ttyACM2/at] --> 'AT+CPMS=?<CR>'
[7273]: <debug> [1666700579.559138] [modem0/ttyACM2/at] <-- '<CR><LF>+CPMS: ("ME","MT","SM","SR"),("ME","MT","SM","SR"),("ME","MT","SM","SR")<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.561549] [modem0] supported storages loaded:
[7273]: <debug> [1666700579.562116] [modem0] mem1 (list/read/delete) storages: 'me, mt, sm, sr'
[7273]: <debug> [1666700579.562648] [modem0] mem2 (write/send) storages: 'me, mt, sm, sr'
[7273]: <debug> [1666700579.563173] [modem0] mem3 (reception) storages: 'me, mt, sm, sr'
[7273]: <debug> [1666700579.564284] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.565160] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.566008] [modem0/ttyACM2/at] --> 'AT+CPMS?<CR>'
[7273]: <debug> [1666700579.586752] [modem0/ttyACM2/at] <-- '<CR><LF>+CPMS: "SR",0,0,"MT",1,255,"MT",1,255<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.588602] [modem0] current storages initialized:
[7273]: <debug> [1666700579.589224] [modem0] mem1 (list/read/delete) storages: 'sr'
[7273]: <debug> [1666700579.589802] [modem0] mem2 (write/send) storages: 'mt'
[7273]: <debug> [1666700579.590371] [modem0] current storages initialized
[7273]: <debug> [1666700579.592527] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.593570] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.594605] [modem0/ttyACM2/at] --> 'AT+CTZU=1<CR>'
[7273]: <debug> [1666700579.618121] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.619036] [modem0/ttyACM2/at] --> 'AT+CCLK?<CR>'
[7273]: <debug> [1666700579.651950] [modem0/ttyACM2/at] <-- '<CR><LF>+CCLK: "22/10/25,12:23:03+08"<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.667305] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.670266] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.672477] [modem0/ttyACM2/at] --> 'AT^SMONI=?<CR>'
[7273]: <debug> [1666700579.694816] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.695965] [modem0] (cinterion) SMONI supported
[7273]: <debug> [1666700579.698915] [modem0/ttyACM2/at] device open count is 1 (close)
[7273]: <debug> [1666700579.699679] [modem0] couldn't initialize interface: 'OMA not supported'
[7273]: <debug> [1666700579.702719] [modem0/ttyACM2/at] device open count is 2 (open)
[7273]: <debug> [1666700579.703706] [modem0/ttyACM2/at] --> 'ATH<CR>'
[7273]: <debug> [1666700579.718201] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.718777] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.719119] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.719468] [modem0/ttyACM2/at] --> 'AT+CLCC=?<CR>'
[7273]: <debug> [1666700579.739356] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.740608] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700579.741359] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700579.742092] [modem0/ttyACM2/at] --> 'AT^SLCC=?<CR>'
[7273]: <debug> [1666700579.762518] [modem0/ttyACM2/at] <-- '<CR><LF>^SLCC: (0-1)<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700579.766630] [modem0/ttyACM2/at] device open count is 1 (close)
[7273]: <debug> [1666700579.767451] [modem0] creating ports context for SIM hot swap
[7273]: <debug> [1666700579.767928] [modem0/ttyACM2/at] device open count is 2 (open)
[7273]: <info> [1666700579.769745] [modem0] state changed (unknown -> disabled)
[7273]: <debug> [1666700579.771054] [modem0] modem initialized
[7273]: <debug> [1666700579.786476] [device /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1] exported modem at path '/org/freedesktop/ModemManager1/Modem/0'
[7273]: <debug> [1666700579.786681] [device /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1] plugin: cinterion
[7273]: <debug> [1666700579.786835] [device /sys/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1] vid:pid: 0x1E2D:0x0061
[7273]: <debug> [1666700579.787047] [modem0/ttyACM2/at] device open count is 1 (close)
[7273]: <debug> [1666700587.178189] [modem0] user request to connect modem
[7273]: <info> [1666700587.178795] [modem0] simple connect started...
[7273]: <debug> [1666700587.179012] [modem0] profile ID: unspecified
[7273]: <debug> [1666700587.179191] [modem0] PIN: unspecified
[7273]: <debug> [1666700587.179368] [modem0] operator ID: unspecified
[7273]: <debug> [1666700587.179544] [modem0] allowed roaming: yes
[7273]: <debug> [1666700587.179709] [modem0] APN: telenor.smart
[7273]: <debug> [1666700587.179887] [modem0] APN type: unspecified
[7273]: <debug> [1666700587.180068] [modem0] IP family: ipv4
[7273]: <debug> [1666700587.180240] [modem0] allowed authentication: unspecified
[7273]: <debug> [1666700587.180421] [modem0] user: unspecified
[7273]: <debug> [1666700587.180560] [modem0] password: unspecified
[7273]: <debug> [1666700587.180676] [modem0] multiplex: unspecified
[7273]: <info> [1666700587.180809] [modem0] simple connect state (3/8): enable
[7273]: <info> [1666700587.181175] [modem0] state changed (disabled -> enabling)
[7273]: <debug> [1666700587.182336] [modem0] skipping initialization: device hotplugged
[7273]: <debug> [1666700587.182550] [modem0/ttyACM2/at] device open count is 2 (open)
[7273]: <debug> [1666700587.182706] [modem0] flashing primary AT port before enabling...
[7273]: <debug> [1666700587.183550] [modem0] loading power state...
[7273]: <debug> [1666700587.183889] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700587.184970] [modem0/ttyACM2/at] --> 'AT+CFUN?<CR>'
[7273]: <debug> [1666700587.202052] [modem0/ttyACM2/at] <-- '<CR><LF>+CFUN: 1<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.203677] [modem0] no need to change power state: already 'on'
[7273]: <info> [1666700587.204440] [modem0] power state updated: on
[7273]: <debug> [1666700587.205174] [modem0] checking if SIM was swapped...
[7273]: <debug> [1666700587.205857] [modem0/sim0] loading SIM identifier...
[7273]: <debug> [1666700587.206605] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.207524] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.208375] [modem0/ttyACM2/at] --> 'AT+CRSM=176,12258,0,0,10<CR>'
[7273]: <debug> [1666700587.269646] [modem0/ttyACM2/at] <-- '<CR><LF>+CRSM: 144,0,"98740000814021362191"<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.271504] [modem0/sim0] loaded SIM identifier: 89470000180412631219
[7273]: <debug> [1666700587.272395] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.273306] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.274244] [modem0/ttyACM2/at] --> 'AT\Q3<CR>'
[7273]: <debug> [1666700587.289697] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.290286] [modem0] modem has 3GPP capabilities, enabling the Modem 3GPP interface...
[7273]: <debug> [1666700587.290658] [modem0] checking indicator support...
[7273]: <debug> [1666700587.290999] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.291382] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.291735] [modem0/ttyACM2/at] --> 'AT+CIND=?<CR>'
[7273]: <debug> [1666700587.311875] [modem0/ttyACM2/at] <-- '<CR><LF>+CIND: ("battchg",(0-5)),("signal",(0-5)),("service",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1)),("GPRS coverage",(0-1)),("callsetup",(0-3))<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.314149] [modem0] signal quality indications via CIND are supported at index '2' (min: 0, max: 5)
[7273]: <debug> [1666700587.314669] [modem0] roaming indications via CIND are supported at index '5'
[7273]: <debug> [1666700587.315091] [modem0] service indications via CIND are supported at index '3'
[7273]: <debug> [1666700587.315716] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.316386] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.317133] [modem0/ttyACM2/at] --> 'AT+CMER=?<CR>'
[7273]: <debug> [1666700587.340733] [modem0/ttyACM2/at] <-- '<CR><LF>+CMER: (0-3),(0),(0),(0-1),(0-1)<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.342222] [modem0] supported +CMER modes: discard-urcs, discard-urcs-if-link-reserved, buffer-urcs-if-link-reserved, forward-urcs
[7273]: <debug> [1666700587.342797] [modem0] supported +CMER indication settings: disable, enable-not-caused-by-cind
[7273]: <debug> [1666700587.343238] [modem0] +CMER enable mode: forward-urcs
[7273]: <debug> [1666700587.343650] [modem0] +CMER disable mode: discard-urcs
[7273]: <debug> [1666700587.344181] [modem0] +CMER indication setting: enable-not-caused-by-cind
[7273]: <debug> [1666700587.344612] [modem0] checking packet domain event reporting...
[7273]: <debug> [1666700587.345160] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.345828] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.346554] [modem0/ttyACM2/at] --> 'AT+CGEREP=?<CR>'
[7273]: <debug> [1666700587.370544] [modem0/ttyACM2/at] <-- '<CR><LF>+CGEREP: (0-2),(0-1)<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.371134] [modem0] packet domain event reporting is supported
[7273]: <debug> [1666700587.371466] [modem0] setting 3GPP +CIEV unsolicited events handlers in ttyACM2
[7273]: <debug> [1666700587.371774] [modem0] setting 3GPP +CGEV unsolicited events handlers in ttyACM2
[7273]: <debug> [1666700587.372128] [modem0] enabling +CIND event reporting in primary port...
[7273]: <debug> [1666700587.372407] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.372770] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.373139] [modem0/ttyACM2/at] --> 'AT+CMER=3,0,0,1<CR>'
[7273]: <debug> [1666700587.400937] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.402025] [modem0] enabling +CGEV event reporting in primary port...
[7273]: <debug> [1666700587.402615] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.403282] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.404152] [modem0/ttyACM2/at] --> 'AT+CGEREP=2<CR>'
[7273]: <debug> [1666700587.429391] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.430625] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.431392] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.432119] [modem0/ttyACM2/at] --> 'AT^SCFG="URC/DstIfc","app"<CR>'
[7273]: <debug> [1666700587.475376] [modem0/ttyACM2/at] <-- '<CR><LF>^SCFG: "URC/DstIfc","app"<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.476701] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.477402] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.478145] [modem0/ttyACM2/at] --> 'AT^SIND="psinfo",1<CR>'
[7273]: <debug> [1666700587.511459] [modem0/ttyACM2/at] <-- '<CR><LF>^SIND: psinfo,1,17<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.513045] [modem0] (cinterion) reporting initial access technologies...
[7273]: <debug> [1666700587.514078] [modem0] access technology changed (unknown -> lte)
[7273]: <debug> [1666700587.520407] [modem0] setting up 3GPP unsolicited registration messages handlers in ttyACM2
[7273]: <debug> [1666700587.521460] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700587.526680] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700587.527590] [modem0/ttyACM2/at] --> 'AT+CREG=2<CR>'
[7273]: <debug> [1666700587.549473] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.550741] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.553417] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.556600] [modem0/ttyACM2/at] --> 'AT+CGREG=2<CR>'
[7273]: <debug> [1666700587.587687] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.588164] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.588436] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.588712] [modem0/ttyACM2/at] --> 'AT+CEREG=2<CR>'
[7273]: <debug> [1666700587.611964] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.613365] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.614384] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.615134] [modem0/ttyACM2/at] --> 'AT+CGDCONT?<CR>'
[7273]: <debug> [1666700587.765133] [modem0/ttyACM2/at] <-- '<CR><LF>+CGDCONT: 1,"IP","internet","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 2,"IP","telenor.smart","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 3,"IP","internet","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 4,"IP","plus","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 5,"IP","IMS","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 6,"IP","internetipv6","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 7,"IP","something","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 8,"IPV6","internetipv6","0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 9,"IPV6","internet","0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 10,"IPV6","internetipv6WRONG","0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 11,"IPV6","IMS","0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 12,"IPV4V6","internet","0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 13,"IP","hos","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 14,"IP","Hos12","0.0.0.0",0,0,0,0,0,0<CR><L
[7273]: <debug> [1666700587.784955] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.785246] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.785627] [modem0/ttyACM2/at] --> 'AT^SGAUTH?<CR>'
[7273]: <debug> [1666700587.808475] [modem0/ttyACM2/at] <-- '<CR><LF>^SGAUTH: 1,0<CR><LF>^SGAUTH: 2,0<CR><LF>^SGAUTH: 3,0<CR><LF>^SGAUTH: 4,0<CR><LF>^SGAUTH: 5,0<CR><LF>^SGAUTH: 6,2,"internet"<CR><LF>^SGAUTH: 7,0<CR><LF>^SGAUTH: 8,0<CR><LF>^SGAUTH: 9,0<CR><LF>^SGAUTH: 10,2,"internet"<CR><LF>^SGAUTH: 11,2,"internet"<CR><LF>^SGAUTH: 12,0<CR><LF>^SGAUTH: 13,0<CR><LF>^SGAUTH: 14,0<CR><LF>^SGAUTH: 15,0<CR><LF>^SGAUTH: 16,0<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.811488] [modem0] updating initial EPS bearer...
[7273]: <debug> [1666700587.817469] [modem0/bearer0] bearer not allowed to connect, not registered in 3GPP network
[7273]: <debug> [1666700587.820539] [modem0] modem has 3GPP profile management capabilities, enabling the Modem 3GPP Profile Manager interface...
[7273]: <debug> [1666700587.821339] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700587.824198] [modem0] modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface...
[7273]: <debug> [1666700587.825646] [modem0] setting unsolicited result code handlers in ttyACM2
[7273]: <debug> [1666700587.826891] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700587.827797] [modem0/ttyACM2/at] --> 'AT+CUSD=1<CR>'
[7273]: <debug> [1666700587.847853] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.848403] [modem0] modem has location capabilities, enabling the Location interface...
[7273]: <debug> [1666700587.849045] [modem0] need to enable the following location sources: '3gpp-lac-ci'
[7273]: <debug> [1666700587.855399] [modem0] running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes', 5GS: 'no')
[7273]: <debug> [1666700587.860216] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.866020] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700587.870633] [modem0] modem has messaging capabilities, enabling the Messaging interface...
[7273]: <debug> [1666700587.871520] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700587.872765] [modem0/ttyACM2/at] --> 'AT+CREG?<CR>'
[7273]: <debug> [1666700587.893327] [modem0/ttyACM2/at] <-- '<CR><LF>+CREG: 2,5,"2AF9","02C5901",7<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.895249] [modem0] 3GPP registration state change ignored as modem isn't enabled
[7273]: <debug> [1666700587.897003] [modem0/ttyACM2/at] device open count is 5 (open)
[7273]: <debug> [1666700587.898201] [modem0/ttyACM2/at] device open count is 4 (close)
[7273]: <debug> [1666700587.899491] [modem0/ttyACM2/at] --> 'AT+CMGF=?<CR>'
[7273]: <debug> [1666700587.920727] [modem0/ttyACM2/at] <-- '<CR><LF>+CMGF: (0-1)<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.923056] [modem0/ttyACM2/at] device open count is 5 (open)
[7273]: <debug> [1666700587.924371] [modem0/ttyACM2/at] device open count is 4 (close)
[7273]: <debug> [1666700587.925675] [modem0/ttyACM2/at] --> 'AT+CGREG?<CR>'
[7273]: <debug> [1666700587.947293] [modem0/ttyACM2/at] <-- '<CR><LF>+CGREG: 2,5,"2AF9","02C5901",7<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.949048] [modem0] 3GPP registration state change ignored as modem isn't enabled
[7273]: <debug> [1666700587.950756] [modem0/ttyACM2/at] device open count is 5 (open)
[7273]: <debug> [1666700587.951939] [modem0/ttyACM2/at] device open count is 4 (close)
[7273]: <debug> [1666700587.953211] [modem0/ttyACM2/at] --> 'AT+CMGF=0<CR>'
[7273]: <debug> [1666700587.975093] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700587.976693] [modem0] successfully set preferred SMS mode: 'PDU'
[7273]: <debug> [1666700587.978365] [modem0/ttyACM2/at] device open count is 5 (open)
[7273]: <debug> [1666700587.979650] [modem0/ttyACM2/at] device open count is 4 (close)
[7273]: <debug> [1666700587.982228] [modem0/ttyACM2/at] --> 'AT+CEREG?<CR>'
[7273]: <debug> [1666700588.006073] [modem0/ttyACM2/at] <-- '<CR><LF>+CEREG: 2,5,"2AF9","002C5901",7<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.006611] [modem0] 3GPP registration state change ignored as modem isn't enabled
[7273]: <debug> [1666700588.007200] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.007587] [modem0/ttyACM2/at] --> 'AT+CPMS="SR","MT","MT"<CR>'
[7273]: <debug> [1666700588.043693] [modem0/ttyACM2/at] <-- '<CR><LF>+CPMS: 0,0,1,255,1,255<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.045123] [modem0] listing SMS parts in storage 'me'
[7273]: <debug> [1666700588.045659] [modem0] locking SMS storages to: mem1 (ME), mem2 (none)...
[7273]: <debug> [1666700588.046255] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.046966] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.047678] [modem0/ttyACM2/at] --> 'AT+CPMS="ME"<CR>'
[7273]: <debug> [1666700588.073746] [modem0/ttyACM2/at] <-- '<CR><LF>+CPMS: 1,255,1,255,1,255<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.075242] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.076016] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.076737] [modem0/ttyACM2/at] --> 'AT+CMGL=4<CR>'
[7273]: <debug> [1666700588.102041] [modem0/ttyACM2/at] <-- '<CR><LF>+CMGL: 0,1,,121<CR><LF>07918405210077F7040B918496114388F3000022014190351580744BFA7B0E22EAEF6F779AED023D41E73759EF02C172BADA0C54D7E7E9EBF7DD9D5E83E8E5F31BE4AEB7CBF23A082E7F8BDFF7301B344F97417A10F52D0E83E6EBB79B1E5ED3DFF7F0D8050A8AF3203DC89D6E83E06FF95BDF0EDFD3E1310BF42693F5F7B7DB05<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.104259] [modem0] parsing PDU (0)...
[7273]: <debug> [1666700588.104727] [modem0] SMSC address parsed: '+48501200777'
[7273]: <debug> [1666700588.105146] [modem0] deliver type PDU detected
[7273]: <debug> [1666700588.105534] [modem0] number parsed: +48691134883
[7273]: <debug> [1666700588.106050] [modem0] PID: 0
[7273]: <debug> [1666700588.106443] [modem0] user data encoding is GSM7
[7273]: <debug> [1666700588.106826] [modem0] user data length: 116 elements
[7273]: <debug> [1666700588.107241] [modem0] user data length: 102 bytes
[7273]: <debug> [1666700588.107640] [modem0] decoding SMS text with 116 elements
[7273]: <debug> [1666700588.108252] [modem0] converted SMS part text from GSM-7 to UTF-8: Ktos Dzwonil. O godz. 09:53 uzytkownik tego numeru probowal sie z Toba skontaktowac. Aby z nim porozmawiac, oddzwon.
[7273]: <debug> [1666700588.108730] [modem0] correctly parsed PDU (0)
[7273]: <debug> [1666700588.109162] [modem0/sms-list] SMS part at 'me/0' is from a singlepart SMS
[7273]: <debug> [1666700588.116650] [modem0] listing SMS parts in storage 'sm'
[7273]: <debug> [1666700588.116936] [modem0] locking SMS storages to: mem1 (SM), mem2 (none)...
[7273]: <debug> [1666700588.118182] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.118656] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.119445] [modem0/ttyACM2/at] --> 'AT+CPMS="SM"<CR>'
[7273]: <debug> [1666700588.144095] [modem0/ttyACM2/at] <-- '<CR><LF>+CPMS: 0,20,1,255,1,255<CR><LF><CR><LF>OK<CR><LF><CR><LF>+CIEV: 5,0<CR><LF>'
[7273]: <debug> [1666700588.146068] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.146844] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.148015] [modem0/ttyACM2/at] --> 'AT+CMGL=4<CR>'
[7273]: <debug> [1666700588.171131] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.171958] [modem0] listing SMS parts in storage 'sr'
[7273]: <debug> [1666700588.172414] [modem0] locking SMS storages to: mem1 (SR), mem2 (none)...
[7273]: <debug> [1666700588.172736] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.173455] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.174245] [modem0/ttyACM2/at] --> 'AT+CPMS="SR"<CR>'
[7273]: <debug> [1666700588.199749] [modem0/ttyACM2/at] <-- '<CR><LF>+CPMS: 0,0,1,255,1,255<CR><LF><CR><LF>OK<CR><LF><CR><LF>+CIEV: 5,1<CR><LF>'
[7273]: <debug> [1666700588.201733] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.202518] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.203610] [modem0/ttyACM2/at] --> 'AT+CMGL=4<CR>'
[7273]: <debug> [1666700588.229075] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.231828] [modem0] setting messaging unsolicited events handlers in ttyACM2
[7273]: <debug> [1666700588.233411] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700588.235373] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700588.237033] [modem0/ttyACM2/at] --> 'AT+CNMI=2,1,2,0,1<CR>'
[7273]: <debug> [1666700588.271506] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.280397] [modem0] modem has time capabilities, enabling the Time interface...
[7273]: <debug> [1666700588.283573] [modem0] (cinterion) setting up time unsolicited events...
[7273]: <debug> [1666700588.288089] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700588.289184] [modem0] modem has extended signal reporting capabilities, enabling the Signal interface...
[7273]: <debug> [1666700588.289489] [modem0] extended signal information reporting disabled (rate: 0 seconds)
[7273]: <debug> [1666700588.290131] [modem0] modem has voice capabilities, enabling the Voice interface...
[7273]: <debug> [1666700588.290824] [modem0] setting voice unsolicited events handlers in ttyACM2
[7273]: <debug> [1666700588.291362] [modem0] enabling +CLIP calling line reporting in primary port...
[7273]: <debug> [1666700588.291627] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700588.292048] [modem0/ttyACM2/at] --> 'AT+CLIP=1<CR>'
[7273]: <debug> [1666700588.314651] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.315875] [modem0] enabling +CRC extended format of incoming call indications in primary port...
[7273]: <debug> [1666700588.316484] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.317156] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.317889] [modem0/ttyACM2/at] --> 'AT+CRC=1<CR>'
[7273]: <debug> [1666700588.338300] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.339519] [modem0] enabling +CCWA call waiting indications in primary port...
[7273]: <debug> [1666700588.340113] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.340793] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.341534] [modem0/ttyACM2/at] --> 'AT+CCWA=1<CR>'
[7273]: <debug> [1666700588.364019] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.365335] [modem0] (cinterion) enabling ^SLCC extended list of current calls reporting in primary port...
[7273]: <debug> [1666700588.365955] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.366708] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.367433] [modem0/ttyACM2/at] --> 'AT^SLCC=1<CR>'
[7273]: <debug> [1666700588.387859] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <info> [1666700588.389210] [modem0] simple connect state (4/8): wait to get fully enabled
[7273]: <info> [1666700588.390080] [modem0] state changed (enabling -> enabled)
[7273]: <debug> [1666700588.393733] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <info> [1666700588.395509] [modem0] simple connect state (5/8): register
[7273]: <debug> [1666700588.397129] [modem0] launching automatic network registration...
[7273]: <debug> [1666700588.398653] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700588.400490] [modem0] running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes', 5GS: 'no')
[7273]: <debug> [1666700588.401978] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.403496] [modem0/ttyACM2/at] --> 'AT+COPS=0<CR>'
[7273]: <debug> [1666700588.429641] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.436820] [modem0] running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes', 5GS: 'no')
[7273]: <debug> [1666700588.441320] [modem0/ttyACM2/at] device open count is 5 (open)
[7273]: <debug> [1666700588.448352] [modem0/ttyACM2/at] device open count is 4 (close)
[7273]: <debug> [1666700588.450839] [modem0/ttyACM2/at] --> 'AT+CREG?<CR>'
[7273]: <debug> [1666700588.468529] [modem0/ttyACM2/at] <-- '<CR><LF>+CREG: 2,5,"2AF9","02C5901",7<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <info> [1666700588.469375] [modem0] 3GPP registration state changed (unknown -> registering)
[7273]: <debug> [1666700588.469860] [modem0] loading Operator Code...
[7273]: <debug> [1666700588.470301] [modem0/ttyACM2/at] device open count is 5 (open)
[7273]: <debug> [1666700588.471433] [modem0/ttyACM2/at] device open count is 6 (open)
[7273]: <debug> [1666700588.472584] [modem0] 3GPP tracking area code updated: '000000->002AF9'
[7273]: <debug> [1666700588.473492] [modem0] 3GPP cell id updated: '00000000->002C5901'
[7273]: <debug> [1666700588.474452] [modem0] 3GPP location updated (MCCMNC: '<none>', location area code: '0000', tracking area code: '002AF9', cell ID: '002C5901')
[7273]: <debug> [1666700588.476133] [modem0/ttyACM2/at] device open count is 7 (open)
[7273]: <debug> [1666700588.477218] [modem0/ttyACM2/at] device open count is 6 (close)
[7273]: <debug> [1666700588.478432] [modem0/ttyACM2/at] --> 'AT+CREG?<CR>'
[7273]: <debug> [1666700588.497493] [modem0/ttyACM2/at] <-- '<CR><LF>+CREG: 2,5,"2AF9","02C5901",7<CR><LF><CR><LF>OK<CR><LF><CR><LF>+CIEV: psinfo,0<CR><LF>'
[7273]: <debug> [1666700588.498129] [modem0] (cinterion) received indicator 'psinfo' update: 0
[7273]: <debug> [1666700588.498795] [modem0] access technology changed (lte -> unknown)
[7273]: <debug> [1666700588.499895] [modem0/ttyACM2/at] device open count is 7 (open)
[7273]: <debug> [1666700588.500425] [modem0/ttyACM2/at] device open count is 6 (close)
[7273]: <debug> [1666700588.501568] [modem0/ttyACM2/at] --> 'AT+COPS=3,2<CR>'
[7273]: <debug> [1666700588.523810] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.525570] [modem0/ttyACM2/at] device open count is 5 (close)
[7273]: <debug> [1666700588.526773] [modem0/ttyACM2/at] --> 'AT+COPS?<CR>'
[7273]: <debug> [1666700588.544554] [modem0/ttyACM2/at] <-- '<CR><LF>+COPS: 0,2,"26001",7<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.546874] [modem0] couldn't convert operator string '26001' from charset 'UCS2': Hex conversion failed: invalid input length
[7273]: <debug> [1666700588.547970] [modem0] operator normalized '26001'->'26001'
[7273]: <debug> [1666700588.548810] [modem0] loaded Operator Code: 26001
[7273]: <debug> [1666700588.550083] [modem0] 3GPP location updated (MCCMNC: '26001', location area code: '0000', tracking area code: '002AF9', cell ID: '002C5901')
[7273]: <debug> [1666700588.551303] [modem0] loading Operator Name...
[7273]: <debug> [1666700588.552218] [modem0/ttyACM2/at] device open count is 6 (open)
[7273]: <debug> [1666700588.553330] [modem0/ttyACM2/at] device open count is 7 (open)
[7273]: <debug> [1666700588.554646] [modem0/ttyACM2/at] device open count is 6 (close)
[7273]: <debug> [1666700588.557114] [modem0/ttyACM2/at] --> 'AT+CGREG?<CR>'
[7273]: <debug> [1666700588.578456] [modem0/ttyACM2/at] <-- '<CR><LF>+CGREG: 2,5,"2AF9","02C5901",7<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.579913] [modem0] 3GPP location area code updated: '0000->2AF9'
[7273]: <debug> [1666700588.580402] [modem0] 3GPP tracking area code cleared: '002AF9->000000'
[7273]: <debug> [1666700588.580860] [modem0] 3GPP location updated (MCCMNC: '26001', location area code: '2AF9', tracking area code: '000000', cell ID: '002C5901')
[7273]: <debug> [1666700588.581990] [modem0/ttyACM2/at] device open count is 7 (open)
[7273]: <debug> [1666700588.582686] [modem0/ttyACM2/at] device open count is 6 (close)
[7273]: <debug> [1666700588.583815] [modem0/ttyACM2/at] --> 'AT+CGREG?<CR>'
[7273]: <debug> [1666700588.601725] [modem0/ttyACM2/at] <-- '<CR><LF>+CGREG: 2,5,"2AF9","02C5901",7<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.603968] [modem0/ttyACM2/at] device open count is 7 (open)
[7273]: <debug> [1666700588.604772] [modem0/ttyACM2/at] device open count is 6 (close)
[7273]: <debug> [1666700588.605637] [modem0/ttyACM2/at] --> 'AT+COPS=3,0<CR>'
[7273]: <debug> [1666700588.627085] [modem0/ttyACM2/at] <-- '<CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.628394] [modem0/ttyACM2/at] device open count is 5 (close)
[7273]: <debug> [1666700588.629303] [modem0/ttyACM2/at] --> 'AT+COPS?<CR>'
[7273]: <debug> [1666700588.648043] [modem0/ttyACM2/at] <-- '<CR><LF>+COPS: 0,0,"0050006C00750073",7<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.650285] [modem0] couldn't convert operator string '0050006C00750073' from charset 'UCS2': Invalid conversion from UCS2 to UTF-8: Couldn't convert from UCS2 to UTF-8 with translit: Could not open converter from “UCS-2BE” to “UTF-8”
[7273]: <debug> [1666700588.650990] [modem0] operator normalized '0050006C00750073'->'0050006C00750073'
[7273]: <debug> [1666700588.651499] [modem0] loaded Operator Name: 0050006C00750073
[7273]: <info> [1666700588.652396] [modem0] 3GPP registration state changed (registering -> roaming)
[7273]: <debug> [1666700588.652963] [modem0] consolidated registration state: cs 'roaming', ps 'roaming', eps 'roaming', 5gs 'unknown' --> 'roaming'
[7273]: <debug> [1666700588.654147] [modem0] will start keeping track of state for subsystem '3gpp'
[7273]: <info> [1666700588.654791] [modem0] state changed (enabled -> registered)
[7273]: <debug> [1666700588.655956] [modem0] network timezone polling started
[7273]: <debug> [1666700588.658915] [modem0] periodic signal checks enabled
[7273]: <debug> [1666700588.660600] [modem0] periodic signal check refresh requested
[7273]: <debug> [1666700588.662344] [modem0] loading signal quality...
[7273]: <debug> [1666700588.664105] [modem0/ttyACM2/at] device open count is 6 (open)
[7273]: <debug> [1666700588.665301] [modem0/ttyACM2/at] device open count is 5 (close)
[7273]: <debug> [1666700588.667001] [modem0/ttyACM2/at] --> 'AT+CEREG?<CR>'
[7273]: <debug> [1666700588.684535] [modem0/ttyACM2/at] <-- '<CR><LF>+CEREG: 2,5,"2AF9","002C5901",7<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.685683] [modem0] 3GPP tracking area code updated: '000000->002AF9'
[7273]: <debug> [1666700588.686662] [modem0] 3GPP location area code cleared: '2AF9->0000'
[7273]: <debug> [1666700588.687588] [modem0] 3GPP location updated (MCCMNC: '26001', location area code: '0000', tracking area code: '002AF9', cell ID: '002C5901')
[7273]: <debug> [1666700588.689287] [modem0] initial 3GPP registration checks finished
[7273]: <debug> [1666700588.690299] [modem0/ttyACM2/at] device open count is 4 (close)
[7273]: <debug> [1666700588.691501] [modem0/ttyACM2/at] --> 'AT+CEREG?<CR>'
[7273]: <debug> [1666700588.711729] [modem0/ttyACM2/at] <-- '<CR><LF>+CEREG: 2,5,"2AF9","002C5901",7<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.714301] [modem0] periodic signal check refresh ignored: check already running
[7273]: <debug> [1666700588.715238] [modem0] currently registered in a 3GPP network
[7273]: <info> [1666700588.716115] [modem0] simple connect state (6/8): bearer
[7273]: <debug> [1666700588.716967] [modem0] creating new bearer...
[7273]: <debug> [1666700588.717914] [modem0] (cinterion) checking ^SWWAN support...
[7273]: <debug> [1666700588.718904] [modem0/ttyACM2/at] device open count is 5 (open)
[7273]: <debug> [1666700588.720151] [modem0/ttyACM2/at] device open count is 4 (close)
[7273]: <debug> [1666700588.721703] [modem0/ttyACM2/at] --> 'AT+CIND?<CR>'
[7273]: <debug> [1666700588.739114] [modem0/ttyACM2/at] <-- '<CR><LF>+CIND: 0,3,1,1,1,1,1,0<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.740460] [modem0] signal quality updated (60)
[7273]: <debug> [1666700588.741035] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.742239] [modem0] polling to refresh access technologies is unsupported
[7273]: <debug> [1666700588.742699] [modem0] periodic signal quality and access technology checks scheduled
[7273]: <debug> [1666700588.743268] [modem0/ttyACM2/at] --> 'AT^SWWAN=?<CR>'
[7273]: <debug> [1666700588.767755] [modem0/ttyACM2/at] <-- '<CR><LF>^SWWAN: (0,1),(1-16),(1,2)<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.769656] [modem0] (cinterion) SWWAN supported
[7273]: <debug> [1666700588.770542] [modem0] (cinterion) ^SWWAN supported, creating cinterion bearer...
[7273]: <debug> [1666700588.775520] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.776620] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <info> [1666700588.779835] [modem0] simple connect state (7/8): connect
[7273]: <debug> [1666700588.782412] [modem0/bearer1] connecting...
[7273]: <info> [1666700588.784271] [modem0] state changed (registered -> connecting)
[7273]: <debug> [1666700588.787736] [modem0/bearer1] launching 3GPP connection attempt
[7273]: <debug> [1666700588.788956] [modem0] set profile state (1/8): check format
[7273]: <debug> [1666700588.791654] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.793100] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.796239] [modem0] unhandled PDP type in CGDCONT=? reply: 'PPP'
[7273]: <debug> [1666700588.796621] [modem0] +CGDCONT format details for PDP type 'ipv4': minimum 1, maximum 16
[7273]: <debug> [1666700588.796882] [modem0] context definition format: minimum 1, maximum 16
[7273]: <debug> [1666700588.797082] [modem0] set profile state (2/8): list before
[7273]: <debug> [1666700588.797535] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.797914] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.798256] [modem0/ttyACM2/at] --> 'AT+CGDCONT?<CR>'
[7273]: <debug> [1666700588.870757] [modem0/ttyACM2/at] <-- '<CR><LF>+CGDCONT: 1,"IP","internet","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 2,"IP","telenor.smart","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 3,"IP","internet","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 4,"IP","plus","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 5,"IP","IMS","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 6,"IP","internetipv6","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 7,"IP","something","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 8,"IPV6","internetipv6","0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 9,"IPV6","internet","0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 10,"IPV6","internetipv6WRONG","0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 11,"IPV6","IMS","0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 12,"IPV4V6","internet","0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 13,"IP","hos","0.0.0.0",0,0,0,0,0,0<CR><LF>+CGDCONT: 14,"IP","Hos12","0.0.0.0",0,0,0,0,0,0<CR><L
[7273]: <debug> [1666700588.876748] [modem0] set profile state (3/8): select profile (best)
[7273]: <debug> [1666700588.877271] [modem0] found exact context at profile 2
[7273]: <debug> [1666700588.877683] [modem0] reusing profile '2'
[7273]: <debug> [1666700588.878076] [modem0] set profile state (4/8): check activated profile
[7273]: <debug> [1666700588.878637] [modem0] checking if profile with id '2' is already activated...
[7273]: <debug> [1666700588.879198] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.879979] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.880880] [modem0/ttyACM2/at] --> 'AT+CGACT?<CR>'
[7273]: <debug> [1666700588.899942] [modem0/ttyACM2/at] <-- '<CR><LF>+CGACT: 1,1<CR><LF>+CGACT: 2,0<CR><LF>+CGACT: 3,0<CR><LF>+CGACT: 4,0<CR><LF>+CGACT: 5,0<CR><LF>+CGACT: 6,0<CR><LF>+CGACT: 7,0<CR><LF>+CGACT: 8,0<CR><LF>+CGACT: 9,0<CR><LF>+CGACT: 10,0<CR><LF>+CGACT: 11,0<CR><LF>+CGACT: 12,0<CR><LF>+CGACT: 13,0<CR><LF>+CGACT: 14,0<CR><LF>+CGACT: 15,0<CR><LF>+CGACT: 16,0<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700588.903787] [modem0] profile '2' is not activated
[7273]: <debug> [1666700588.904399] [modem0] set profile state (6/8): profile already stored
[7273]: <debug> [1666700588.904823] [modem0] set profile state (8/8): all done
[7273]: <debug> [1666700588.905395] [modem0/bearer1] (cinterion) dial step 1/4: authentication not required
[7273]: <debug> [1666700588.905861] [modem0/bearer1] (cinterion) dial step 2/4: starting SWWAN interface 1 connection...
[7273]: <debug> [1666700588.906488] [modem0/ttyACM2/at] device open count is 4 (open)
[7273]: <debug> [1666700588.907642] [modem0/ttyACM2/at] device open count is 3 (close)
[7273]: <debug> [1666700588.908397] [modem0/ttyACM2/at] --> 'AT^SWWAN=1,2,1<CR>'
[7273]: <debug> [1666700588.957899] [modem0/ttyACM2/at] <-- '<CR><LF>+CME ERROR: 148<CR><LF>'
[7273]: <debug> [1666700588.962765] [modem0/ttyACM2/at] operation failure: 148 (Unspecified GPRS error)
[7273]: <warn> [1666700588.965711] [modem0/bearer1] connection attempt #1 failed: Unspecified GPRS error
[7273]: <info> [1666700588.972453] [modem0] state changed (connecting -> registered)
[7273]: <info> [1666700588.974904] [modem0/bearer1] connection #1 finished: duration 0s, tx: 0 bytes, rx: 0 bytes
[7273]: <debug> [1666700588.975114] [modem0] couldn't connect bearer: Unspecified GPRS error
[7273]: <debug> [1666700588.976114] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700593.510670] [modem0/ttyACM2/at] device open count is 3 (open)
[7273]: <debug> [1666700593.514288] [modem0/ttyACM2/at] --> 'AT+CCLK?<CR>'
[7273]: <debug> [1666700593.541624] [modem0/ttyACM2/at] <-- '<CR><LF>+CCLK: "22/10/25,12:23:17+08"<CR><LF><CR><LF>OK<CR><LF>'
[7273]: <debug> [1666700593.544349] [modem0/ttyACM2/at] device open count is 2 (close)
[7273]: <debug> [1666700605.122239] [modem0/ttyACM2/at] <-- '<CR><LF>+CIEV: 1,0<CR><LF><CR><LF>+CIEV: 2,0<CR><LF><CR><LF>+CIEV: 6,0<CR><LF><CR><LF>+CIEV: 4,0<CR><LF>'
[7273]: <debug> [1666700605.124021] [modem0] signal quality updated (0)
[7273]: <debug> [1666700605.127759] [modem0/ttyACM2/at] <-- '<CR><LF>+CGEV: NW DETACH<CR><LF><CR><LF>+CREG: 2<CR><LF><CR><LF>+CGREG: 2<CR><LF><CR><LF>+CEREG: 2<CR><LF><CR><LF>+CIEV: psinfo,16<CR><LF><CR><LF>+CIEV: psinfo,0<CR><LF><CR><LF>+CIEV: psinfo,17<CR><LF>'
[7273]: <debug> [1666700605.128761] [modem0] (cinterion) received indicator 'psinfo' update: 16
[7273]: <debug> [1666700605.129648] [modem0] access technology changed (unknown -> lte)
[7273]: <debug> [1666700605.130225] [modem0] (cinterion) received indicator 'psinfo' update: 0
[7273]: <debug> [1666700605.131041] [modem0] access technology changed (lte -> unknown)
[7273]: <debug> [1666700605.131586] [modem0] (cinterion) received indicator 'psinfo' update: 17
[7273]: <debug> [1666700605.132368] [modem0] access technology changed (unknown -> lte)
[7273]: <info> [1666700605.133052] [modem0] network forced PS detach: all contexts have been deactivated
[7273]: <info> [1666700605.134936] [modem0] 3GPP registration state changed (roaming -> searching)
[7273]: <debug> [1666700605.135416] [modem0] consolidated registration state: cs 'searching', ps 'searching', eps 'searching', 5gs 'unknown' --> 'searching'
[7273]: <debug> [1666700605.136538] [modem0] 3GPP location updated (MCCMNC: '<none>', location area code: '0000', tracking area code: '002AF9', cell ID: '002C5901')
[7273]: <info> [1666700605.137593] [modem0] state changed (registered -> searching)
[7273]: <debug> [1666700605.142844] [modem0] signal quality updated (0)
[7273]: <debug> [1666700605.143240] [modem0] access technology changed (lte -> unknown)
[7273]: <debug> [1666700605.143479] [modem0] periodic signal checks disabled
[7273]: <debug> [1666700605.143726] [modem0] 3GPP location updated (MCCMNC: '<none>', location area code: '0000', tracking area code: '000000', cell ID: '00000000')
--
Arkadiusz Drabczyk <arkadiusz at drabczyk.org>
More information about the ModemManager-devel
mailing list