MM 1.18.12: Cinterion ELS62-W on Eval carrier: a simple-connect reports an error
When trying to connect (simple-connect) an error is reported. Nevertheless the network works and traffic is transmitted.
$ mmcli -m 1
-----------------------------
General | path: /org/freedesktop/ModemManager1/Modem/1
| device id: 58492f826e6b50fc24d0c1f61b32f1fea5c66f6c
-----------------------------
Hardware | manufacturer: Cinterion
| model: ELS62-W
| firmware revision: REVISION 00.816
| supported: gsm-umts, lte
| current: gsm-umts, lte
| equipment id: 004401084542329
-----------------------------
System | device: /sys/devices/platform/soc@0/32c00000.bus/32e40000.usb/ci_hdrc.0/usb1/1-1
| drivers: cdc_acm, cdc_ether
| plugin: cinterion
| primary port: ttyACM0
| ports: eth1 (net), ttyACM0 (at), ttyACM1 (at), ttyACM2 (at)
-----------------------------
Status | lock: sim-pin
| state: locked
| power state: on
-----------------------------
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
-----------------------------
IP | supported: ipv4, ipv6, ipv4v6
-----------------------------
SIM | primary sim path: /org/freedesktop/ModemManager1/SIM/0$ mmcli -m 1 --sim 0 --pin xxxx
Note: replaced pin with xxxx in the log
Click to expand
2019-02-14T10:20:22.292467+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (open)
2019-02-14T10:20:22.293080+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CPIN="xxxx"<CR>'
2019-02-14T10:20:22.637924+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:22.638578+00:00 me ModemManager[450]: <debug> [modem1] checking if unlock required...
2019-02-14T10:20:22.638899+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:22.639151+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:22.639378+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CPIN?<CR>'
2019-02-14T10:20:22.964944+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:20:22.965542+00:00 me ModemManager[450]: <debug> [modem1] SIM is ready, running after SIM unlock step...
2019-02-14T10:20:22.965844+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:22.966105+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:22.966308+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SIND=?<CR>'
2019-02-14T10:20:22.979874+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>'
2019-02-14T10:20:22.980727+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SIND: (ceer,(0-3,6-8,99)),(euiccid,(32)),(iccid,(20)),(imsi,(15)),(is_cert,(0-1)),(lsta,(0-2,11,21)),(nitz,(17)),(psinfo,(0-4,16,17)),(roam,(0-1)),(rssi,(0-5,99)),(service,(0-1)),(simdata,(0,1)),(simstatus,(0,1,3,5)),(simtray,(0-1)),(smsfull,(0-1)),(0-2)<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:20:22.981203+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) psinfo support? yes
2019-02-14T10:20:22.981823+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) simstatus support? yes
2019-02-14T10:20:22.982240+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:22.982553+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:22.982819+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SIND="simstatus",2<CR>'
2019-02-14T10:20:23.010250+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>^SIND: simstatus,0,5<CR><LF>'
2019-02-14T10:20:23.010818+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.011372+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:23.011706+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:23.011904+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SPIC="SC"<CR>'
2019-02-14T10:20:23.032112+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>ERROR<CR><LF>'
2019-02-14T10:20:23.032680+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:23.033104+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) Couldn't load retry count for lock 'sim-pin': Unknown error
2019-02-14T10:20:23.033382+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:23.033724+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:23.034251+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SPIC="SC",1<CR>'
2019-02-14T10:20:23.057379+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>ERROR<CR><LF>'
2019-02-14T10:20:23.057942+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:23.058374+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) Couldn't load retry count for lock 'sim-puk': Unknown error
2019-02-14T10:20:23.058782+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:23.058992+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:23.059216+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SPIC="P2"<CR>'
2019-02-14T10:20:23.077698+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>ERROR<CR><LF>'
2019-02-14T10:20:23.078355+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:23.078641+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) Couldn't load retry count for lock 'sim-pin2': Unknown error
2019-02-14T10:20:23.078809+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:23.079456+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:23.079848+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SPIC="P2",1<CR>'
2019-02-14T10:20:23.102187+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>ERROR<CR><LF>'
2019-02-14T10:20:23.102721+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:23.102978+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) Couldn't load retry count for lock 'sim-puk2': Unknown error
2019-02-14T10:20:23.103270+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:23.103493+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:23.103722+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SPIC="PS"<CR>'
2019-02-14T10:20:23.123651+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>ERROR<CR><LF>'
2019-02-14T10:20:23.124271+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:23.124517+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) Couldn't load retry count for lock 'ph-fsim-pin': Unknown error
2019-02-14T10:20:23.124677+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:23.124862+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:23.125142+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SPIC="PS",1<CR>'
2019-02-14T10:20:23.148311+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>ERROR<CR><LF>'
2019-02-14T10:20:23.148859+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:23.149217+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) Couldn't load retry count for lock 'ph-fsim-puk': Unknown error
2019-02-14T10:20:23.149444+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:23.149684+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:23.149886+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SPIC="PN"<CR>'
2019-02-14T10:20:23.171206+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>ERROR<CR><LF>'
2019-02-14T10:20:23.171687+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:23.171917+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) Couldn't load retry count for lock 'ph-net-pin': Unknown error
2019-02-14T10:20:23.172183+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:23.172366+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:23.172574+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SPIC="PN",1<CR>'
2019-02-14T10:20:23.196202+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>ERROR<CR><LF>'
2019-02-14T10:20:23.196782+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:23.197471+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) Couldn't load retry count for lock 'ph-net-puk': Unknown error
2019-02-14T10:20:23.197979+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 1 (close)
2019-02-14T10:20:23.200364+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (open)
2019-02-14T10:20:23.200964+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:23.203524+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.204357+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.204946+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CSCS="UCS2"<CR>'
2019-02-14T10:20:23.226945+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.227588+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.227833+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.228365+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CSCS?<CR>'
2019-02-14T10:20:23.243704+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.244414+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) Using cinterion default modem family
2019-02-14T10:20:23.245168+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.245745+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.246394+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SCFG=?<CR>'
2019-02-14T10:20:23.260400+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>'
2019-02-14T10:20:23.261437+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Radio/Band/2G",("00000001-0000000f"),,("0","1")<CR><LF>'
2019-02-14T10:20:23.261971+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Radio/Band/4G",("00000001-080800df"),("0000000000000020-00000002000001a0"),("0","1")<CR><LF>'
2019-02-14T10:20:23.262499+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPRS/AutoAttach",("disabled","enabled")<CR><LF>^SCFG: "MEopMode/PingRsp",("0","1")<CR><LF>'
2019-02-14T10:20:23.262935+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Tcp/IRT",("1-60")<CR><LF>'
2019-02-14T10:20:23.263352+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Tcp/MR",("1-30")<CR><LF>^SCFG: "Tcp/OT",("0-6000")<CR><LF>'
2019-02-14T10:20:23.263626+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Tcp/TLS/Version",("MIN","1.1","1.2","1.3","MAX"),("1.2","1.3","MAX")<CR><LF>'
2019-02-14T10:20:23.263908+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Tcp/WithURCs",("on","off")<CR><LF>'
2019-02-14T10:20:23.264258+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Audio/Loop",("0","1")<CR><LF>^SCFG: "Call/Speech/Codec",("0","2")<CR><LF>'
2019-02-14T10:20:23.264634+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/DAI",("gpio","std","rsv")<CR><LF>'
2019-02-14T10:20:23.265122+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/MCLK",("gpio","std","rsv")<CR><LF>^SCFG: "GPIO/Mode/ASC1",("gpio","std","rsv")<CR><LF>^SCFG: "GPIO/Mode/DCD0",("gpio","std")<CR><LF>^SCFG: "GPIO/Mode/DSR0",("gpio","std")<CR><LF>'
2019-02-14T10:20:23.265775+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/DTR0",("gpio","std")<CR><LF>^SCFG: "GPIO/Mode/FNS",("gpio","std","rsv")<CR><LF>'
2019-02-14T10:20:23.266492+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/FSR",("gpio","std","rsv")<CR><LF>^SCFG: "GPIO/Mode/RING0",("gpio","std")<CR><LF>^SCFG: "GPIO/Mode/SYNC",("gpio","std","rsv")<CR><LF>'
2019-02-14T10:20:23.267230+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPRS/MTU/Mode",("0-1")<CR><LF>^SCFG: "GPRS/MTU/Size",("1280-1500")<CR><LF>^SCFG: "Ident/Manufacturer",(25)<CR><LF>'
2019-02-14T10:20:23.268053+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Ident/Product",(25)<CR><LF>^SCFG: "MEopMode/IMS",("0","1")<CR><LF>^SCFG: "MEShutdown/sVsup/threshold",("-4"-"4"),("0")<CR><LF>^SCFG: "Radio/Mtpl",("0-1"),("1-8")<CR><LF>^SCFG: "Radio/Mtpl/2G",("2-3"),("1-8"),("1-8"),,("18-33"),("18-27")<CR><LF>'
2019-02-14T10:20:23.268853+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Radio/Mtpl/4G",("2-3"),("1-8"),("0-8000000"),("0-200000000"),("18-23")<CR><LF>^SCFG: "Radio/OutputPowerReduction",("0"-"4")<CR><LF>^SCFG: "MEopMode/RingOnData",("off","on")<CR><LF>^SCFG: "Serial/USB/DDD",("0-1"),("0"),("0001-ffff"),("0001-ffff"),("0001-ffff"),(63),(63),(8)<CR><LF>'
2019-02-14T10:20:23.288777+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "SIM/CS",("SIM1","SIM3")<CR><LF>^SCFG: "SIM/DualMode",("0","2")<CR><LF>'
2019-02-14T10:20:23.289362+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "URC/Ringline",("off","local","asc0")<CR><LF>^SCFG: "URC/Ringline/ActiveTime",("0","1","2")<CR><LF>^SCFG: "URC/Ringline/SelWUrc",("all","RING","+CMT")<CR><LF>'
2019-02-14T10:20:23.290740+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.293543+00:00 me ModemManager[450]: <debug> [modem1/sim0] loading IMSI...
2019-02-14T10:20:23.293818+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.294079+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.294291+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CIMI<CR>'
2019-02-14T10:20:23.310718+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>262075158032155<CR><LF>'
2019-02-14T10:20:23.311334+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.311665+00:00 me ModemManager[450]: <debug> [modem1/sim0] loaded IMSI: 262075158032155
2019-02-14T10:20:23.312487+00:00 me ModemManager[450]: <debug> [modem1/sim0] loading operator ID...
2019-02-14T10:20:23.312964+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.313337+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.313557+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CRSM=176,28589,0,0,4<CR>'
2019-02-14T10:20:23.355984+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CRSM: 144,0,"01000102"<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.356527+00:00 me ModemManager[450]: <debug> [modem1/sim0] loading operator name...
2019-02-14T10:20:23.356940+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.357416+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.357758+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CRSM=176,28486,0,0,17<CR>'
2019-02-14T10:20:23.402186+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CRSM: 144,0,"02FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF"<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.403149+00:00 me ModemManager[450]: <debug> [modem1/sim0] loading emergency numbers...
2019-02-14T10:20:23.403962+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.404373+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.404701+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CRSM=176,28599,0,0,15<CR>'
2019-02-14T10:20:23.448971+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CRSM: 105,129<CR><LF>'
2019-02-14T10:20:23.449614+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.450146+00:00 me ModemManager[450]: <debug> [modem1/sim0] selecting user-defined preferred network list...
2019-02-14T10:20:23.450743+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.451183+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.451864+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CPLS=0<CR>'
2019-02-14T10:20:23.468639+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
2019-02-14T10:20:23.469170+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:23.469410+00:00 me ModemManager[450]: <debug> [modem1/sim0] selecting user-defined preferred network list failed: 'Unknown error'
2019-02-14T10:20:23.469570+00:00 me ModemManager[450]: <debug> [modem1/sim0] setting preferred networks format...
2019-02-14T10:20:23.469789+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.469971+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.470360+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CPOL=,2<CR>'
2019-02-14T10:20:23.488794+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.489387+00:00 me ModemManager[450]: <debug> [modem1/sim0] loading preferred networks...
2019-02-14T10:20:23.489671+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.489839+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.490093+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CPOL?<CR>'
2019-02-14T10:20:23.506829+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.507356+00:00 me ModemManager[450]: <debug> [modem1/sim0] loaded 0 preferred networks
2019-02-14T10:20:23.507660+00:00 me ModemManager[450]: <debug> [modem1] loading own numbers...
2019-02-14T10:20:23.507999+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.508395+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.508631+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CNUM<CR>'
2019-02-14T10:20:23.528269+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CME ERROR: 4<CR><LF>'
2019-02-14T10:20:23.528761+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 4 (Operation not supported)
2019-02-14T10:20:23.529450+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.529669+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.529937+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SCFG?<CR>'
2019-02-14T10:20:23.545694+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>^SCFG: "Radio/Band/2G","0000000f"<CR><LF>'
2019-02-14T10:20:23.546572+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Radio/Band/4G","080800df","00000002000001a0"<CR><LF>'
2019-02-14T10:20:23.547320+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPRS/AutoAttach","enabled"<CR><LF>'
2019-02-14T10:20:23.547962+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "MEopMode/PingRsp","0"<CR><LF>'
2019-02-14T10:20:23.565157+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Tcp/IRT","3"<CR><LF>'
2019-02-14T10:20:23.581851+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Tcp/MR","10"<CR><LF>'
2019-02-14T10:20:23.597840+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Tcp/OT","6000"<CR><LF>'
2019-02-14T10:20:23.622222+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Tcp/TLS/Version","MIN","MAX"<CR><LF>'
2019-02-14T10:20:23.630741+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Tcp/WithURCs","on"<CR><LF>'
2019-02-14T10:20:23.631293+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Trace/Jti","0"<CR><LF>'
2019-02-14T10:20:23.631597+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Audio/Loop","0"<CR><LF>'
2019-02-14T10:20:23.632703+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Call/Speech/Codec","0"<CR><LF>'
2019-02-14T10:20:23.656249+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/DAI","std"<CR><LF>'
2019-02-14T10:20:23.678600+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/MCLK","gpio"<CR><LF>'
2019-02-14T10:20:23.701617+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/ASC1","std"<CR><LF>'
2019-02-14T10:20:23.716351+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/DCD0","std"<CR><LF>'
2019-02-14T10:20:23.730760+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/DSR0","std"<CR><LF>'
2019-02-14T10:20:23.744649+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/DTR0","std"<CR><LF>'
2019-02-14T10:20:23.761776+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/FNS","gpio"<CR><LF>'
2019-02-14T10:20:23.783462+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/FSR","gpio"<CR><LF>'
2019-02-14T10:20:23.797117+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/RING0","std"<CR><LF>'
2019-02-14T10:20:23.810224+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPIO/Mode/SYNC","std"<CR><LF>'
2019-02-14T10:20:23.814580+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPRS/MTU/Mode","0"<CR><LF>'
2019-02-14T10:20:23.820064+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "GPRS/MTU/Size",1430<CR><LF>'
2019-02-14T10:20:23.820992+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Ident/Manufacturer","Cinterion"<CR><LF>'
2019-02-14T10:20:23.822130+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Ident/Product","ELS62-W"<CR><LF>'
2019-02-14T10:20:23.822913+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "MEopMode/IMS","1"<CR><LF>^SCFG: "MEShutdown/sVsup/threshold","0","0"<CR><LF>'
2019-02-14T10:20:23.823506+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Radio/Mtpl","0"<CR><LF>'
2019-02-14T10:20:23.824079+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Radio/Mtpl/2G","0"<CR><LF>'
2019-02-14T10:20:23.824750+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Radio/Mtpl/4G","0"<CR><LF>'
2019-02-14T10:20:23.825638+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Radio/OutputPowerReduction","4"<CR><LF>'
2019-02-14T10:20:23.838304+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "MEopMode/RingOnData","off"<CR><LF>'
2019-02-14T10:20:23.841101+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "Serial/USB/DDD","0","0","0409","1E2D","00D0","Cinterion Wireless Modules","ELS62","20080600"<CR><LF>'
2019-02-14T10:20:23.859288+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "SIM/CS","SIM1"<CR><LF>'
2019-02-14T10:20:23.876999+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '^SCFG: "SIM/DualMode","0"<CR><LF>^SCFG: "URC/Ringline","local"<CR><LF>^SCFG: "URC/Ringline/ActiveTime","2"<CR><LF>^SCFG: "URC/Ringline/SelWUrc","all"<CR><LF>'
2019-02-14T10:20:23.877491+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.879579+00:00 me ModemManager[450]: <debug> [modem1] loading enabled facility locks...
2019-02-14T10:20:23.879895+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.880123+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.880320+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CLCK=?<CR>'
2019-02-14T10:20:23.901894+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CLCK: ("CS", "PS", "PF", "SC", "AO", "OI", "OX", "AI", "IR", "NT", "NM", "NS", "NA", "AB", "AG", "AC", "FD", "PN", "PU", "PP", "PC" )<CR><LF>'
2019-02-14T10:20:23.902668+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.902964+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.903246+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.903477+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CLCK="SC",2<CR>'
2019-02-14T10:20:23.929795+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CLCK: 1<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.930402+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.930783+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.931080+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CLCK="FD",2<CR>'
2019-02-14T10:20:23.958198+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CLCK: 0<CR><LF>'
2019-02-14T10:20:23.958841+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:23.959307+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.959573+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.959984+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CLCK="PS",2<CR>'
2019-02-14T10:20:23.983370+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
2019-02-14T10:20:23.983989+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:23.984304+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:23.984525+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:23.984913+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CLCK="PF",2<CR>'
2019-02-14T10:20:24.008528+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CME ERROR: 13<CR><LF>'
2019-02-14T10:20:24.009192+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 13 (SIM failure)
2019-02-14T10:20:24.009465+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.009755+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.010156+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CLCK="PN",2<CR>'
2019-02-14T10:20:24.033819+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
2019-02-14T10:20:24.034425+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:24.034671+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.034854+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.035325+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CLCK="PU",2<CR>'
2019-02-14T10:20:24.058752+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
2019-02-14T10:20:24.059446+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:24.059728+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.059891+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.060140+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CLCK="PP",2<CR>'
2019-02-14T10:20:24.083741+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
2019-02-14T10:20:24.084383+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:24.084672+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.084844+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.085221+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CLCK="PC",2<CR>'
2019-02-14T10:20:24.108490+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
2019-02-14T10:20:24.108995+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:20:24.109483+00:00 me ModemManager[450]: <debug> [modem1] loading IMEI...
2019-02-14T10:20:24.109862+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.110318+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.110871+00:00 me ModemManager[450]: <debug> [modem1] loaded IMEI: 004401084542329
2019-02-14T10:20:24.111294+00:00 me ModemManager[450]: <debug> [modem1] loading UE mode of operation for EPS...
2019-02-14T10:20:24.111581+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.112375+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.112742+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CEMODE?<CR>'
2019-02-14T10:20:24.134417+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CEMODE: 2<CR><LF>'
2019-02-14T10:20:24.134917+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:24.135310+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.135672+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.136364+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SCFG="MEopMode/Prov/Cfg"<CR>'
2019-02-14T10:20:24.172956+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CME ERROR: 21<CR><LF>'
2019-02-14T10:20:24.173494+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 21 (Invalid index)
2019-02-14T10:20:24.173773+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) couldn't query MNO profiles: Invalid index
2019-02-14T10:20:24.174137+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) using default EPS bearer context id: 1
2019-02-14T10:20:24.174446+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.174720+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.174910+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CGCONTRDP<CR>'
2019-02-14T10:20:24.196362+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:24.196920+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) couldn't parse CGDCONTRDP response: Couldn't match +CGCONTRDP response
2019-02-14T10:20:24.197324+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.197538+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.197729+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SGAUTH?<CR>'
2019-02-14T10:20:24.220735+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/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, 0<CR><LF>^SGAUTH:7, 0<CR><LF>^SGAUTH:8, 0<CR><LF>^SGAUTH:9, 0<CR><LF>^SGAUTH:10, 0<CR><LF>^SGAUTH:11, 0<CR><LF>^SGAUTH:12, 0<CR><LF>^SGAUTH:13, 0<CR><LF>^SGAUTH:14, 0<CR><LF>^SGAUTH:15, 0<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:20:24.221391+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) couldn't parse context 1 auth settings: Auth settings for context 1 not found
2019-02-14T10:20:24.223395+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.223944+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.226089+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.226648+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.226990+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CUSD=?<CR>'
2019-02-14T10:20:24.248771+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CUSD: (0-2)<CR><LF>'
2019-02-14T10:20:24.249636+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:24.250500+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:24.250997+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) no GPS data port found: no GPS capabilities
2019-02-14T10:20:24.252974+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:24.253577+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CNMI=?<CR>'
2019-02-14T10:20:24.275395+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CNMI: (0-3),(0-3),(0-3),(0-1),(0-1)<CR><LF>'
2019-02-14T10:20:24.275907+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:24.276270+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.276475+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.276652+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CPMS=?<CR>'
2019-02-14T10:20:24.298882+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CPMS: ("SM","SR","ME"),("SM","SR","ME"),("SM","SR","ME")<CR><LF>'
2019-02-14T10:20:24.299501+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:24.299778+00:00 me ModemManager[450]: <debug> [modem1] supported storages loaded:
2019-02-14T10:20:24.299965+00:00 me ModemManager[450]: <debug> [modem1] mem1 (list/read/delete) storages: 'sm, sr, me'
2019-02-14T10:20:24.300615+00:00 me ModemManager[450]: <debug> [modem1] mem2 (write/send) storages: 'sm, sr, me'
2019-02-14T10:20:24.301110+00:00 me ModemManager[450]: <debug> [modem1] mem3 (reception) storages: 'sm, sr, me'
2019-02-14T10:20:24.301476+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.302297+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.302610+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CPMS?<CR>'
2019-02-14T10:20:24.319838+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CPMS: "SM",0,10,"SM",0,10,"SM",0,10<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:20:24.320399+00:00 me ModemManager[450]: <debug> [modem1] current storages initialized:
2019-02-14T10:20:24.320761+00:00 me ModemManager[450]: <debug> [modem1] mem1 (list/read/delete) storages: 'sm'
2019-02-14T10:20:24.321557+00:00 me ModemManager[450]: <debug> [modem1] mem2 (write/send) storages: 'sm'
2019-02-14T10:20:24.322514+00:00 me ModemManager[450]: <debug> [modem1] current storages initialized
2019-02-14T10:20:24.322920+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.323295+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.323648+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CTZU=1<CR>'
2019-02-14T10:20:24.341603+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:24.342212+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CCLK?<CR>'
2019-02-14T10:20:24.362796+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CCLK: "70/01/01,00:04:20+08"<CR><LF>'
2019-02-14T10:20:24.363377+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:24.364765+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:20:24.365359+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:20:24.365627+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SMONI=?<CR>'
2019-02-14T10:20:24.382993+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:20:24.383519+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) SMONI supported
2019-02-14T10:20:24.384987+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:24.385582+00:00 me ModemManager[450]: <debug> [modem1] couldn't initialize interface: 'OMA not supported'
2019-02-14T10:20:24.385976+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:24.386406+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'ATH<CR>'
2019-02-14T10:20:24.437261+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CME ERROR: 4<CR><LF>'
2019-02-14T10:20:24.437781+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 4 (Operation not supported)
2019-02-14T10:20:24.438160+00:00 me ModemManager[450]: <debug> [modem1] voice support check failed: Operation not supported
2019-02-14T10:20:24.438528+00:00 me ModemManager[450]: <debug> [modem1] couldn't initialize interface: 'Voice not supported'
2019-02-14T10:20:24.439731+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:24.440640+00:00 me ModemManager[450]: <debug> [modem1] ports context for SIM hot swap already available
2019-02-14T10:20:24.441661+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 1 (close)
$ mmcli -m 1 --enable
Click to expand
2019-02-14T10:20:24.385976+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:20:24.386406+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'ATH<CR>'
2019-02-14T10:20:24.437261+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CME ERROR: 4<CR><LF>'
2019-02-14T10:20:24.437781+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 4 (Operation not supported)
2019-02-14T10:20:24.438160+00:00 me ModemManager[450]: <debug> [modem1] voice support check failed: Operation not supported
2019-02-14T10:20:24.438528+00:00 me ModemManager[450]: <debug> [modem1] couldn't initialize interface: 'Voice not supported'
2019-02-14T10:20:24.439731+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:20:24.440640+00:00 me ModemManager[450]: <debug> [modem1] ports context for SIM hot swap already available
2019-02-14T10:20:24.441661+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 1 (close)
2019-02-14T10:20:59.009718+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CTZU: "22/10/04,13:12:31",+8,1<CR><LF>'
2019-02-14T10:21:42.499431+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (open)
2019-02-14T10:21:42.500095+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] opening serial port...
2019-02-14T10:21:42.500476+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] setting up baudrate: 57600
2019-02-14T10:21:42.500910+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] no flow control explicitly requested for device
2019-02-14T10:21:42.501284+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] device open count is 1 (open)
2019-02-14T10:21:42.501565+00:00 me ModemManager[450]: <debug> [modem1] flashing primary AT port before enabling...
2019-02-14T10:21:42.501887+00:00 me ModemManager[450]: <debug> [modem1] running initialization sequence...
2019-02-14T10:21:42.502687+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:21:42.503384+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'ATZ<CR>'
2019-02-14T10:21:42.519643+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:42.520163+00:00 me ModemManager[450]: <debug> [modem1] giving some time to settle the modem...
2019-02-14T10:21:42.520371+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:43.021293+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] running init sequence...
2019-02-14T10:21:43.022094+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] running init sequence...
2019-02-14T10:21:43.022359+00:00 me ModemManager[450]: <debug> [modem1] loading power state...
2019-02-14T10:21:43.022729+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:21:43.023164+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'ATE0<CR>'
2019-02-14T10:21:43.023534+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] --> 'ATE0<CR>'
2019-02-14T10:21:43.026587+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] <-- 'ATE0'
2019-02-14T10:21:43.027857+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.028255+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] <-- '<CR>'
2019-02-14T10:21:43.028636+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.028855+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'ATV1<CR>'
2019-02-14T10:21:43.040138+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.040816+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CMEE=1<CR>'
2019-02-14T10:21:43.058118+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.058576+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'ATX4<CR>'
2019-02-14T10:21:43.071652+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.072151+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT&C1<CR>'
2019-02-14T10:21:43.083972+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.084545+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CFUN?<CR>'
2019-02-14T10:21:43.105243+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CFUN: 1<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.105774+00:00 me ModemManager[450]: <debug> [modem1] no need to change power state: already 'on'
2019-02-14T10:21:43.106754+00:00 me ModemManager[450]: <debug> [modem1] checking if SIM was swapped...
2019-02-14T10:21:43.107136+00:00 me ModemManager[450]: <debug> [modem1/sim0] loading SIM identifier...
2019-02-14T10:21:43.107340+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.107669+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.108297+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CRSM=176,12258,0,0,10<CR>'
2019-02-14T10:21:43.145313+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CRSM: 144,0,"989422183801561255F2"<CR><LF>'
2019-02-14T10:21:43.145903+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.146501+00:00 me ModemManager[450]: <debug> [modem1/sim0] loaded SIM identifier: 8949228183106521552
2019-02-14T10:21:43.146763+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.146961+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.147466+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT\Q3<CR>'
2019-02-14T10:21:43.160227+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.160909+00:00 me ModemManager[450]: <debug> [modem1] modem has 3GPP capabilities, enabling the Modem 3GPP interface...
2019-02-14T10:21:43.161245+00:00 me ModemManager[450]: <debug> [modem1] checking indicator support...
2019-02-14T10:21:43.161483+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.161707+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.161918+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CIND=?<CR>'
2019-02-14T10:21:43.179456+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CIND: ("service",(0-1))<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.179953+00:00 me ModemManager[450]: <debug> [modem1] service indications via CIND are supported at index '1'
2019-02-14T10:21:43.180486+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.180781+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.180968+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CMER=?<CR>'
2019-02-14T10:21:43.198669+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>ERROR<CR><LF>'
2019-02-14T10:21:43.199358+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 100 (Unknown error)
2019-02-14T10:21:43.199622+00:00 me ModemManager[450]: <debug> [modem1] +CMER check failed: Unknown error
2019-02-14T10:21:43.199804+00:00 me ModemManager[450]: <debug> [modem1] generic indications are unsupported
2019-02-14T10:21:43.199974+00:00 me ModemManager[450]: <debug> [modem1] checking packet domain event reporting...
2019-02-14T10:21:43.200750+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.201618+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.202073+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CGEREP=?<CR>'
2019-02-14T10:21:43.224149+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CGEREP: (0-2),(0-1)<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.224893+00:00 me ModemManager[450]: <debug> [modem1] packet domain event reporting is supported
2019-02-14T10:21:43.225376+00:00 me ModemManager[450]: <debug> [modem1] setting 3GPP +CIEV unsolicited events handlers in ttyACM0
2019-02-14T10:21:43.225706+00:00 me ModemManager[450]: <debug> [modem1] setting 3GPP +CIEV unsolicited events handlers in ttyACM1
2019-02-14T10:21:43.226166+00:00 me ModemManager[450]: <debug> [modem1] setting 3GPP +CGEV unsolicited events handlers in ttyACM0
2019-02-14T10:21:43.226497+00:00 me ModemManager[450]: <debug> [modem1] setting 3GPP +CGEV unsolicited events handlers in ttyACM1
2019-02-14T10:21:43.226725+00:00 me ModemManager[450]: <debug> [modem1] enabling +CGEV event reporting in primary port...
2019-02-14T10:21:43.226938+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.227618+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.228116+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CGEREP=2<CR>'
2019-02-14T10:21:43.246814+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.247495+00:00 me ModemManager[450]: <debug> [modem1] enabling +CGEV event reporting in secondary port...
2019-02-14T10:21:43.247759+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] device open count is 2 (open)
2019-02-14T10:21:43.247980+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:43.248455+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] --> 'AT+CGEREP=2<CR>'
2019-02-14T10:21:43.268799+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.269465+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:21:43.269667+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] device open count is 1 (close)
2019-02-14T10:21:43.269881+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SCFG="URC/DstIfc","app"<CR>'
2019-02-14T10:21:43.305151+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CME ERROR: 21<CR><LF>'
2019-02-14T10:21:43.305749+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 21 (Invalid index)
2019-02-14T10:21:43.305942+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) couldn't guarantee unsolicited events are sent to the correct port: Invalid index
2019-02-14T10:21:43.306170+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.306446+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.306721+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SIND="psinfo",1<CR>'
2019-02-14T10:21:43.332824+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>^SIND: psinfo,1,17<CR><LF>'
2019-02-14T10:21:43.333421+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.333719+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) reporting initial access technologies...
2019-02-14T10:21:43.333925+00:00 me ModemManager[450]: <debug> [modem1] access technology changed (unknown -> lte)
2019-02-14T10:21:43.334385+00:00 me ModemManager[450]: <debug> [modem1] setting up 3GPP unsolicited registration messages handlers in ttyACM0
2019-02-14T10:21:43.334740+00:00 me ModemManager[450]: <debug> [modem1] setting up 3GPP unsolicited registration messages handlers in ttyACM1
2019-02-14T10:21:43.335138+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:43.335962+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:21:43.336618+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CREG=2<CR>'
2019-02-14T10:21:43.355204+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.355803+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] device open count is 2 (open)
2019-02-14T10:21:43.356202+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:43.356423+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] --> 'AT+CREG=2<CR>'
2019-02-14T10:21:43.373834+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.374504+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:21:43.374754+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] device open count is 1 (close)
2019-02-14T10:21:43.374972+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CGREG=2<CR>'
2019-02-14T10:21:43.393867+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.394586+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] device open count is 2 (open)
2019-02-14T10:21:43.394929+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:43.395198+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] --> 'AT+CGREG=2<CR>'
2019-02-14T10:21:43.414753+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.415586+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CSQ: 15,99<CR><LF>'
2019-02-14T10:21:44.106878+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:44.107270+00:00 me ModemManager[450]: <debug> [modem1] signal quality updated (48)
2019-02-14T10:21:44.107548+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:44.107838+00:00 me ModemManager[450]: <debug> [modem1] polling to refresh access technologies is unsupported
2019-02-14T10:21:44.108380+00:00 me ModemManager[450]: <debug> [modem1] periodic signal quality and access technology checks scheduledager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:21:43.415921+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] device open count is 1 (close)
2019-02-14T10:21:43.416666+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CEREG=2<CR>'
2019-02-14T10:21:43.435621+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.436355+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] device open count is 2 (open)
2019-02-14T10:21:43.436659+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:43.436848+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] --> 'AT+CEREG=2<CR>'
2019-02-14T10:21:43.456743+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.457482+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:21:43.457869+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] device open count is 1 (close)
2019-02-14T10:21:43.458132+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CGDCONT?<CR>'
2019-02-14T10:21:43.482195+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>'
2019-02-14T10:21:43.518290+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '+CGDCONT: 1,"IPV4V6","pinternet.MNC007.MCC262.GPRS","10.146.17.174 254.128.0.0.0.0.0.0.0.2.0.2.242.29.10.19",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 2,"IPV4V6","internet"",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 3,"IP","internet"",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 4,"IPV6","internet"",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 5,"IPV4V6","internet"",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 6,"IP","internet"",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 7,"IPV6","internet"",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 8,"IPV4V6","IMS","254.128.0.0.0.0.0.0.0.1.0.1.175.26.32.71",0,0,0,2,1,1<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.518898+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.519175+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.519401+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SGAUTH?<CR>'
2019-02-14T10:21:43.542078+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/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, 0<CR><LF>^SGAUTH:7, 0<CR><LF>^SGAUTH:8, 0<CR><LF>^SGAUTH:9, 0<CR><LF>^SGAUTH:10, 0<CR><LF>^SGAUTH:11, 0<CR><LF>^SGAUTH:12, 0<CR><LF>^SGAUTH:13, 0<CR><LF>^SGAUTH:14, 0<CR><LF>^SGAUTH:15, 0<CR><LF>'
2019-02-14T10:21:43.542578+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.542867+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) couldn't parse context 1 auth settings: Auth settings for context 1 not found
2019-02-14T10:21:43.543153+00:00 me ModemManager[450]: <debug> [modem1] updating initial EPS bearer...
2019-02-14T10:21:43.545467+00:00 me ModemManager[450]: <debug> [modem1/bearer0] bearer not allowed to connect, not registered in 3GPP network
2019-02-14T10:21:43.545989+00:00 me ModemManager[450]: <debug> [modem1] modem has 3GPP profile management capabilities, enabling the Modem 3GPP Profile Manager interface...
2019-02-14T10:21:43.546899+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:43.547462+00:00 me ModemManager[450]: <debug> [modem1] modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface...
2019-02-14T10:21:43.548398+00:00 me ModemManager[450]: <debug> [modem1] setting unsolicited result code handlers in ttyACM0
2019-02-14T10:21:43.548797+00:00 me ModemManager[450]: <debug> [modem1] setting unsolicited result code handlers in ttyACM1
2019-02-14T10:21:43.549101+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:21:43.549762+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CUSD=1<CR>'
2019-02-14T10:21:43.567280+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.567889+00:00 me ModemManager[450]: <debug> [modem1] modem has location capabilities, enabling the Location interface...
2019-02-14T10:21:43.568492+00:00 me ModemManager[450]: <debug> [modem1] need to enable the following location sources: '3gpp-lac-ci'
2019-02-14T10:21:43.569339+00:00 me ModemManager[450]: <debug> [modem1] running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes', 5GS: 'no')
2019-02-14T10:21:43.569866+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.571225+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.571814+00:00 me ModemManager[450]: <debug> [modem1] modem has messaging capabilities, enabling the Messaging interface...
2019-02-14T10:21:43.572379+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.572790+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CREG?<CR>'
2019-02-14T10:21:43.592053+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>'
2019-02-14T10:21:43.592582+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '+CREG: 2,1,"bc0c","02b7d71a",7<CR><LF>'
2019-02-14T10:21:43.592959+00:00 me ModemManager[450]: <debug> [modem1] 3GPP registration state change ignored as modem isn't enabled
2019-02-14T10:21:43.593567+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.593845+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 5 (open)
2019-02-14T10:21:43.594122+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (close)
2019-02-14T10:21:43.594517+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CMGF=?<CR>'
2019-02-14T10:21:43.615748+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CMGF: (0,1)<CR><LF>'
2019-02-14T10:21:43.616517+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.616859+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 5 (open)
2019-02-14T10:21:43.617109+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (close)
2019-02-14T10:21:43.617371+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CGREG?<CR>'
2019-02-14T10:21:43.638904+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CGREG: 2,0<CR><LF>'
2019-02-14T10:21:43.639698+00:00 me ModemManager[450]: <debug> [modem1] 3GPP registration state change ignored as modem isn't enabled
2019-02-14T10:21:43.640127+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.640353+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 5 (open)
2019-02-14T10:21:43.640768+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (close)
2019-02-14T10:21:43.641224+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CMGF=0<CR>'
2019-02-14T10:21:43.658203+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.658735+00:00 me ModemManager[450]: <debug> [modem1] successfully set preferred SMS mode: 'PDU'
2019-02-14T10:21:43.659206+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 5 (open)
2019-02-14T10:21:43.659745+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (close)
2019-02-14T10:21:43.660328+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CEREG?<CR>'
2019-02-14T10:21:43.681701+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CEREG: 2,1,"bc0c","02b7d71a",7<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.682290+00:00 me ModemManager[450]: <debug> [modem1] 3GPP registration state change ignored as modem isn't enabled
2019-02-14T10:21:43.682882+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.683200+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CPMS="SM","ME","ME"<CR>'
2019-02-14T10:21:43.719285+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>'
2019-02-14T10:21:43.719915+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '+CPMS: 0,10,1,180,1,180<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.720281+00:00 me ModemManager[450]: <debug> [modem1] listing SMS parts in storage 'sm'
2019-02-14T10:21:43.720481+00:00 me ModemManager[450]: <debug> [modem1] locking SMS storages to: mem1 (SM), mem2 (none)...
2019-02-14T10:21:43.720734+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.720941+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.721526+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CPMS="SM"<CR>'
2019-02-14T10:21:43.745888+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CPMS: 0,10,1,180,1,180<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.746426+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.746804+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.747110+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CMGL=4<CR>'
2019-02-14T10:21:43.766273+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.766795+00:00 me ModemManager[450]: <debug> [modem1] listing SMS parts in storage 'sr'
2019-02-14T10:21:43.767318+00:00 me ModemManager[450]: <debug> [modem1] locking SMS storages to: mem1 (SR), mem2 (none)...
2019-02-14T10:21:43.767572+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.767755+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.768584+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CPMS="SR"<CR>'
2019-02-14T10:21:43.793455+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CPMS: 0,5,1,180,1,180<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.794114+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.794426+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.794642+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CMGL=4<CR>'
2019-02-14T10:21:43.838080+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.838748+00:00 me ModemManager[450]: <debug> [modem1] listing SMS parts in storage 'me'
2019-02-14T10:21:43.839114+00:00 me ModemManager[450]: <debug> [modem1] locking SMS storages to: mem1 (ME), mem2 (none)...
2019-02-14T10:21:43.839320+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.840292+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.840687+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CPMS="ME"<CR>'
2019-02-14T10:21:43.865074+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CPMS: 1,180,1,180,1,180<CR><LF>'
2019-02-14T10:21:43.865706+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.865965+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.866221+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:43.866428+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CMGL=4<CR>'
2019-02-14T10:21:43.887587+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CMGL: 1,1,,86<CR><LF>0791947106004034040C919471390030334200220140313314804CD6B75B076A86D36CF11BEF024DD365103A2C2EBB413190BB5C2F839CE1315A9E1EA3E920619A4E2F83E47573D90D9AA6CB20D18A968BDD7233180C369BCD44A0B0DB05<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.888226+00:00 me ModemManager[450]: <debug> [modem1] parsing PDU (1)...
2019-02-14T10:21:43.888589+00:00 me ModemManager[450]: <debug> [modem1] SMSC address parsed: '+491760000443'
2019-02-14T10:21:43.888847+00:00 me ModemManager[450]: <debug> [modem1] deliver type PDU detected
2019-02-14T10:21:43.889099+00:00 me ModemManager[450]: <debug> [modem1] number parsed: +491793000333
2019-02-14T10:21:43.889407+00:00 me ModemManager[450]: <debug> [modem1] signal quality updated (48)
2019-02-14T10:21:44.107548+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:44.107838+00:00 me ModemManager[450]: <debug> [modem1] polling to refresh access technologies is unsupported
2019-02-14T10:21:44.108380+00:00 me ModemManager[450]: <debug> [modem1] periodic signal quality and access technology checks scheduled00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:44.087976+00:00 me ModemManager[450]: <debug> [modem1] initial 3GPP registration checks finished
2019-02-14T10:21:44.088307+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:44.088772+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CSQ<CR>'
2019-02-14T10:21:44.106387+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CSQ: 15,99<CR><LF>'
2019-02-14T10:21:44.106878+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:44.107270+00:00 me ModemManager[450]: <debug> [modem1] signal quality updated (48)
2019-02-14T10:21:44.107548+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:44.107838+00:00 me ModemManager[450]: <debug> [modem1] polling to refresh access technologies is unsupported
2019-02-14T10:21:44.108380+00:00 me ModemManager[450]: <debug> [modem1] periodic signal quality and access technology checks scheduled]: <debug> [modem1] PID: 66
2019-02-14T10:21:43.889613+00:00 me ModemManager[450]: <debug> [modem1] user data encoding is GSM7
2019-02-14T10:21:43.889789+00:00 me ModemManager[450]: <debug> [modem1] user data length: 76 elements
2019-02-14T10:21:43.889967+00:00 me ModemManager[450]: <debug> [modem1] user data length: 67 bytes
2019-02-14T10:21:43.890231+00:00 me ModemManager[450]: <debug> [modem1] decoding SMS text with 76 elements
2019-02-14T10:21:43.890791+00:00 me ModemManager[450]: <debug> [modem1] converted SMS part text from GSM-7 to UTF-8: Von: Mailbox. Sie haben 1 neue Nachricht Bitte rufen Sie "+491793000333" an.
2019-02-14T10:21:43.891319+00:00 me ModemManager[450]: <debug> [modem1] correctly parsed PDU (1)
2019-02-14T10:21:43.891706+00:00 me ModemManager[450]: <debug> [modem1/sms-list] SMS part at 'me/1' is from a singlepart SMS
2019-02-14T10:21:43.892363+00:00 me ModemManager[450]: <debug> [modem1] setting messaging unsolicited events handlers in ttyACM0
2019-02-14T10:21:43.893094+00:00 me ModemManager[450]: <debug> [modem1] setting messaging unsolicited events handlers in ttyACM1
2019-02-14T10:21:43.893685+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:43.894664+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:21:43.895335+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CNMI=2,2,2,1,1<CR>'
2019-02-14T10:21:43.921260+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CMS ERROR: 302<CR><LF>'
2019-02-14T10:21:43.921758+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] operation failure: 302 (Operation not allowed)
2019-02-14T10:21:43.922128+00:00 me ModemManager[450]: <debug> [modem1] couldn't enable unsolicited events: Operation not allowed
2019-02-14T10:21:43.922468+00:00 me ModemManager[450]: <debug> [modem1] modem has time capabilities, enabling the Time interface...
2019-02-14T10:21:43.922798+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) setting up time unsolicited events...
2019-02-14T10:21:43.923154+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:43.923487+00:00 me ModemManager[450]: <debug> [modem1] modem has extended signal reporting capabilities, enabling the Signal interface...
2019-02-14T10:21:43.923799+00:00 me ModemManager[450]: <debug> [modem1] extended signal information reporting disabled (rate: 0 seconds)
2019-02-14T10:21:43.924738+00:00 me ModemManager[450]: <debug> [modem1] running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes', 5GS: 'no')
2019-02-14T10:21:43.926074+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:21:43.927166+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CREG?<CR>'
2019-02-14T10:21:43.946403+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>'
2019-02-14T10:21:43.946977+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '+CREG: 2,1,"bc0c","02b7d71a",7<CR><LF>'
2019-02-14T10:21:43.947888+00:00 me ModemManager[450]: <debug> [modem1] loading Operator Code...
2019-02-14T10:21:43.948323+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:21:43.948677+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 5 (open)
2019-02-14T10:21:43.949345+00:00 me ModemManager[450]: <debug> [modem1] 3GPP tracking area code updated: '000000->00BC0C'
2019-02-14T10:21:43.949773+00:00 me ModemManager[450]: <debug> [modem1] 3GPP cell id updated: '00000000->02B7D71A'
2019-02-14T10:21:43.950153+00:00 me ModemManager[450]: <debug> [modem1] 3GPP location updated (MCCMNC: '<none>', location area code: '0000', tracking area code: '00BC0C', cell ID: '02B7D71A')
2019-02-14T10:21:43.950656+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.951178+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 6 (open)
2019-02-14T10:21:43.951535+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 5 (close)
2019-02-14T10:21:43.951918+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+COPS=3,2<CR>'
2019-02-14T10:21:43.970928+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.971424+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (close)
2019-02-14T10:21:43.971638+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+COPS?<CR>'
2019-02-14T10:21:43.992244+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+COPS: 0,2,"26203",7<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:21:43.992756+00:00 me ModemManager[450]: <debug> [modem1] couldn't convert operator string '26203' from charset 'UCS2': Hex conversion failed: invalid input length
2019-02-14T10:21:43.993204+00:00 me ModemManager[450]: <debug> [modem1] operator normalized '26203'->'26203'
2019-02-14T10:21:43.993499+00:00 me ModemManager[450]: <debug> [modem1] loaded Operator Code: 26203
2019-02-14T10:21:43.993894+00:00 me ModemManager[450]: <debug> [modem1] 3GPP location updated (MCCMNC: '26203', location area code: '0000', tracking area code: '00BC0C', cell ID: '02B7D71A')
2019-02-14T10:21:43.994158+00:00 me ModemManager[450]: <debug> [modem1] loading Operator Name...
2019-02-14T10:21:43.995146+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 5 (open)
2019-02-14T10:21:43.995511+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 6 (open)
2019-02-14T10:21:43.996170+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 5 (close)
2019-02-14T10:21:43.996508+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CGREG?<CR>'
2019-02-14T10:21:44.016336+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CGREG: 2,0<CR><LF>'
2019-02-14T10:21:44.016974+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:44.017400+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 6 (open)
2019-02-14T10:21:44.017636+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 5 (close)
2019-02-14T10:21:44.017849+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+COPS=3,0<CR>'
2019-02-14T10:21:44.038578+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:44.039099+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (close)
2019-02-14T10:21:44.039406+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+COPS?<CR>'
2019-02-14T10:21:44.059955+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+COPS: 0,0,"FONIC",7<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:21:44.060530+00:00 me ModemManager[450]: <debug> [modem1] couldn't convert operator string 'FONIC' from charset 'UCS2': Hex conversion failed: invalid input length
2019-02-14T10:21:44.060838+00:00 me ModemManager[450]: <debug> [modem1] operator normalized 'FONIC'->'FONIC'
2019-02-14T10:21:44.061191+00:00 me ModemManager[450]: <debug> [modem1] loaded Operator Name: FONIC
2019-02-14T10:21:44.061924+00:00 me ModemManager[450]: <debug> [modem1] consolidated registration state: cs 'home', ps 'idle', eps 'home', 5gs 'unknown' --> 'home'
2019-02-14T10:21:44.062352+00:00 me ModemManager[450]: <debug> [modem1] will start keeping track of state for subsystem '3gpp'
2019-02-14T10:21:44.063362+00:00 me ModemManager[450]: <debug> [modem1] network timezone polling started
2019-02-14T10:21:44.064285+00:00 me ModemManager[450]: <debug> [modem1] periodic signal checks enabled
2019-02-14T10:21:44.064693+00:00 me ModemManager[450]: <debug> [modem1] periodic signal check refresh requested
2019-02-14T10:21:44.065176+00:00 me ModemManager[450]: <debug> [modem1] loading signal quality...
2019-02-14T10:21:44.065636+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 5 (open)
2019-02-14T10:21:44.066090+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (close)
2019-02-14T10:21:44.066600+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CEREG?<CR>'
2019-02-14T10:21:44.086953+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CEREG: 2,1,"bc0c","02b7d71a",7<CR><LF>'
2019-02-14T10:21:44.087650+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:44.087976+00:00 me ModemManager[450]: <debug> [modem1] initial 3GPP registration checks finished
2019-02-14T10:21:44.088307+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:44.088772+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CSQ<CR>'
2019-02-14T10:21:44.106387+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CSQ: 15,99<CR><LF>'
2019-02-14T10:21:44.106878+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:44.107270+00:00 me ModemManager[450]: <debug> [modem1] signal quality updated (48)
2019-02-14T10:21:44.107548+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:44.107838+00:00 me ModemManager[450]: <debug> [modem1] polling to refresh access technologies is unsupported
2019-02-14T10:21:44.108380+00:00 me ModemManager[450]: <debug> [modem1] periodic signal quality and access technology checks scheduled00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:44.087976+00:00 me ModemManager[450]: <debug> [modem1] initial 3GPP registration checks finished
2019-02-14T10:21:44.088307+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:21:44.088772+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CSQ<CR>'
2019-02-14T10:21:44.106387+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CSQ: 15,99<CR><LF>'
2019-02-14T10:21:44.106878+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:21:44.107270+00:00 me ModemManager[450]: <debug> [modem1] signal quality updated (48)
2019-02-14T10:21:44.107548+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:21:44.107838+00:00 me ModemManager[450]: <debug> [modem1] polling to refresh access technologies is unsupported
2019-02-14T10:21:44.108380+00:00 me ModemManager[450]: <debug> [modem1] periodic signal quality and access technology checks scheduled
$ mmcli -m 1 --simple-connect='apn=pinternet.MNC007.MCC262.GPRS,ip-type=ipv4v6'
error: couldn't connect the modem: 'GDBus.Error:org.freedesktop.ModemManager1.Error.Core.Failed: CID 1 is reported disconnected'
$ echo $?
1Click to expand
2019-02-14T10:23:05.358482+00:00 me ModemManager[450]: <debug> [modem1] user request to connect modem
2019-02-14T10:23:05.359610+00:00 me ModemManager[450]: <debug> [modem1] profile ID: unspecified
2019-02-14T10:23:05.359891+00:00 me ModemManager[450]: <debug> [modem1] PIN: unspecified
2019-02-14T10:23:05.360381+00:00 me ModemManager[450]: <debug> [modem1] operator ID: unspecified
2019-02-14T10:23:05.360835+00:00 me ModemManager[450]: <debug> [modem1] allowed roaming: yes
2019-02-14T10:23:05.361408+00:00 me ModemManager[450]: <debug> [modem1] APN: pinternet.MNC007.MCC262.GPRS
2019-02-14T10:23:05.361742+00:00 me ModemManager[450]: <debug> [modem1] APN type: unspecified
2019-02-14T10:23:05.362360+00:00 me ModemManager[450]: <debug> [modem1] IP family: ipv4v6
2019-02-14T10:23:05.362617+00:00 me ModemManager[450]: <debug> [modem1] allowed authentication: unspecified
2019-02-14T10:23:05.362817+00:00 me ModemManager[450]: <debug> [modem1] user: unspecified
2019-02-14T10:23:05.364473+00:00 me ModemManager[450]: <debug> [modem1] password: unspecified
2019-02-14T10:23:05.364787+00:00 me ModemManager[450]: <debug> [modem1] multiplex: unspecified
2019-02-14T10:23:05.365803+00:00 me ModemManager[450]: <debug> [modem1] already registered automatically in network '26203', automatic registration not launched...
2019-02-14T10:23:05.366217+00:00 me ModemManager[450]: <debug> [modem1] creating new bearer...
2019-02-14T10:23:05.366748+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) checking ^SWWAN support...
2019-02-14T10:23:05.367254+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:23:05.367439+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SWWAN=?<CR>'
2019-02-14T10:23:05.378251+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>^SWWAN: (0,1),(1-15),(1)<CR><LF>'
2019-02-14T10:23:05.378715+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:23:05.379186+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) SWWAN supported
2019-02-14T10:23:05.379701+00:00 me ModemManager[450]: <debug> [modem1] (cinterion) ^SWWAN supported, creating cinterion bearer...
2019-02-14T10:23:05.380505+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:23:05.380830+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:23:05.383213+00:00 me ModemManager[450]: <debug> [modem1/bearer1] connecting...
2019-02-14T10:23:05.386099+00:00 me ModemManager[450]: <debug> [modem1/bearer1] launching 3GPP connection attempt
2019-02-14T10:23:05.387203+00:00 me ModemManager[450]: <debug> [modem1] set profile state (1/8): check format
2019-02-14T10:23:05.387736+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:23:05.388368+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:23:05.388640+00:00 me ModemManager[450]: <debug> [modem1] +CGDCONT format details for PDP type 'ipv4v6': minimum 1, maximum 15
2019-02-14T10:23:05.388820+00:00 me ModemManager[450]: <debug> [modem1] context definition format: minimum 1, maximum 15
2019-02-14T10:23:05.388982+00:00 me ModemManager[450]: <debug> [modem1] set profile state (2/8): list before
2019-02-14T10:23:05.389182+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:23:05.389352+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:23:05.389644+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CGDCONT?<CR>'
2019-02-14T10:23:05.409595+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>'
2019-02-14T10:23:05.445700+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '+CGDCONT: 1,"IPV4V6","pinternet.MNC007.MCC262.GPRS","10.146.17.174 254.128.0.0.0.0.0.0.0.2.0.2.242.29.10.19",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 2,"IPV4V6","internet"",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 3,"IP","internet"",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 4,"IPV6","internet"",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 5,"IPV4V6","internet"",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 6,"IP","internet"",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 7,"IPV6","internet"",0,0,,,,<CR><LF><CR><LF>+CGDCONT: 8,"IPV4V6","IMS","254.128.0.0.0.0.0.0.0.1.0.1.175.26.32.71",0,0,0,2,1,1<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:23:05.446455+00:00 me ModemManager[450]: <debug> [modem1] set profile state (3/8): select profile (best)
2019-02-14T10:23:05.446989+00:00 me ModemManager[450]: <debug> [modem1] found exact context at profile 1
2019-02-14T10:23:05.447506+00:00 me ModemManager[450]: <debug> [modem1] reusing profile '1'
2019-02-14T10:23:05.447774+00:00 me ModemManager[450]: <debug> [modem1] set profile state (4/8): check activated profile
2019-02-14T10:23:05.448104+00:00 me ModemManager[450]: <debug> [modem1] checking if profile with id '1' is already activated...
2019-02-14T10:23:05.448421+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:23:05.448789+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:23:05.449125+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CGACT?<CR>'
2019-02-14T10:23:05.469368+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>'
2019-02-14T10:23:05.504728+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '+CGACT: 1,1<CR><LF><CR><LF>+CGACT: 2,0<CR><LF><CR><LF>+CGACT: 3,0<CR><LF><CR><LF>+CGACT: 4,0<CR><LF><CR><LF>+CGACT: 5,0<CR><LF><CR><LF>+CGACT: 6,0<CR><LF><CR><LF>+CGACT: 7,0<CR><LF><CR><LF>+CGACT: 8,1<CR><LF><CR><LF>OK<CR><LF>'
2019-02-14T10:23:05.505409+00:00 me ModemManager[450]: <debug> [modem1] profile '1' is activated
2019-02-14T10:23:05.505785+00:00 me ModemManager[450]: <debug> [modem1] set profile state (5/8): deactivate profile
2019-02-14T10:23:05.506455+00:00 me ModemManager[450]: <debug> [modem1] deactivating profile with id '1'...
2019-02-14T10:23:05.506880+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:23:05.507274+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:23:05.507586+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CGACT=0,1<CR>'
2019-02-14T10:23:05.695692+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:23:05.696290+00:00 me ModemManager[450]: <debug> [modem1] deactivated profile with id '1'
2019-02-14T10:23:05.696792+00:00 me ModemManager[450]: <debug> [modem1] set profile state (6/8): profile already stored
2019-02-14T10:23:05.697285+00:00 me ModemManager[450]: <debug> [modem1] set profile state (8/8): all done
2019-02-14T10:23:05.698158+00:00 me ModemManager[450]: <debug> [modem1/bearer1] (cinterion) dial step 1/4: authentication not required
2019-02-14T10:23:05.698655+00:00 me ModemManager[450]: <debug> [modem1/bearer1] (cinterion) dial step 2/4: starting SWWAN interface 1 connection...
2019-02-14T10:23:05.698931+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:23:05.699412+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:23:05.699714+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CGEV: ME PDN DEACT 1<CR><LF>'
2019-02-14T10:23:05.700696+00:00 me ModemManager[450]: <debug> [modem1/ttyACM1/at] <-- '<CR><LF>+CGEV: ME PDN DEACT 1<CR><LF>'
2019-02-14T10:23:05.701414+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SWWAN=1,1,1<CR>'
2019-02-14T10:23:05.720802+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:23:05.721457+00:00 me ModemManager[450]: <debug> [modem1/bearer1] (cinterion) dial step 3/4: checking SWWAN interface 1 status...
2019-02-14T10:23:05.721671+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 4 (open)
2019-02-14T10:23:05.721834+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (close)
2019-02-14T10:23:05.722142+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT^SWWAN?<CR>'
2019-02-14T10:23:05.738731+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:23:05.742239+00:00 me ModemManager[450]: <debug> [modem1] couldn't connect bearer: CID 1 is reported disconnected
2019-02-14T10:23:05.743089+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)
2019-02-14T10:23:14.743256+00:00 me ModemManager[450]: <debug> [modem1] loading signal quality...
2019-02-14T10:23:14.743779+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 3 (open)
2019-02-14T10:23:14.744073+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] --> 'AT+CSQ<CR>'
2019-02-14T10:23:14.758481+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>+CSQ: 11,99<CR><LF>'
2019-02-14T10:23:14.758988+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] <-- '<CR><LF>OK<CR><LF>'
2019-02-14T10:23:14.759403+00:00 me ModemManager[450]: <debug> [modem1] signal quality updated (35)
2019-02-14T10:23:14.759833+00:00 me ModemManager[450]: <debug> [modem1] periodic signal quality and access technology checks scheduled
2019-02-14T10:23:14.760284+00:00 me ModemManager[450]: <debug> [modem1/ttyACM0/at] device open count is 2 (close)