Hey Aleksander, hey Dan, I ran the ModemManager with debug logs and produced the same behavior as in the previous mail again.
Maybe, you can gain some knowledge from the debug log I attached. Any ideas, why the command keeps failing, but after waiting a little the state changes from “locked” to “registered”? BR Dominik Von: Aleksander Morgado <aleksande...@chromium.org> Gesendet: Mittwoch, 11. Dezember 2024 09:46 An: Dominik Nille <dominik.ni...@balluff.de> Cc: modemmanager-devel@lists.freedesktop.org Betreff: Re: SIM PIN unlock timeout Hey Dominik, I am currently trying to unlock my Quectel RM520N-GL on Linux Debian with the ModemManager (Version 1.20.4). mmcli -i any --pin=<SIMPIN> >> error: couldn't send PIN code to the SIM: 'Timeout was reached' Runs into a timeout and still manages to unlock the SIM-Card. Afterwards the state changes from “locked” to “registered”. Please run MM with debug logs (use "mmcli -G DEBUG" or follow https://modemmanager.org/docs/modemmanager/debugging/), as that will give us much more information about the specific sequence in place here. -- Aleksander Dominik Nille Technology Innovation Management [http://assets.balluff.com/JPG_original_size/E-Mail-Footer_Vertrieb_Logo.jpg] Balluff GmbH · Schurwaldstrasse 9 · 73765 Neuhausen a.d.F. · Germany Phone +497158173-8020 · Fax +4971585010 · dominik.ni...@balluff.de<mailto:dominik.ni...@balluff.de> · www.balluff.com<https://www.balluff.com> [Facebook]<https://de-de.facebook.com/balluff.gmbh> [LinkedIn] <https://www.linkedin.com/company/balluff-gmbh-germany> [Twitter] <https://twitter.com/balluff?lang=de> [Youtube] <https://www.youtube.com/user/BalluffSensors> [Xing] <https://www.xing.com/companies/balluffgmbh> [Blog] <https://www.innovating-automation.blog/?lang=de> Place of incorporation/Sitz der Gesellschaft: Neuhausen a.d.F., Germany · Register court/Registergericht: Amtsgericht Stuttgart, Germany Trade register/Handelsregister: HRB 214038 · Managing directors/Geschäftsführer: Katrin Stegmaier-Hermle, Florian Hermle, Frank Nonnenmann Chairman board of directors/Vorsitzender des Aufsichtsrats: Michael Unger · VAT ID/USt-ID: DE213 402 337 [http://assets.balluff.com/JPG_original_size/E-Mail-Footer_Vertrieb_Claim.jpg]
ModemManager[4639]: <info> [1737381534.102008] [modem1] state changed (disabled -> enabling) ModemManager[4639]: <debug> [1737381534.102609] [modem1] skipping initialization: not required ModemManager[4639]: <debug> [1737381534.102727] [ttyUSB2/at] device open count is 2 (open) ModemManager[4639]: <debug> [1737381534.102765] [ttyUSB3/at] opening serial port... ModemManager[4639]: <debug> [1737381534.103954] [ttyUSB3/at] setting up baudrate: 57600 ModemManager[4639]: <debug> [1737381534.104063] [ttyUSB3/at] no flow control explicitly requested for device ModemManager[4639]: <debug> [1737381534.104106] [ttyUSB3/at] port attributes not fully set ModemManager[4639]: <debug> [1737381534.104181] [ttyUSB3/at] device open count is 1 (open) ModemManager[4639]: <debug> [1737381534.104204] [ttyUSB3/at] running init sequence... ModemManager[4639]: <debug> [1737381534.104258] [modem1] flashing primary AT port before enabling... ModemManager[4639]: <debug> [1737381534.104365] [ttyUSB3/at] --> 'ATE0<CR>' ModemManager[4639]: <debug> [1737381534.104450] [modem1] need to check whether autoconnect is disabled or not... ModemManager[4639]: <debug> [1737381534.104542] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.104591] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.108728] [ttyUSB3/at] <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>+QUSIM: 1<CR><LF><CR><LF>+QIND: SMS DONE<CR><LF>' ModemManager[4639]: <debug> [1737381534.109129] [modem1] (quectel) checking SIM swap ModemManager[4639]: <debug> [1737381534.109191] [modem1] checking if SIM was swapped... ModemManager[4639]: <debug> [1737381534.109267] [modem1/sim1] loading SIM identifier... ModemManager[4639]: <debug> [1737381534.109412] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.109523] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.111661] [ttyUSB3/at] <-- '<CR><LF>+QIND: PB DONE<CR><LF>' ModemManager[4639]: <debug> [1737381534.112255] [ttyUSB3/at] <-- '<CR><LF>OK<CR><LF>' ModemManager[4639]: <debug> [1737381534.121070] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.121203] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.121376] [modem1] autoconnect is already disabled ModemManager[4639]: <debug> [1737381534.121496] [modem1] getting device operating mode... ModemManager[4639]: <debug> [1737381534.121599] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.121661] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.153318] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.153504] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.153761] [modem1] SIM identifier has not changed ModemManager[4639]: <debug> [1737381534.153834] [modem1/sim1] loading IMSI... ModemManager[4639]: <debug> [1737381534.154008] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.154152] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.185693] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.185855] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.186025] [modem1] no need to change power state: already 'on' ModemManager[4639]: <info> [1737381534.186063] [modem1] power state updated: on ModemManager[4639]: <debug> [1737381534.186152] [modem1] checking if SIM was swapped... ModemManager[4639]: <debug> [1737381534.186222] [modem1/sim1] loading SIM identifier... ModemManager[4639]: <debug> [1737381534.186371] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.186563] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.217441] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.217648] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.217855] [modem1] SIM imsi has not changed ModemManager[4639]: <debug> [1737381534.217905] [modem1] (quectel) check SIM swap completed ModemManager[4639]: <debug> [1737381534.248978] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.249168] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.249448] [modem1] SIM identifier has not changed ModemManager[4639]: <debug> [1737381534.249530] [modem1/sim1] loading IMSI... ModemManager[4639]: <debug> [1737381534.249704] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.249844] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.281112] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.281338] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.281610] [modem1] SIM imsi has not changed ModemManager[4639]: <debug> [1737381534.281685] [modem1] modem has 3GPP capabilities, enabling the Modem 3GPP interface... ModemManager[4639]: <debug> [1737381534.282072] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.285538] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.313316] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.313449] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.313640] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.313713] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.345177] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.345314] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.345479] [modem1] signal info indications enabled: polling disabled ModemManager[4639]: <debug> [1737381534.345636] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.345741] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.376860] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.377007] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.377462] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.377619] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.408988] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.409257] [/dev/cdc-wdm0] received generic indication (translated)... ModemManager[4639]: <debug> [1737381534.409693] [modem1] RSSI (LTE): -75 dBm ModemManager[4639]: <debug> [1737381534.409755] [modem1] RSRP (5G): -113 dBm ModemManager[4639]: <debug> [1737381534.409786] [modem1] SNR (5G): 11 dB ModemManager[4639]: <debug> [1737381534.409816] [modem1] RSRQ (5G): -12 dB ModemManager[4639]: <debug> [1737381534.409845] [modem1] RSSI: -75 dBm --> 62% ModemManager[4639]: <debug> [1737381534.410046] [modem1] signal quality updated (62) ModemManager[4639]: <debug> [1737381534.441468] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.441614] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.442187] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.442307] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.473100] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.473248] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.473537] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.473650] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.504845] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.505098] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.505423] [modem1] updating initial EPS bearer... ModemManager[4639]: <debug> [1737381534.506542] [modem1/bearer0] bearer not allowed to connect, not registered in 3GPP network ModemManager[4639]: <debug> [1737381534.507011] [modem1] modem has 3GPP profile management capabilities, enabling the Modem 3GPP Profile Manager interface... ModemManager[4639]: <debug> [1737381534.507211] [modem1] set up profile events handler ModemManager[4639]: <debug> [1737381534.507752] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.507879] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.536855] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.536998] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.537153] [modem1] registered for refresh events ModemManager[4639]: <debug> [1737381534.537210] [modem1] modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface... ModemManager[4639]: <debug> [1737381534.537510] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.537625] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.569034] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.569247] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.569611] [modem1] modem has location capabilities, enabling the Location interface... ModemManager[4639]: <debug> [1737381534.569731] [modem1] location 'gps-raw' gathering is already disabled... ModemManager[4639]: <debug> [1737381534.569780] [modem1] location 'gps-nmea' gathering is already disabled... ModemManager[4639]: <debug> [1737381534.569820] [modem1] location 'agps-msa' gathering is already disabled... ModemManager[4639]: <debug> [1737381534.569854] [modem1] location 'agps-msb' gathering is already disabled... ModemManager[4639]: <debug> [1737381534.569882] [modem1] need to enable the following location sources: '3gpp-lac-ci' ModemManager[4639]: <debug> [1737381534.570091] [modem1] running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes', 5GS: 'yes') ModemManager[4639]: <debug> [1737381534.570228] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.570300] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.571267] [modem1] modem has messaging capabilities, enabling the Messaging interface... ModemManager[4639]: <debug> [1737381534.572149] [modem1] setting default messaging routes... ModemManager[4639]: <debug> [1737381534.572299] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.574063] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.601000] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.601519] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.601989] [modem1] no NR5G service reported ModemManager[4639]: <debug> [1737381534.602081] [modem1] fallback PS registration state to idle: DSD data RAT unavailable ModemManager[4639]: <debug> [1737381534.602141] [modem1] 3GPP registration state change ignored as modem isn't enabled ModemManager[4639]: <debug> [1737381534.602296] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.602429] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.632980] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.633128] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.633404] [modem1] loading all 3GPP messages from storage 'sm'... ModemManager[4639]: <debug> [1737381534.633520] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.633783] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.665404] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.665798] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.666169] [modem1] updating PS registration state: DSD data RAT available ModemManager[4639]: <debug> [1737381534.666291] [modem1] 3GPP registration state change ignored as modem isn't enabled ModemManager[4639]: <debug> [1737381534.697302] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.697439] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.697613] [modem1] couldn't read SMS messages: QMI protocol error (17): 'MissingArgument' ModemManager[4639]: <debug> [1737381534.697674] [modem1] loading 3GPP MT-read messages from storage 'sm'... ModemManager[4639]: <debug> [1737381534.697780] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.697889] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.728851] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.728992] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.729159] [modem1] couldn't read SMS messages: QMI protocol error (47): 'UnknownError' ModemManager[4639]: <debug> [1737381534.729213] [modem1] loading 3GPP MT-not-read messages from storage 'sm'... ModemManager[4639]: <debug> [1737381534.729350] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.729590] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.761571] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.761730] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.761950] [modem1] loading 3GPP MO-sent messages from storage 'sm'... ModemManager[4639]: <debug> [1737381534.762084] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.762191] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.792852] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.792986] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.793117] [modem1] couldn't read SMS messages: QMI protocol error (48): 'InvalidArgument' ModemManager[4639]: <debug> [1737381534.793155] [modem1] loading 3GPP MO-not-sent messages from storage 'sm'... ModemManager[4639]: <debug> [1737381534.793240] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.793331] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.824872] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.825016] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.825180] [modem1] couldn't read SMS messages: QMI protocol error (48): 'InvalidArgument' ModemManager[4639]: <debug> [1737381534.825288] [modem1] loading all 3GPP messages from storage 'me'... ModemManager[4639]: <debug> [1737381534.825376] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.825482] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.857146] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.857284] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.857468] [modem1] couldn't read SMS messages: QMI protocol error (17): 'MissingArgument' ModemManager[4639]: <debug> [1737381534.857510] [modem1] loading 3GPP MT-read messages from storage 'me'... ModemManager[4639]: <debug> [1737381534.857602] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.857734] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.889153] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.889300] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.889474] [modem1] couldn't read SMS messages: QMI protocol error (47): 'UnknownError' ModemManager[4639]: <debug> [1737381534.889525] [modem1] loading 3GPP MT-not-read messages from storage 'me'... ModemManager[4639]: <debug> [1737381534.889653] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.889754] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.921152] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.921307] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.921539] [modem1] loading 3GPP MO-sent messages from storage 'me'... ModemManager[4639]: <debug> [1737381534.921662] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.921773] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.952749] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.952882] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.953017] [modem1] couldn't read SMS messages: QMI protocol error (48): 'InvalidArgument' ModemManager[4639]: <debug> [1737381534.953067] [modem1] loading 3GPP MO-not-sent messages from storage 'me'... ModemManager[4639]: <debug> [1737381534.953153] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.953244] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381534.984860] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381534.985003] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381534.985156] [modem1] couldn't read SMS messages: QMI protocol error (48): 'InvalidArgument' ModemManager[4639]: <debug> [1737381534.985320] [modem1] setting messaging unsolicited events handlers in ttyUSB2 ModemManager[4639]: <debug> [1737381534.985383] [modem1] setting messaging unsolicited events handlers in ttyUSB3 ModemManager[4639]: <debug> [1737381534.985680] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381534.985801] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381535.017373] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381535.017518] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381535.018068] [modem1] modem has time capabilities, enabling the Time interface... ModemManager[4639]: <debug> [1737381535.018298] [modem1] modem has extended signal reporting capabilities, enabling the Signal interface... ModemManager[4639]: <debug> [1737381535.018357] [modem1] reseting extended signal information... ModemManager[4639]: <debug> [1737381535.018535] [modem1] cleaning up extended signal information polling: interface enabled, rate 0 seconds ModemManager[4639]: <debug> [1737381535.018581] [modem1] cleaning up extended signal information thresholds: interface enabled, rssi threshold 0 dBm, error rate threshold disabled ModemManager[4639]: <debug> [1737381535.018723] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381535.018855] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381535.049140] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381535.049282] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381535.049450] [modem1] modem has voice capabilities, enabling the Voice interface... ModemManager[4639]: <debug> [1737381535.049695] [modem1] setting voice unsolicited events handlers in ttyUSB2 ModemManager[4639]: <debug> [1737381535.049782] [modem1] setting voice unsolicited events handlers in ttyUSB3 ModemManager[4639]: <debug> [1737381535.050145] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381535.050262] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381535.080829] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381535.080968] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <info> [1737381535.081403] [modem1] state changed (enabling -> enabled) ModemManager[4639]: <debug> [1737381535.082069] [modem1] running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes', 5GS: 'yes') ModemManager[4639]: <debug> [1737381535.082240] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381535.082322] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381535.113177] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381535.113625] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381535.114295] [modem1] no NR5G service reported ModemManager[4639]: <debug> [1737381535.114469] [modem1] updating PS registration state: DSD data RAT available ModemManager[4639]: <info> [1737381535.114553] [modem1] 3GPP registration state changed (unknown -> registering) ModemManager[4639]: <debug> [1737381535.114762] [modem1] access technology changed (unknown -> lte, 5gnr) ModemManager[4639]: <debug> [1737381535.114835] [modem1] 3GPP tracking area code updated: '000000->0067B6' ModemManager[4639]: <debug> [1737381535.114872] [modem1] 3GPP cell id updated: '00000000->01A38C00' ModemManager[4639]: <debug> [1737381535.114913] [modem1] 3GPP location updated (MCCMNC: '<none>', location area code: '0000', tracking area code: '0067B6', cell ID: '01A38C00') ModemManager[4639]: <debug> [1737381535.115053] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381535.115123] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381535.115442] [modem1] 3GPP location updated (MCCMNC: '26201', location area code: '0000', tracking area code: '0067B6', cell ID: '01A38C00') ModemManager[4639]: <debug> [1737381535.115634] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381535.115727] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381535.145819] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381535.146322] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381535.146718] [modem1] updating PS registration state: DSD data RAT available ModemManager[4639]: <debug> [1737381535.146879] [modem1] initial 3GPP registration checks finished ModemManager[4639]: <debug> [1737381535.177497] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381535.177766] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381535.178070] [modem1] current operator long name: Telekom.de ModemManager[4639]: <debug> [1737381535.178132] [modem1] current operator short name: TDG ModemManager[4639]: <debug> [1737381535.178169] [modem1] current operator service name: Telekom.de ModemManager[4639]: <info> [1737381535.178313] [modem1] 3GPP registration state changed (registering -> home) ModemManager[4639]: <debug> [1737381535.178347] [modem1] consolidated registration state: cs 'home', ps 'home', eps 'unknown', 5gs 'home' --> 'home' ModemManager[4639]: <debug> [1737381535.178548] [modem1] will start keeping track of state for subsystem '3gpp' ModemManager[4639]: <info> [1737381535.178619] [modem1] state changed (enabled -> registered) ModemManager[4639]: <debug> [1737381535.178773] [modem1] network timezone polling started ModemManager[4639]: <debug> [1737381535.179413] [modem1] periodic signal checks enabled ModemManager[4639]: <debug> [1737381535.179488] [modem1] periodic signal check refresh requested ModemManager[4639]: <debug> [1737381535.179554] [modem1] loading signal quality... ModemManager[4639]: <debug> [1737381535.179652] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381535.179717] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381535.208878] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381535.209112] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381535.209405] [modem1] RSSI (LTE): -75 dBm ModemManager[4639]: <debug> [1737381535.209462] [modem1] RSRP (5G): -113 dBm ModemManager[4639]: <debug> [1737381535.209496] [modem1] SNR (5G): 11 dB ModemManager[4639]: <debug> [1737381535.209524] [modem1] RSRQ (5G): -12 dB ModemManager[4639]: <debug> [1737381535.209552] [modem1] RSSI: -75 dBm --> 62% ModemManager[4639]: <debug> [1737381535.209736] [modem1] signal quality updated (62) ModemManager[4639]: <debug> [1737381535.209804] [modem1] periodic signal quality and access technology checks scheduled ModemManager[4639]: <debug> [1737381535.914955] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381535.915066] [modem0] retrying (10) unlock required check ModemManager[4639]: <debug> [1737381536.649095] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381536.649269] [/dev/cdc-wdm0] received generic indication (translated)... ModemManager[4639]: <debug> [1737381536.649502] [modem1] data systems update, preferred network: 3gpp ModemManager[4639]: <debug> [1737381536.649676] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381536.649759] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381536.681473] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381536.681867] [/dev/cdc-wdm0] received generic indication (translated)... ModemManager[4639]: <debug> [1737381536.682209] [modem1] updating PS registration state: DSD data RAT available ModemManager[4639]: <debug> [1737381536.713249] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381536.713431] [/dev/cdc-wdm0] received generic indication (translated)... ModemManager[4639]: <debug> [1737381536.713665] [modem1] RSSI (LTE): -78 dBm ModemManager[4639]: <debug> [1737381536.713721] [modem1] RSSI: -78 dBm --> 57% ModemManager[4639]: <debug> [1737381536.713914] [modem1] signal quality updated (57) ModemManager[4639]: <debug> [1737381536.745548] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381536.745927] [/dev/cdc-wdm0] received generic indication (translated)... ModemManager[4639]: <debug> [1737381536.746568] [modem1] no NR5G service reported ModemManager[4639]: <debug> [1737381536.746696] [modem1] updating PS registration state: DSD data RAT available ModemManager[4639]: <debug> [1737381536.777171] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381536.777569] [/dev/cdc-wdm0] received generic indication (translated)... ModemManager[4639]: <debug> [1737381536.777871] [modem1] updating PS registration state: DSD data RAT available ModemManager[4639]: <debug> [1737381536.809236] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381536.809447] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381536.841639] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381536.842009] [/dev/cdc-wdm0] received generic indication (translated)... ModemManager[4639]: <debug> [1737381536.842306] [modem1] updating PS registration state: DSD data RAT available ModemManager[4639]: <debug> [1737381536.873933] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381536.874101] [/dev/cdc-wdm0] received generic indication (translated)... ModemManager[4639]: <debug> [1737381536.874262] [modem1] data systems update, preferred network: 3gpp ModemManager[4639]: <debug> [1737381536.874432] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381536.874533] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381536.905647] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381536.906007] [/dev/cdc-wdm0] received generic indication (translated)... ModemManager[4639]: <debug> [1737381536.906329] [modem1] updating PS registration state: DSD data RAT available ModemManager[4639]: <debug> [1737381536.937721] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381536.937929] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381537.915599] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381537.915725] [modem0] retrying (11) unlock required check ModemManager[4639]: <debug> [1737381539.914930] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381539.915044] [modem0] retrying (12) unlock required check ModemManager[4639]: <debug> [1737381540.914621] [ttyUSB2/at] device open count is 3 (open) ModemManager[4639]: <debug> [1737381540.914846] [ttyUSB2/at] --> 'AT+CCLK?<CR>' ModemManager[4639]: <debug> [1737381540.927507] [ttyUSB2/at] <-- '<CR><LF>+CCLK: "25/01/20,13:58:59+04"<CR><LF><CR><LF>OK<CR><LF>' ModemManager[4639]: <debug> [1737381540.928812] [ttyUSB2/at] device open count is 2 (close) ModemManager[4639]: <debug> [1737381541.914698] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381541.914819] [modem0] retrying (13) unlock required check ModemManager[4639]: <debug> [1737381543.916112] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381543.916234] [modem0] retrying (14) unlock required check ModemManager[4639]: <debug> [1737381545.916625] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381545.916748] [modem0] retrying (15) unlock required check ModemManager[4639]: <debug> [1737381547.916616] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381547.916733] [modem0] retrying (16) unlock required check ModemManager[4639]: <debug> [1737381549.916618] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381549.916736] [modem0] retrying (17) unlock required check ModemManager[4639]: <debug> [1737381551.914924] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381551.915042] [modem0] retrying (18) unlock required check ModemManager[4639]: <debug> [1737381553.916913] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381553.917018] [modem0] retrying (19) unlock required check ModemManager[4639]: <debug> [1737381555.916618] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381555.916738] [modem0] retrying (20) unlock required check ModemManager[4639]: <debug> [1737381557.914734] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381557.914858] [modem0] retrying (21) unlock required check ModemManager[4639]: <debug> [1737381558.120900] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381558.121109] [/dev/cdc-wdm0] received generic indication (translated)... ModemManager[4639]: <debug> [1737381558.121341] [modem1] RSSI (LTE): -72 dBm ModemManager[4639]: <debug> [1737381558.121400] [modem1] RSSI: -72 dBm --> 67% ModemManager[4639]: <debug> [1737381558.121611] [modem1] signal quality updated (67) ModemManager[4639]: <debug> [1737381559.914951] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381559.915068] [modem0] retrying (22) unlock required check ModemManager[4639]: <debug> [1737381561.914939] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381561.915056] [modem0] retrying (23) unlock required check ModemManager[4639]: <debug> [1737381563.916606] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381563.916722] [modem0] retrying (24) unlock required check ModemManager[4639]: <debug> [1737381565.916854] [modem1] loading signal quality... ModemManager[4639]: <debug> [1737381565.917047] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381565.917138] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381565.917359] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381565.917425] [modem0] retrying (25) unlock required check ModemManager[4639]: <debug> [1737381565.928933] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381565.929142] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381565.929372] [modem1] RSSI (LTE): -75 dBm ModemManager[4639]: <debug> [1737381565.929426] [modem1] RSSI: -75 dBm --> 62% ModemManager[4639]: <debug> [1737381565.929647] [modem1] signal quality updated (62) ModemManager[4639]: <debug> [1737381565.929714] [modem1] periodic signal quality and access technology checks scheduled ModemManager[4639]: <debug> [1737381567.914770] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381567.914894] [modem0] retrying (26) unlock required check ModemManager[4639]: <debug> [1737381569.916617] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381569.916737] [modem0] retrying (27) unlock required check ModemManager[4639]: <debug> [1737381571.914985] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381571.915107] [modem0] retrying (28) unlock required check ModemManager[4639]: <debug> [1737381573.914991] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381573.915110] [modem0] retrying (29) unlock required check ModemManager[4639]: <debug> [1737381575.916912] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381575.917031] [modem0] retrying (30) unlock required check ModemManager[4639]: <debug> [1737381577.915048] [modem0] couldn't check if unlock required: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381577.915186] [modem0] skipping SIM error in non 3GPP-capable device, assuming no lock is needed ModemManager[4639]: <debug> [1737381577.915238] [modem0] SIM is ready, and no need for the after SIM unlock step... ModemManager[4639]: <debug> [1737381577.915276] [modem0] loading unlock retries... ModemManager[4639]: <warn> [1737381577.915389] [modem0] couldn't load unlock retries: Couldn't peek QMI port ModemManager[4639]: <debug> [1737381577.915973] [modem0] completely disposed ModemManager[4639]: <debug> [1737381595.914872] [modem1] loading signal quality... ModemManager[4639]: <debug> [1737381595.915070] [/dev/cdc-wdm0] sent message... ModemManager[4639]: <debug> [1737381595.915303] [/dev/cdc-wdm0] sent generic request (translated)... ModemManager[4639]: <debug> [1737381595.945140] [/dev/cdc-wdm0] received message... ModemManager[4639]: <debug> [1737381595.945570] [/dev/cdc-wdm0] received generic response (translated)... ModemManager[4639]: <debug> [1737381595.945920] [modem1] RSSI (LTE): -77 dBm ModemManager[4639]: <debug> [1737381595.945993] [modem1] RSSI: -77 dBm --> 59% ModemManager[4639]: <debug> [1737381595.946262] [modem1] signal quality updated (59) ModemManager[4639]: <debug> [1737381595.946335] [modem1] periodic signal quality and access technology checks scheduled ^CModemManager[4639]: <info> [1737381603.333962] caught signal, shutting down... ModemManager[4639]: <debug> [1737381603.334079] [base-manager] stopping connection in object manager server ModemManager[4639]: <info> [1737381603.334573] [modem1] state changed (registered -> disabling) ModemManager[4639]: <debug> [1737381603.334902] [modem1] signal quality updated (0) ModemManager[4639]: <debug> [1737381603.335024] [modem1] access technology changed (lte, 5gnr -> unknown) ModemManager[4639]: <debug> [1737381603.335076] [modem1] periodic signal checks disabled
