mbed-os-example-cellular Built: Jan 17 2020, 13:39:11 Establishing connection [00000001ms][DBG ][CELL]: MODEM default instance [00000001ms][DBG ][CELL]: STMOD_CELLULAR flow control: RTS 108 CTS 107 [00000002ms][DBG ][CELL]: STModCellular creation [00000455ms][INFO][CELL]: New CellularContext (0x200052b8) [00000455ms][INFO][CELL]: CellularContext connect [00000456ms][DBG ][CELL]: callback: 4106, err: 0, data: 4096 [00000457ms][INFO][CELL]: Start connecting (timeout 1000 ms) [00000459ms][INFO][CELL]: AT TX ( 2): AT [00000464ms][INFO][CELL]: AT TX ( 1): [00001460ms][DBG ][CELL]: AT error -3012 [00001460ms][INFO][CELL]: AT TX ( 2): AT [00001461ms][INFO][CELL]: AT TX ( 1): [00002461ms][DBG ][CELL]: AT error -3012 [00002461ms][DBG ][CELL]: Init => Power [00002462ms][DBG ][CELL]: callback: 4106, err: 0, data: 4096 [00002462ms][INFO][CELL]: Modem power ON (timeout 1000 ms) [00002463ms][DBG ][CELL]: Power => Device ready [00002468ms][DBG ][CELL]: callback: 4106, err: 0, data: 4096 [00002474ms][DBG ][CELL]: Device was not ready, calling soft_power_on() [00002482ms][DBG ][CELL]: STMOD cellular modem power ON [00002488ms][DBG ][CELL]: Booting BG96 [00007943ms][INFO][CELL]: AT TX ( 7): AT+IFC= [00007943ms][INFO][CELL]: AT TX ( 1): 2 [00007944ms][INFO][CELL]: AT TX ( 1): , [00007945ms][INFO][CELL]: AT TX ( 1): 2 [00007945ms][INFO][CELL]: AT TX ( 1): [00007946ms][INFO][CELL]: AT RX (25): RDYATOKAT+IFC=2, [00008055ms][DBG ][CELL]: AT flush [00008055ms][INFO][CELL]: AT RX ( 8): 2OK [00008056ms][INFO][CELL]: AT TX ( 4): ATE0 [00008056ms][INFO][CELL]: AT TX ( 1): [00008057ms][DBG ][CELL]: Modem ready to receive AT commands [00008061ms][DBG ][CELL]: Enable flow control [00008067ms][INFO][CELL]: AT TX ( 7): AT+IFC= [00008072ms][INFO][CELL]: AT TX ( 1): 2 [00008077ms][INFO][CELL]: AT TX ( 1): , [00008082ms][INFO][CELL]: AT TX ( 1): 2 [00008086ms][INFO][CELL]: AT TX ( 1): [00008091ms][INFO][CELL]: AT RX (17): ATE0OKOK [00008100ms][DBG ][CELL]: Flow control turned ON [00008606ms][INFO][CELL]: AT TX ( 7): AT+IFC? [00008606ms][INFO][CELL]: AT TX ( 1): [00008607ms][INFO][CELL]: AT RX (11): APP RDY [00008608ms][DBG ][CELL]: AT flush [00008609ms][INFO][CELL]: AT RX (17): +IFC: 2,2OK [00008614ms][INFO][CELL]: AT RX ( 2): [00008619ms][INFO][CELL]: AT TX ( 4): ATE0 [00008624ms][INFO][CELL]: AT TX ( 1): [00008629ms][INFO][CELL]: AT RX ( 6): OK [00008635ms][INFO][CELL]: AT TX ( 9): AT+CMEE=1 [00008641ms][INFO][CELL]: AT TX ( 1): [00008646ms][INFO][CELL]: AT RX ( 6): OK [00008652ms][INFO][CELL]: AT TX ( 9): AT+CFUN=1 [00008658ms][INFO][CELL]: AT TX ( 1): [00008663ms][INFO][CELL]: AT RX ( 6): OK [00008669ms][INFO][CELL]: Modem ready [00008673ms][INFO][CELL]: AT TX (26): AT+QCFG="nwscanseq",030201 [00008680ms][INFO][CELL]: AT TX ( 1): [00008687ms][INFO][CELL]: AT RX ( 6): OK [00008692ms][INFO][CELL]: AT TX (24): AT+QCFG="nwscanmode",3,1 [00008698ms][INFO][CELL]: AT TX ( 1): [00008703ms][INFO][CELL]: AT RX ( 6): OK [00008710ms][INFO][CELL]: AT TX (23): AT+QCFG="iotopmode",1,1 [00008716ms][INFO][CELL]: AT TX ( 1): [00008721ms][INFO][CELL]: AT RX ( 6): OK [00008728ms][DBG ][CELL]: callback: 4096, err: 0, data: 4096 [00008734ms][DBG ][CELL]: Device ready => SIM pin [00008740ms][DBG ][CELL]: callback: 4106, err: 0, data: 4097 [00008746ms][INFO][CELL]: Setup SIM (timeout 1000 ms) [00008752ms][DBG ][CELL]: AT flush [00008757ms][INFO][CELL]: AT TX ( 8): AT+CPIN? [00008762ms][INFO][CELL]: AT TX ( 1): [00008767ms][INFO][CELL]: AT RX (22): +CPIN: READYOK [00008776ms][INFO][CELL]: SIM is ready [00008781ms][DBG ][CELL]: callback: 4097, err: 0, data: 0 [00009787ms][INFO][CELL]: AT TX ( 7): AT+CIMI [00009787ms][INFO][CELL]: AT TX ( 1): [00009790ms][INFO][CELL]: AT RX (10): 46692470 [00009791ms][INFO][CELL]: AT RX ( 9): 0000586 [00009791ms][INFO][CELL]: AT RX ( 6): OK [00009794ms][INFO][CELL]: Looked up APN internet [00009800ms][DBG ][CELL]: Automatic network registration [00009806ms][INFO][CELL]: AT TX ( 8): AT+COPS? [00009811ms][INFO][CELL]: AT TX ( 1): [00009816ms][INFO][CELL]: AT RX (18): +COPS: 1OK [00009825ms][INFO][CELL]: AT TX ( 9): AT+COPS=0 [00009830ms][INFO][CELL]: AT TX ( 1): [00009835ms][INFO][CELL]: AT RX ( 5): OK [00009841ms][INFO][CELL]: AT RX ( 1): [00009846ms][DBG ][CELL]: STM: set_registration: 0, plmn: NULL [00009853ms][INFO][CELL]: AT TX ( 9): AT+CEREG= [00009858ms][INFO][CELL]: AT TX ( 1): 2 [00009863ms][INFO][CELL]: AT TX ( 1): [00009868ms][INFO][CELL]: AT RX ( 6): OK [00009874ms][INFO][CELL]: AT TX ( 9): AT+CGREG= [00009880ms][INFO][CELL]: AT TX ( 1): 2 [00009885ms][INFO][CELL]: AT TX ( 1): [00009890ms][INFO][CELL]: AT RX ( 6): OK [00009896ms][INFO][CELL]: AT TX ( 8): AT+CREG= [00009901ms][INFO][CELL]: AT TX ( 1): 2 [00009906ms][INFO][CELL]: AT TX ( 1): [00009911ms][INFO][CELL]: AT RX ( 6): OK [00009917ms][INFO][CELL]: AT TX ( 9): AT+QIACT? [00009923ms][INFO][CELL]: AT TX ( 1): [00009928ms][INFO][CELL]: AT RX ( 6): OK [00009934ms][INFO][CELL]: AT TX ( 9): AT+CGATT? [00009940ms][INFO][CELL]: AT TX ( 1): [00009945ms][INFO][CELL]: AT RX (19): +CGATT: 0OK [00009953ms][INFO][CELL]: AT TX (10): AT+CGEREP= [00009959ms][INFO][CELL]: AT TX ( 1): 1 [00009963ms][INFO][CELL]: AT TX ( 1): [00009968ms][INFO][CELL]: AT RX ( 6): OK [00009975ms][DBG ][CELL]: SIM pin => Signal quality sending (AT+QCFG="band") to modem... [00009984ms][DBG ][CELL]: AT flush [00009988ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00009994ms][INFO][CELL]: AT TX ( 1): [00009999ms][INFO][CELL]: AT RX (32): +QCFG: "band",0x1,0x8000084,0x [00010007ms][INFO][CELL]: AT RX (15): 8000084OK +QCFG: band = band,0x1,0x8000084,0x8000084 sending (AT+QCFG="nwscanseq") to modem... [00010022ms][DBG ][CELL]: AT flush [00010026ms][INFO][CELL]: AT TX (19): AT+QCFG="nwscanseq" [00010033ms][INFO][CELL]: AT TX ( 1): [00010038ms][INFO][CELL]: AT RX (32): +QCFG: "nwscanseq",030201O [00010047ms][INFO][CELL]: AT RX ( 3): K +QCFG: nwscanseq = nwscanseq,030201 sending (AT+QCFG="nwscanmode") to modem... [00010059ms][DBG ][CELL]: AT flush [00010064ms][INFO][CELL]: AT TX (20): AT+QCFG="nwscanmode" [00010070ms][INFO][CELL]: AT TX ( 1): [00010075ms][INFO][CELL]: AT RX (31): +QCFG: "nwscanmode",0OK +QCFG: nwscanmode = nwscanmode,0 sending (AT+QCFG="iotopmode") to modem... [00010091ms][DBG ][CELL]: AT flush [00010096ms][INFO][CELL]: AT TX (19): AT+QCFG="iotopmode" [00010102ms][INFO][CELL]: AT TX ( 1): [00010107ms][INFO][CELL]: AT RX (30): +QCFG: "iotopmode",1OK +QCFG: iotopmode = iotopmode,1 [00010119ms][INFO][CELL]: AT TX ( 6): AT+CSQ [00010124ms][INFO][CELL]: AT TX ( 1): [00010129ms][INFO][CELL]: AT RX (21): +CSQ: 99,99OK [00010138ms][DBG ][CELL]: callback: 4104, err: 0, data: -1 [00010144ms][DBG ][CELL]: Signal quality => Registering network sending (AT+QCFG="band") to modem... [00010154ms][DBG ][CELL]: AT flush [00010159ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00010165ms][INFO][CELL]: AT TX ( 1): [00010170ms][INFO][CELL]: AT RX (32): +QCFG: "band",0x1,0x8000084,0x [00010178ms][INFO][CELL]: AT RX (15): 8000084OK +QCFG: band = band,0x1,0x8000084,0x8000084 sending (AT+QCFG="nwscanseq") to modem... [00010193ms][DBG ][CELL]: AT flush [00010197ms][INFO][CELL]: AT TX (19): AT+QCFG="nwscanseq" [00010204ms][INFO][CELL]: AT TX ( 1): [00010208ms][INFO][CELL]: AT RX (32): +QCFG: "nwscanseq",030201O [00010218ms][INFO][CELL]: AT RX ( 3): K +QCFG: nwscanseq = nwscanseq,030201 sending (AT+QCFG="nwscanmode") to modem... [00010230ms][DBG ][CELL]: AT flush [00010234ms][INFO][CELL]: AT TX (20): AT+QCFG="nwscanmode" [00010241ms][INFO][CELL]: AT TX ( 1): [00010246ms][INFO][CELL]: AT RX (31): +QCFG: "nwscanmode",0OK +QCFG: nwscanmode = nwscanmode,0 sending (AT+QCFG="iotopmode") to modem... [00010262ms][DBG ][CELL]: AT flush [00010266ms][INFO][CELL]: AT TX (19): AT+QCFG="iotopmode" [00010273ms][INFO][CELL]: AT TX ( 1): [00010278ms][INFO][CELL]: AT RX (30): +QCFG: "iotopmode",1OK +QCFG: iotopmode = iotopmode,1 [00010290ms][INFO][CELL]: AT TX ( 6): AT+CSQ [00010295ms][INFO][CELL]: AT TX ( 1): [00010300ms][INFO][CELL]: AT RX (21): +CSQ: 99,99OK [00010309ms][DBG ][CELL]: callback: 4104, err: 0, data: -1 [00010315ms][INFO][CELL]: RSSI unknown [00010320ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00010326ms][INFO][CELL]: AT TX ( 9): AT+CEREG? [00010332ms][INFO][CELL]: AT TX ( 1): [00010337ms][INFO][CELL]: AT RX (21): +CEREG: 2,0OK [00010345ms][DBG ][CELL]: +CEREG: NotRegistered, LAC -1, cell -1, RAT unknown [00010353ms][INFO][CELL]: AT TX ( 9): AT+CGREG? [00010359ms][INFO][CELL]: AT TX ( 1): [00010364ms][INFO][CELL]: AT RX (21): +CGREG: 2,4OK [00010373ms][DBG ][CELL]: +CGREG: RegistrationUnknown, LAC -1, cell -1, RAT unknown [00010381ms][INFO][CELL]: Network registration (timeout 180000 ms) [00010388ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00010395ms][DBG ][CELL]: Automatic network registration [00010401ms][INFO][CELL]: AT TX ( 8): AT+COPS? [00010406ms][INFO][CELL]: AT TX ( 1): [00010412ms][INFO][CELL]: AT RX (18): +COPS: 0OK [00010420ms][DBG ][CELL]: Registering network: retry 0/10 [00010426ms][DBG ][CELL]: callback: CellularStateRetryEventsending (AT+QCFG="band") to modem... [00013694ms][DBG ][CELL]: AT flush [00013694ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00013695ms][INFO][CELL]: AT TX ( 1): [00013697ms][INFO][CELL]: AT RX ( 1): [00013697ms][INFO][CELL]: AT RX ( 8): +QCFG: [00013701ms][INFO][CELL]: AT RX (32): "band",0x1,0x8000084,0x8000084 [00013709ms][INFO][CELL]: AT RX ( 6): OK +QCFG: band = band,0x1,0x8000084,0x8000084 sending (AT+QCFG="nwscanseq") to modem... [00013723ms][DBG ][CELL]: AT flush [00013728ms][INFO][CELL]: AT TX (19): AT+QCFG="nwscanseq" [00013734ms][INFO][CELL]: AT TX ( 1): [00013739ms][INFO][CELL]: AT RX (32): +QCFG: "nwscanseq",030201O [00013748ms][INFO][CELL]: AT RX ( 3): K +QCFG: nwscanseq = nwscanseq,030201 sending (AT+QCFG="nwscanmode") to modem... [00013760ms][DBG ][CELL]: AT flush [00013765ms][INFO][CELL]: AT TX (20): AT+QCFG="nwscanmode" [00013771ms][INFO][CELL]: AT TX ( 1): [00013776ms][INFO][CELL]: AT RX (31): +QCFG: "nwscanmode",0OK +QCFG: nwscanmode = nwscanmode,0 sending (AT+QCFG="iotopmode") to modem... [00013792ms][DBG ][CELL]: AT flush [00013797ms][INFO][CELL]: AT TX (19): AT+QCFG="iotopmode" [00013803ms][INFO][CELL]: AT TX ( 1): [00013808ms][INFO][CELL]: AT RX (30): +QCFG: "iotopmode",1OK +QCFG: iotopmode = iotopmode,1 [00013820ms][INFO][CELL]: AT TX ( 6): AT+CSQ [00013825ms][INFO][CELL]: AT TX ( 1): [00013830ms][INFO][CELL]: AT RX (21): +CSQ: 99,99OK [00013839ms][DBG ][CELL]: callback: 4104, err: 0, data: 4098 [00013846ms][INFO][CELL]: RSSI unknown [00013850ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00013857ms][INFO][CELL]: AT TX ( 9): AT+CEREG? [00013862ms][INFO][CELL]: AT TX ( 1): [00013867ms][INFO][CELL]: AT RX (21): +CEREG: 2,2OK [00013876ms][DBG ][CELL]: +CEREG: SearchingNetwork, LAC -1, cell -1, RAT unknown [00013884ms][INFO][CELL]: AT TX ( 9): AT+CGREG? [00013890ms][INFO][CELL]: AT TX ( 1): [00013895ms][INFO][CELL]: AT RX (21): +CGREG: 2,4OK [00013903ms][DBG ][CELL]: +CGREG: RegistrationUnknown, LAC -1, cell -1, RAT unknown [00013912ms][INFO][CELL]: Network registration (timeout 180000 ms) [00013919ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00013926ms][DBG ][CELL]: Registering network: retry 2/10 [00013932ms][DBG ][CELL]: callback: CellularStateRetryEvent, err: 0, data: 4098, retrycount: 2 [00013942ms][INFO][CELL]: Continue after 4 seconds sending (AT+QCFG="band") to modem... [00017947ms][DBG ][CELL]: AT flush [00017947ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00017948ms][INFO][CELL]: AT TX ( 1): [00017950ms][INFO][CELL]: AT RX ( 2): [00017951ms][INFO][CELL]: AT RX ( 8): +QCFG: " [00017955ms][INFO][CELL]: AT RX (32): band",0x1,0x8000084,0x8000084 [00017963ms][INFO][CELL]: AT RX ( 5): OK +QCFG: band = band,0x1,0x8000084,0x8000084 sending (AT+QCFG="nwscanseq") to modem... [00017976ms][DBG ][CELL]: AT flush [00017981ms][INFO][CELL]: AT TX (19): AT+QCFG="nwscanseq" [00017987ms][INFO][CELL]: AT TX ( 1): [00017992ms][INFO][CELL]: AT RX (32): +QCFG: "nwscanseq",030201O [00018001ms][INFO][CELL]: AT RX ( 3): K +QCFG: nwscanseq = nwscanseq,030201 sending (AT+QCFG="nwscanmode") to modem... [00018013ms][DBG ][CELL]: AT flush [00018018ms][INFO][CELL]: AT TX (20): AT+QCFG="nwscanmode" [00018024ms][INFO][CELL]: AT TX ( 1): [00018029ms][INFO][CELL]: AT RX (31): +QCFG: "nwscanmode",0OK +QCFG: nwscanmode = nwscanmode,0 sending (AT+QCFG="iotopmode") to modem... [00018045ms][DBG ][CELL]: AT flush [00018050ms][INFO][CELL]: AT TX (19): AT+QCFG="iotopmode" [00018056ms][INFO][CELL]: AT TX ( 1): [00018061ms][INFO][CELL]: AT RX (30): +QCFG: "iotopmode",1OK +QCFG: iotopmode = iotopmode,1 [00018073ms][INFO][CELL]: AT TX ( 6): AT+CSQ [00018078ms][INFO][CELL]: AT TX ( 1): [00018083ms][INFO][CELL]: AT RX (21): +CSQ: 16,99OK [00018092ms][DBG ][CELL]: callback: 4104, err: 0, data: 4098 [00018099ms][INFO][CELL]: RSSI -81 dBm [00018103ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00018110ms][INFO][CELL]: AT TX ( 9): AT+CEREG? [00018115ms][INFO][CELL]: AT TX ( 1): [00018120ms][INFO][CELL]: AT RX (21): +CEREG: 2,2OK [00018129ms][DBG ][CELL]: +CEREG: SearchingNetwork, LAC -1, cell -1, RAT unknown [00018137ms][INFO][CELL]: AT TX ( 9): AT+CGREG? [00018143ms][INFO][CELL]: AT TX ( 1): [00018148ms][INFO][CELL]: AT RX (21): +CGREG: 2,4OK [00018156ms][DBG ][CELL]: +CGREG: RegistrationUnknown, LAC -1, cell -1, RAT unknown [00018165ms][INFO][CELL]: Network registration (timeout 180000 ms) [00018172ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00018179ms][DBG ][CELL]: Registering network: retry 3/10 [00018185ms][DBG ][CELL]: callback: CellularStateRetryEvent, err: 0, data: 4098, retrycount: 3 [00018195ms][INFO][CELL]: Continue after 8 seconds sending (AT+QCFG="band") to modem... [00026200ms][DBG ][CELL]: AT flush [00026200ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00026201ms][INFO][CELL]: AT TX ( 1): [00026203ms][INFO][CELL]: AT RX ( 1): [00026203ms][INFO][CELL]: AT RX ( 8): +QCFG: [00026207ms][INFO][CELL]: AT RX (32): "band",0x1,0x8000084,0x8000084 [00026215ms][INFO][CELL]: AT RX ( 6): OK +QCFG: band = band,0x1,0x8000084,0x8000084 sending (AT+QCFG="nwscanseq") to modem... [00026229ms][DBG ][CELL]: AT flush [00026234ms][INFO][CELL]: AT TX (19): AT+QCFG="nwscanseq" [00026240ms][INFO][CELL]: AT TX ( 1): [00026245ms][INFO][CELL]: AT RX (32): +QCFG: "nwscanseq",030201O [00026254ms][INFO][CELL]: AT RX ( 3): K +QCFG: nwscanseq = nwscanseq,030201 sending (AT+QCFG="nwscanmode") to modem... [00026266ms][DBG ][CELL]: AT flush [00026271ms][INFO][CELL]: AT TX (20): AT+QCFG="nwscanmode" [00026277ms][INFO][CELL]: AT TX ( 1): [00026282ms][INFO][CELL]: AT RX (31):