Apr 3 20:40:51 canect2 daemon.info ModemManager[252]: [1522788051.345001] Simple connect started... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.345181] PIN: unspecified Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.345272] Operator ID: unspecified Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.345355] Allowed roaming: yes Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.345429] APN: iot.aer.net Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.345516] IP family: ipv4v6 Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.345598] Allowed authentication: unspecified Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.345677] User: unspecified Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.345752] Password: unspecified Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.345827] Number: *99# Apr 3 20:40:51 canect2 daemon.info ModemManager[252]: [1522788051.345884] Simple connect state (4/8): Wait to get fully enabled Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.415548] (ttyACM0) device open count is 2 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.416021] (ttyACM0): --> 'AT+CFUN?' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.419478] (ttyACM0): <-- '+CFUN: 1,0OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.420169] No need to change power state: already in 'on' power state Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.420478] Checking if SIM was swapped... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.420713] loading SIM identifier... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.420973] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.421295] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.421632] (ttyACM0): --> 'AT+CRSM=176,12258,0,0,10' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.440233] (ttyACM0): <-- '+CRSM: 144,0,"XXXXXXXXXXXXXXXXXXXX"OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.441004] loaded SIM identifier: XXXXXXXXXXXXXXXXXXXX Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.441383] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.441732] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.442074] (ttyACM0): --> 'AT+IFC=?' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.445002] (ttyACM0): <-- '+IFC: (0-2),(0-2)OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.445694] TE supports no flow control Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.445936] TE supports XON/XOFF flow control Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.446103] TE supports RTS/CTS flow control Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.446266] TA supports no flow control Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.446414] TA supports XON/XOFF flow control Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.446548] TA supports RTS/CTS flow control Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.446834] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.447229] (ttyACM0) device open count is 4 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.447553] (ttyACM0) device open count is 3 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.447886] (ttyACM0): --> 'AT+IFC=2,2' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.450851] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.451328] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.451697] (ttyACM0): --> 'AT+CSCS=?' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.454870] (ttyACM0): <-- '+CSCS: ("IRA","GSM","PCCP437","8859-1","UCS2","HEX")OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.455855] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.456276] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.456670] (ttyACM0): --> 'AT+CSCS="UCS2"' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.459599] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.460148] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.460517] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.460853] (ttyACM0): --> 'AT+CSCS?' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.463725] (ttyACM0): <-- '+CSCS: "UCS2"OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.464378] Modem has 3GPP capabilities, enabling the Modem 3GPP interface... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.464681] Checking indicator support... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.464943] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.465260] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.465588] (ttyACM0): --> 'AT+CIND=?' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.468628] (ttyACM0): <-- '+CIND: ("battchg",(0-5)),("signal",(0-5)),("service",(0-1)),("sounder",(0-1)),("message",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1)),("gprs",(0-2)),("cal Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.469671] Modem supports signal quality indications via CIND at index '2'(min: 0, max: 5) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.469941] Modem supports roaming indications via CIND at index '7' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.470111] Modem supports service indications via CIND at index '3' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.470371] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.470721] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.471065] (ttyACM0): --> 'AT+CMER=?' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.473353] (ttyACM0): <-- '+CMER: (0-3),(0),(0),(0-2),(0-1)OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.474042] Supported +CMER modes: discard-urcs, discard-urcs-if-link-reserved, buffer-urcs-if-link-reserved, forward-urcs Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.474323] Supported +CMER indication settings: disable, enable-not-caused-by-cind, enable-all Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.474495] +CMER enable mode: forward-urcs Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.474636] +CMER disable mode: discard-urcs Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.474772] +CMER indication setting: enable-not-caused-by-cind Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.475012] (ttyACM0) Setting 3GPP unsolicited events handlers Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.475189] (ttyACM1) Setting 3GPP unsolicited events handlers Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.475488] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.475812] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.476150] (ttyACM0): --> 'AT+CMER=3,0,0,1' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.478353] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.478902] (ttyACM1) device open count is 2 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.479261] (ttyACM0) device open count is 1 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.479597] (ttyACM1): --> 'AT+CMER=3,0,0,1' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.481727] (ttyACM1): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.483336] (ttyACM0) setting up 3GPP unsolicited registration messages handlers Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.483636] (ttyACM1) setting up 3GPP unsolicited registration messages handlers Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.484136] (ttyACM1) device open count is 1 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.484624] (ttyACM0) device open count is 2 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.485071] (ttyACM0): --> 'AT+CREG=2' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.487724] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.488292] (ttyACM1) device open count is 2 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.488661] (ttyACM0) device open count is 1 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.489008] (ttyACM1): --> 'AT+CREG=2' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.491469] (ttyACM1): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.492009] (ttyACM0) device open count is 2 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.492363] (ttyACM1) device open count is 1 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.492701] (ttyACM0): --> 'AT+CGREG=2' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.495101] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.495662] (ttyACM1) device open count is 2 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.496033] (ttyACM0) device open count is 1 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.496378] (ttyACM1): --> 'AT+CGREG=2' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.498846] (ttyACM1): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.499394] (ttyACM0) device open count is 2 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.499753] (ttyACM1) device open count is 1 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.500085] (ttyACM0): --> 'AT+CEREG=2' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.502724] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.503289] (ttyACM1) device open count is 2 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.503654] (ttyACM0) device open count is 1 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.504076] (ttyACM1): --> 'AT+CEREG=2' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.506846] (ttyACM1): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.507343] Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.507724] (ttyACM0) Setting unsolicited result code handlers Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.507946] (ttyACM1) Setting unsolicited result code handlers Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.508197] (ttyACM1) device open count is 1 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.508592] (ttyACM0) device open count is 2 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.509022] (ttyACM0): --> 'AT+CUSD=1' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.511899] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.512591] Modem has location capabilities, enabling the Location interface... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.512996] Need to enable the following location sources: '3gpp-lac-ci' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.513535] Running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes') Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.513953] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.514346] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.519933] Modem has messaging capabilities, enabling the Messaging interface... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.520672] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.521173] (ttyACM0): --> 'AT+CREG?' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.523789] (ttyACM0): <-- '+CREG: 2,3OK' Apr 3 20:40:51 canect2 daemon.info ModemManager[252]: [1522788051.524438] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> denied) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.525211] Will start keeping track of state for subsystem '3gpp' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.525825] (ttyACM0) device open count is 4 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.526181] (ttyACM0) device open count is 3 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.529255] (ttyACM0): --> 'AT+CMGF=?' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.531872] (ttyACM0): <-- '+CMGF: (0-1)OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.532696] (ttyACM0) device open count is 4 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.533070] (ttyACM0) device open count is 3 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.533431] (ttyACM0): --> 'AT+CGREG?' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.535985] (ttyACM0): <-- '+CGREG: 2,3OK' Apr 3 20:40:51 canect2 daemon.info ModemManager[252]: [1522788051.536555] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (denied -> unknown) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.537547] (ttyACM0) device open count is 4 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.537931] (ttyACM0) device open count is 3 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.540948] (ttyACM0): --> 'AT+CMGF=0' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.543606] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.544180] Successfully set preferred SMS mode: 'PDU' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.544813] (ttyACM0) device open count is 4 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.545201] (ttyACM0) device open count is 3 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.548183] (ttyACM0): --> 'AT+CEREG?' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.550891] (ttyACM0): <-- '+CEREG: 2,0OK' Apr 3 20:40:51 canect2 daemon.info ModemManager[252]: [1522788051.551461] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> idle) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.552384] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.555535] (ttyACM0): --> 'AT+CPMS="SR","ME","ME"' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.558741] (ttyACM0): <-- '+CPMS: 0,10,1,100,1,100OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.559324] Listing SMS parts in storage 'bm' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.559581] Locking SMS storages to: mem1 (BM), mem2 (none)... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.559854] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.560178] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.560507] (ttyACM0): --> 'AT+CPMS="BM"' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.563352] (ttyACM0): <-- '+CPMS: 0,10,1,100,1,100OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.564032] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.564425] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.564796] (ttyACM0): --> 'AT+CMGL=4' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.567721] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.568416] Listing SMS parts in storage 'me' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.568689] Locking SMS storages to: mem1 (ME), mem2 (none)... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.568959] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.569277] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.569602] (ttyACM0): --> 'AT+CPMS="ME"' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.572482] (ttyACM0): <-- '+CPMS: 1,100,1,100,1,100OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.573089] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.573470] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.573879] (ttyACM0): --> 'AT+CMGL=4' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.576994] (ttyACM0): <-- '+CMGL: 1,1,,44XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXOK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.577908] Parsing PDU (1)... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.578185] SMSC address parsed: '+XXXXXXXXXXX' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.578350] Deliver type PDU detected Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.578492] Number parsed: '+XXXXX' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.578627] PID: 0 Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.578759] user data encoding is GSM7 Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.578931] user data length: 32 elements Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.579063] user data length: 28 bytes Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.579191] Decoding SMS text with '32' elements Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.579316] Converting SMS part text from GSM-7 to UTF-8... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.579521] Got UTF-8 text: 'This is a test of the LTE modem.' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.579720] Correctly parsed PDU (1) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.579943] SMS part at 'me/1' is from a singlepart SMS Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.582194] Added received SMS at '/org/freedesktop/ModemManager1/SMS/0' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.585337] Listing SMS parts in storage 'sm' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.585670] Locking SMS storages to: mem1 (SM), mem2 (none)... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.586027] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.586375] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.589391] (ttyACM0): --> 'AT+CPMS="SM"' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.592741] (ttyACM0): <-- '+CPMS: 0,3,1,100,1,100OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.593369] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.593821] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.594186] (ttyACM0): --> 'AT+CMGL=4' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.598094] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.598796] Listing SMS parts in storage 'sr' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.599069] Locking SMS storages to: mem1 (SR), mem2 (none)... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.599336] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.599655] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.599979] (ttyACM0): --> 'AT+CPMS="SR"' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.603106] (ttyACM0): <-- '+CPMS: 0,10,1,100,1,100OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.603727] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.604219] (ttyACM0) device open count is 2 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.604568] (ttyACM0): --> 'AT+CMGL=4' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.607350] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.608127] (ttyACM0) Setting messaging unsolicited events handlers Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.608395] (ttyACM1) Setting messaging unsolicited events handlers Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.608667] (ttyACM0) device open count is 1 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.609003] (ttyACM0) Enabling messaging unsolicited events on primary port Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.609286] (ttyACM0) device open count is 2 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.609676] (ttyACM0): --> 'AT+CNMI=2,1,2,1,0' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.612343] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.612837] (ttyACM0) Messaging unsolicited events enabled on primary Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.613056] (ttyACM1) Enabling messaging unsolicited events on secondary port Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.613333] (ttyACM1) device open count is 2 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.613646] (ttyACM0) device open count is 1 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.614059] (ttyACM1): --> 'AT+CNMI=2,1,2,1,0' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.616842] (ttyACM1): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.617327] (ttyACM1) Messaging unsolicited events enabled on secondary Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.617575] Modem has voice capabilities, enabling the Voice interface... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.618366] (ttyACM0) Setting voice unsolicited events handlers Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.618661] (ttyACM1) Setting voice unsolicited events handlers Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.618958] (ttyACM1) device open count is 1 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.619365] (ttyACM0) device open count is 2 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.619798] (ttyACM0): --> 'AT+CLIP=1' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.622596] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.623261] (ttyACM0): --> 'AT+CRC=1' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.625965] (ttyACM0): <-- 'OK' Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.626487] Modem has time capabilities, enabling the Time interface... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.626908] (ttyACM0) device open count is 1 (close) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.627258] Modem has extended signal reporting capabilities, enabling the Signal interface... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.627556] Extended signal information reporting disabled (rate: 0 seconds) Apr 3 20:40:51 canect2 daemon.info ModemManager[252]: [1522788051.630380] Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled) Apr 3 20:40:51 canect2 daemon.info ModemManager[252]: [1522788051.630992] Simple connect state (5/8): Register Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.631363] Launching automatic network registration... Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.631656] (ttyACM0) device open count is 2 (open) Apr 3 20:40:51 canect2 daemon.info NetworkManager[258]: (ttyACM0): modem state changed, 'enabling' --> 'enabled' (reason: user-requested) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.637801] Running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes') Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.638179] (ttyACM0) device open count is 3 (open) Apr 3 20:40:51 canect2 daemon.debug ModemManager[252]: [1522788051.638542] (ttyACM0): --> 'AT+COPS=0' Apr 3 20:40:53 canect2 daemon.debug ModemManager[252]: [1522788053.488558] (ttyACM1): <-- '+CIEV: 9,1+CEREG: 3' Apr 3 20:40:53 canect2 daemon.info ModemManager[252]: [1522788053.489053] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (idle -> unknown) Apr 3 20:40:53 canect2 daemon.debug ModemManager[252]: [1522788053.666272] (ttyACM1): <-- '+CIEV: 2,2' Apr 3 20:40:53 canect2 daemon.debug ModemManager[252]: [1522788053.666996] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (40) Apr 3 20:40:55 canect2 daemon.debug ModemManager[252]: [1522788055.060208] (ttyACM1): <-- '+CGREG: 1,"XXXX","XXXXXXXX",6,"6C"+CIEV: 9,2+CEREG: 4' Apr 3 20:40:55 canect2 daemon.info ModemManager[252]: [1522788055.060971] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering) Apr 3 20:40:55 canect2 daemon.debug ModemManager[252]: [1522788055.061281] loading Operator Code... Apr 3 20:40:55 canect2 daemon.debug ModemManager[252]: [1522788055.061547] (ttyACM0) device open count is 4 (open) Apr 3 20:40:55 canect2 daemon.debug ModemManager[252]: [1522788055.062068] Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> hspa) Apr 3 20:40:55 canect2 daemon.debug ModemManager[252]: [1522788055.062407] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0', MNC: '0', Location area code: 'XXXX', Cell ID: 'XXXXXXX') Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.606142] (ttyACM0): <-- 'OK+CEREG: 3+CGREG: 1,"XXXX","XXXXXXXX",6,"6C"+CEREG: 4+CIEV: 9,1+CIEV: 2,2 [1522788089.606881] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (40) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.608089] Running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes') Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.608319] (ttyACM0) device open count is 5 (open) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.608499] (ttyACM0) device open count is 4 (close) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.608746] (ttyACM0): --> 'AT+CREG?' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.609631] (ttyACM1): <-- '+CREG: 1,"XXXX","XXXXXXXX",6+CIEV: 3,1' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.610950] (ttyACM0): <-- '+CREG: 2,1,"XXXX","XXXXXXXX",6OK' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.611802] (ttyACM0) device open count is 5 (open) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.612181] (ttyACM0) device open count is 4 (close) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.612545] (ttyACM0): --> 'AT+COPS=3,2;+COPS?' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.614280] (ttyACM0): <-- '+COPS: 0,2,"XXXXXXXXXXXXXXXXXXXXXXXX",6OK' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.615585] loaded Operator Code: XXXXXX Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.616236] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: 'XXX', MNC: 'XXX', Location area code: 'XXXX', Cell ID: 'XXXXXXX') Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.616525] loading Operator Name... Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.616777] (ttyACM0) device open count is 5 (open) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.617055] (ttyACM0) device open count is 4 (close) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.620390] (ttyACM0): --> 'AT+CREG?' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.622536] (ttyACM0): <-- '+CREG: 2,1,"XXXX","XXXXXXXX",6OK' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.623492] (ttyACM0) device open count is 5 (open) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.623873] (ttyACM0) device open count is 4 (close) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.624235] (ttyACM0): --> 'AT+CGREG?' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.626906] (ttyACM0): <-- '+CGREG: 2,1,"XXXX","XXXXXXXX",6,"6C"OK' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.627798] (ttyACM0) device open count is 5 (open) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.628170] (ttyACM0) device open count is 4 (close) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.628538] (ttyACM0): --> 'AT+COPS=3,0;+COPS?' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.880423] (ttyACM0): <-- '+COPS: 0,0,"0041005400260054",6OK' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.881255] loaded Operator Name: AT&T Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.881781] (ttyACM0) device open count is 3 (close) Apr 3 20:41:29 canect2 daemon.info ModemManager[252]: [1522788089.884912] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home) Apr 3 20:41:29 canect2 daemon.info ModemManager[252]: [1522788089.885508] Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabled -> registered) Apr 3 20:41:29 canect2 daemon.info NetworkManager[258]: (ttyACM0): modem state changed, 'enabled' --> 'registered' (reason: unknown) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.891482] Periodic signal checks enabled Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.891731] Periodic signal check refresh requested Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.891898] loading signal quality... Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.892158] (ttyACM0) device open count is 4 (open) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.895265] (ttyACM0): --> 'AT+CGREG?' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.899051] (ttyACM0): <-- '+CGREG: 2,1,"XXXX","XXXXXXXX",6,"6C"OK' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.899900] (ttyACM0) device open count is 5 (open) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.900278] (ttyACM0) device open count is 4 (close) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.900639] (ttyACM0): --> 'AT+CEREG?' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.903400] (ttyACM0): <-- '+CEREG: 2,0OK' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.904144] Initial 3GPP registration checks finished Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.904428] (ttyACM0) device open count is 3 (close) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.904755] (ttyACM0): --> 'AT+CIND?' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.907784] (ttyACM0): <-- '+CIND: 5,2,1,0,0,0,0,0,2,0,0,0OK' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.908781] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (40) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.909212] (ttyACM0) device open count is 2 (close) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.909581] Polling to refresh access technologies is unsupported Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.909829] Initial signal quality and access technology ready: fallback to default frequency Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.910035] Periodic signal quality checks scheduled in 30s Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.910331] (ttyACM0): --> 'AT+CEREG?' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.913040] (ttyACM0): <-- '+CEREG: 2,0OK' Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.913796] Periodic signal check refresh requested Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.914065] loading signal quality... Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.914339] (ttyACM0) device open count is 3 (open) Apr 3 20:41:29 canect2 daemon.debug ModemManager[252]: [1522788089.914637] Modem is currently registered in a 3GPP network Apr 3 20:41:29 canect2 daemon.info ModemManager[252]: [1522788089.914849] Simple connect state (6/8): Bearer