mbed-os-example-cellular Built: Jan 10 2020, 12:10:31 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][DBG ][CELL]: AT flush [00007943ms][INFO][CELL]: AT RX (16): RDYATOK [00007944ms][INFO][CELL]: AT TX ( 4): ATE0 [00007945ms][INFO][CELL]: AT TX ( 1): [00007945ms][DBG ][CELL]: Modem ready to receive AT commands [00007951ms][DBG ][CELL]: Enable flow control [00007956ms][INFO][CELL]: AT TX ( 7): AT+IFC= [00007962ms][INFO][CELL]: AT TX ( 1): 2 [00007967ms][INFO][CELL]: AT TX ( 1): , [00007971ms][INFO][CELL]: AT TX ( 1): 2 [00007976ms][INFO][CELL]: AT TX ( 1): [00007981ms][INFO][CELL]: AT RX (17): ATE0OKOK [00007990ms][DBG ][CELL]: Flow control turned ON [00008495ms][INFO][CELL]: AT TX ( 7): AT+IFC? [00008495ms][INFO][CELL]: AT TX ( 1): [00008496ms][DBG ][CELL]: AT flush [00008497ms][INFO][CELL]: AT RX ( 6): +IFC [00008498ms][INFO][CELL]: AT RX ( 8): : 2,2 [00008500ms][INFO][CELL]: AT RX ( 5): OK [00008506ms][INFO][CELL]: AT TX ( 4): ATE0 [00008511ms][INFO][CELL]: AT TX ( 1): [00008516ms][INFO][CELL]: AT RX ( 6): OK [00008522ms][INFO][CELL]: AT TX ( 9): AT+CMEE=1 [00008528ms][INFO][CELL]: AT TX ( 1): [00008533ms][INFO][CELL]: AT RX (13): OKAPP R [00008540ms][INFO][CELL]: AT TX ( 9): AT+CFUN=1 [00008546ms][INFO][CELL]: AT TX ( 1): [00008551ms][INFO][CELL]: AT RX (10): DYOK [00008558ms][INFO][CELL]: Modem ready [00008562ms][DBG ][CELL]: callback: 4096, err: 0, data: 4096 [00008569ms][DBG ][CELL]: Device ready => SIM pin [00008575ms][DBG ][CELL]: callback: 4106, err: 0, data: 4097 [00008581ms][INFO][CELL]: Setup SIM (timeout 1000 ms) [00008587ms][DBG ][CELL]: AT flush [00008592ms][INFO][CELL]: AT TX ( 8): AT+CPIN? [00008597ms][INFO][CELL]: AT TX ( 1): [00008602ms][INFO][CELL]: AT RX (22): +CPIN: READYOK [00008611ms][INFO][CELL]: SIM is ready [00008615ms][DBG ][CELL]: callback: 4097, err: 0, data: 0 [00009622ms][INFO][CELL]: AT TX ( 7): AT+CIMI [00009622ms][INFO][CELL]: AT TX ( 1): [00009625ms][INFO][CELL]: AT RX (11): 466011300 [00009626ms][INFO][CELL]: AT RX ( 9): 005838 [00009626ms][INFO][CELL]: AT RX ( 5): OK [00009629ms][INFO][CELL]: Looked up APN internet [00009635ms][INFO][CELL]: AT_CellularNetwork::clear [00009641ms][INFO][CELL]: AT TX (11): AT+CGDCONT? [00009646ms][INFO][CELL]: AT TX ( 1): [00009651ms][INFO][CELL]: AT RX (31): +CGDCONT: 1,"IPV4V6","interne [00009659ms][INFO][CELL]: AT RX (32): t.iot","0.0.0.0.0.0.0.0.0.0.0.0. [00009667ms][INFO][CELL]: AT RX (24): 0.0.0.0",0,0,0,0OK [00009675ms][DBG ][CELL]: Automatic network registration [00009681ms][INFO][CELL]: AT TX ( 8): AT+COPS? [00009687ms][INFO][CELL]: AT TX ( 1): [00009692ms][INFO][CELL]: AT RX (18): +COPS: 0OK [00009700ms][DBG ][CELL]: STM: set_registration: 0, plmn: NULL [00009707ms][INFO][CELL]: AT TX ( 9): AT+CEREG= [00009712ms][INFO][CELL]: AT TX ( 1): 2 [00009717ms][INFO][CELL]: AT TX ( 1): [00009722ms][INFO][CELL]: AT RX ( 6): OK [00009728ms][INFO][CELL]: AT TX ( 9): AT+CGREG= [00009734ms][INFO][CELL]: AT TX ( 1): 2 [00009738ms][INFO][CELL]: AT TX ( 1): [00009743ms][INFO][CELL]: AT RX ( 6): OK [00009750ms][INFO][CELL]: AT TX ( 8): AT+CREG= [00009755ms][INFO][CELL]: AT TX ( 1): 2 [00009760ms][INFO][CELL]: AT TX ( 1): [00009765ms][INFO][CELL]: AT RX ( 6): OK [00009771ms][INFO][CELL]: AT TX ( 9): AT+QIACT? [00009777ms][INFO][CELL]: AT TX ( 1): [00009782ms][INFO][CELL]: AT RX ( 6): OK [00009788ms][INFO][CELL]: AT TX ( 9): AT+CGATT? [00009793ms][INFO][CELL]: AT TX ( 1): [00009798ms][INFO][CELL]: AT RX (19): +CGATT: 0OK [00009807ms][INFO][CELL]: AT TX (10): AT+CGEREP= [00009812ms][INFO][CELL]: AT TX ( 1): 1 [00009817ms][INFO][CELL]: AT TX ( 1): [00009822ms][INFO][CELL]: AT RX ( 6): OK [00009828ms][DBG ][CELL]: SIM pin => Signal quality sending (AT+QCFG="band") to modem... [00009838ms][DBG ][CELL]: AT flush [00009842ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00009848ms][INFO][CELL]: AT TX ( 1): [00009853ms][INFO][CELL]: AT RX (32): +QCFG: "band",0x1,0x80,0xa0e18 [00009861ms][INFO][CELL]: AT RX (10): 9fOK +QCFG: band,0x1,0x80,0xa0e189f [00009871ms][INFO][CELL]: AT TX ( 6): AT+CSQ [00009876ms][INFO][CELL]: AT TX ( 1): [00009881ms][INFO][CELL]: AT RX (21): +CSQ: 99,99OK [00009889ms][DBG ][CELL]: callback: 4104, err: 0, data: -1 [00009896ms][DBG ][CELL]: Signal quality => Registering network sending (AT+QCFG="band") to modem... [00009906ms][DBG ][CELL]: AT flush [00009910ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00009916ms][INFO][CELL]: AT TX ( 1): [00009921ms][INFO][CELL]: AT RX (32): +QCFG: "band",0x1,0x80,0xa0e18 [00009929ms][INFO][CELL]: AT RX (10): 9fOK +QCFG: band,0x1,0x80,0xa0e189f [00009939ms][INFO][CELL]: AT TX ( 6): AT+CSQ [00009944ms][INFO][CELL]: AT TX ( 1): [00009949ms][INFO][CELL]: AT RX (21): +CSQ: 99,99OK [00009958ms][DBG ][CELL]: callback: 4104, err: 0, data: -1 [00009964ms][INFO][CELL]: RSSI unknown [00009969ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00009976ms][INFO][CELL]: AT TX ( 9): AT+CEREG? [00009981ms][INFO][CELL]: AT TX ( 1): [00009986ms][INFO][CELL]: AT RX (21): +CEREG: 2,2OK [00009995ms][DBG ][CELL]: +CEREG: SearchingNetwork, LAC -1, cell -1, RAT unknown [00010003ms][INFO][CELL]: AT TX ( 9): AT+CGREG? [00010009ms][INFO][CELL]: AT TX ( 1): [00010014ms][INFO][CELL]: AT RX (21): +CGREG: 2,4OK [00010022ms][DBG ][CELL]: +CGREG: RegistrationUnknown, LAC -1, cell -1, RAT unknown [00010031ms][INFO][CELL]: Network registration (timeout 180000 ms) [00010038ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00010044ms][DBG ][CELL]: Automatic network registration [00010051ms][INFO][CELL]: AT TX ( 8): AT+COPS? [00010056ms][INFO][CELL]: AT TX ( 1): [00010061ms][INFO][CELL]: AT RX (18): +COPS: 0OK [00010069ms][DBG ][CELL]: Registering network: retry 0/10 [00010076ms][DBG ][CELL]: callback: CellularStateRetryEvent, err: 0, data: 4098, retrycount: 0 [00010085ms][INFO][CELL]: Continue after 1 seconds sending (AT+QCFG="band") to modem... [00011091ms][DBG ][CELL]: AT flush [00011091ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00011092ms][INFO][CELL]: AT TX ( 1): [00011094ms][INFO][CELL]: AT RX ( 2): [00011095ms][INFO][CELL]: AT RX ( 8): +QCFG: " [00011098ms][INFO][CELL]: AT RX (32): band",0x1,0x80,0xa0e189fOK +QCFG: band,0x1,0x80,0xa0e189f [00011110ms][INFO][CELL]: AT TX ( 6): AT+CSQ [00011116ms][INFO][CELL]: AT TX ( 1): [00011121ms][INFO][CELL]: AT RX (21): +CSQ: 99,99OK [00011129ms][DBG ][CELL]: callback: 4104, err: 0, data: 4098 [00011136ms][INFO][CELL]: RSSI unknown [00011140ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00011147ms][INFO][CELL]: AT TX ( 9): AT+CEREG? [00011152ms][INFO][CELL]: AT TX ( 1): [00011158ms][INFO][CELL]: AT RX (21): +CEREG: 2,2OK [00011166ms][DBG ][CELL]: +CEREG: SearchingNetwork, LAC -1, cell -1, RAT unknown [00011174ms][INFO][CELL]: AT TX ( 9): AT+CGREG? [00011180ms][INFO][CELL]: AT TX ( 1): [00011185ms][INFO][CELL]: AT RX (21): +CGREG: 2,4OK [00011194ms][DBG ][CELL]: +CGREG: RegistrationUnknown, LAC -1, cell -1, RAT unknown [00011202ms][INFO][CELL]: Network registration (timeout 180000 ms) [00011209ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00011216ms][DBG ][CELL]: Registering network: retry 1/10 [00011222ms][DBG ][CELL]: callback: CellularStateRetryEvent, err: 0, data: 4098, retrycount: 1 [00011232ms][INFO][CELL]: Continue after 2 seconds sending (AT+QCFG="band") to modem... [00013237ms][DBG ][CELL]: AT flush [00013238ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00013238ms][INFO][CELL]: AT TX ( 1): [00013240ms][INFO][CELL]: AT RX ( 2): [00013241ms][INFO][CELL]: AT RX ( 7): +QCFG: [00013244ms][INFO][CELL]: AT RX (32): "band",0x1,0x80,0xa0e189fOK [00013253ms][INFO][CELL]: AT RX ( 1): +QCFG: band,0x1,0x80,0xa0e189f [00013261ms][INFO][CELL]: AT TX ( 6): AT+CSQ [00013266ms][INFO][CELL]: AT TX ( 1): [00013271ms][INFO][CELL]: AT RX (21): +CSQ: 99,99OK [00013280ms][DBG ][CELL]: callback: 4104, err: 0, data: 4098 [00013286ms][INFO][CELL]: RSSI unknown [00013291ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00013298ms][INFO][CELL]: AT TX ( 9): AT+CEREG? [00013303ms][INFO][CELL]: AT TX ( 1): [00013308ms][INFO][CELL]: AT RX (21): +CEREG: 2,2OK [00013317ms][DBG ][CELL]: +CEREG: SearchingNetwork, LAC -1, cell -1, RAT unknown [00013325ms][INFO][CELL]: AT TX ( 9): AT+CGREG? [00013331ms][INFO][CELL]: AT TX ( 1): [00013336ms][INFO][CELL]: AT RX (21): +CGREG: 2,4OK [00013344ms][DBG ][CELL]: +CGREG: RegistrationUnknown, LAC -1, cell -1, RAT unknown [00013353ms][INFO][CELL]: Network registration (timeout 180000 ms) [00013360ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00013367ms][DBG ][CELL]: Registering network: retry 2/10 [00013373ms][DBG ][CELL]: callback: CellularStateRetryEvent, err: 0, data: 4098, retrycount: 2 [00013382ms][INFO][CELL]: Continue after 4 seconds sending (AT+QCFG="band") to modem... [00017388ms][DBG ][CELL]: AT flush [00017388ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00017389ms][INFO][CELL]: AT TX ( 1): [00017391ms][INFO][CELL]: AT RX ( 2): [00017392ms][INFO][CELL]: AT RX ( 8): +QCFG: " [00017396ms][INFO][CELL]: AT RX (32): band",0x1,0x80,0xa0e189fOK +QCFG: band,0x1,0x80,0xa0e189f [00017407ms][INFO][CELL]: AT TX ( 6): AT+CSQ [00017413ms][INFO][CELL]: AT TX ( 1): [00017418ms][INFO][CELL]: AT RX (21): +CSQ: 99,99OK [00017426ms][DBG ][CELL]: callback: 4104, err: 0, data: 4098 [00017433ms][INFO][CELL]: RSSI unknown [00017437ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00017444ms][INFO][CELL]: AT TX ( 9): AT+CEREG? [00017449ms][INFO][CELL]: AT TX ( 1): [00017455ms][INFO][CELL]: AT RX (21): +CEREG: 2,2OK [00017463ms][DBG ][CELL]: +CEREG: SearchingNetwork, LAC -1, cell -1, RAT unknown [00017471ms][INFO][CELL]: AT TX ( 9): AT+CGREG? [00017477ms][INFO][CELL]: AT TX ( 1): [00017482ms][INFO][CELL]: AT RX (21): +CGREG: 2,4OK [00017491ms][DBG ][CELL]: +CGREG: RegistrationUnknown, LAC -1, cell -1, RAT unknown [00017499ms][INFO][CELL]: Network registration (timeout 180000 ms) [00017506ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00017513ms][DBG ][CELL]: Registering network: retry 3/10 [00017519ms][DBG ][CELL]: callback: CellularStateRetryEvent, err: 0, data: 4098, retrycount: 3 [00017529ms][INFO][CELL]: Continue after 8 seconds sending (AT+QCFG="band") to modem... [00025534ms][DBG ][CELL]: AT flush [00025535ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00025535ms][INFO][CELL]: AT TX ( 1): [00025537ms][INFO][CELL]: AT RX ( 1): [00025538ms][INFO][CELL]: AT RX ( 8): +QCFG: [00025541ms][INFO][CELL]: AT RX (32): "band",0x1,0x80,0xa0e189fOK [00025550ms][INFO][CELL]: AT RX ( 1): +QCFG: band,0x1,0x80,0xa0e189f [00025558ms][INFO][CELL]: AT TX ( 6): AT+CSQ [00025563ms][INFO][CELL]: AT TX ( 1): [00025568ms][INFO][CELL]: AT RX (21): +CSQ: 99,99OK [00025577ms][DBG ][CELL]: callback: 4104, err: 0, data: 4098 [00025583ms][INFO][CELL]: RSSI unknown [00025588ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00025595ms][INFO][CELL]: AT TX ( 9): AT+CEREG? [00025600ms][INFO][CELL]: AT TX ( 1): [00025605ms][INFO][CELL]: AT RX (21): +CEREG: 2,2OK [00025614ms][DBG ][CELL]: +CEREG: SearchingNetwork, LAC -1, cell -1, RAT unknown [00025622ms][INFO][CELL]: AT TX ( 9): AT+CGREG? [00025628ms][INFO][CELL]: AT TX ( 1): [00025633ms][INFO][CELL]: AT RX (21): +CGREG: 2,4OK [00025641ms][DBG ][CELL]: +CGREG: RegistrationUnknown, LAC -1, cell -1, RAT unknown [00025650ms][INFO][CELL]: Network registration (timeout 180000 ms) [00025657ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00025664ms][DBG ][CELL]: Registering network: retry 4/10 [00025670ms][DBG ][CELL]: callback: CellularStateRetryEvent, err: 0, data: 4098, retrycount: 4 [00025679ms][INFO][CELL]: Continue after 16 seconds sending (AT+QCFG="band") to modem... [00041685ms][DBG ][CELL]: AT flush [00041686ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00041686ms][INFO][CELL]: AT TX ( 1): [00041688ms][INFO][CELL]: AT RX ( 1): [00041689ms][INFO][CELL]: AT RX ( 8): +QCFG: [00041692ms][INFO][CELL]: AT RX (32): "band",0x1,0x80,0xa0e189fOK [00041701ms][INFO][CELL]: AT RX ( 1): +QCFG: band,0x1,0x80,0xa0e189f [00041709ms][INFO][CELL]: AT TX ( 6): AT+CSQ [00041714ms][INFO][CELL]: AT TX ( 1): [00041719ms][INFO][CELL]: AT RX (21): +CSQ: 99,99OK [00041728ms][DBG ][CELL]: callback: 4104, err: 0, data: 4098 [00041734ms][INFO][CELL]: RSSI unknown [00041739ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00041746ms][INFO][CELL]: AT TX ( 9): AT+CEREG? [00041751ms][INFO][CELL]: AT TX ( 1): [00041756ms][INFO][CELL]: AT RX (21): +CEREG: 2,2OK [00041765ms][DBG ][CELL]: +CEREG: SearchingNetwork, LAC -1, cell -1, RAT unknown [00041773ms][INFO][CELL]: AT TX ( 9): AT+CGREG? [00041779ms][INFO][CELL]: AT TX ( 1): [00041784ms][INFO][CELL]: AT RX (21): +CGREG: 2,4OK [00041792ms][DBG ][CELL]: +CGREG: RegistrationUnknown, LAC -1, cell -1, RAT unknown [00041801ms][INFO][CELL]: Network registration (timeout 180000 ms) [00041808ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00041815ms][DBG ][CELL]: Registering network: retry 5/10 [00041821ms][DBG ][CELL]: callback: CellularStateRetryEvent, err: 0, data: 4098, retrycount: 5 [00041830ms][INFO][CELL]: Continue after 32 seconds sending (AT+QCFG="band") to modem... [00073836ms][DBG ][CELL]: AT flush [00073837ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00073837ms][INFO][CELL]: AT TX ( 1): [00073839ms][INFO][CELL]: AT RX ( 1): [00073840ms][INFO][CELL]: AT RX ( 8): +QCFG: [00073843ms][INFO][CELL]: AT RX (32): "band",0x1,0x80,0xa0e189fOK [00073852ms][INFO][CELL]: AT RX ( 1): +QCFG: band,0x1,0x80,0xa0e189f [00073860ms][INFO][CELL]: AT TX ( 6): AT+CSQ [00073865ms][INFO][CELL]: AT TX ( 1): [00073870ms][INFO][CELL]: AT RX (21): +CSQ: 99,99OK [00073879ms][DBG ][CELL]: callback: 4104, err: 0, data: 4098 [00073885ms][INFO][CELL]: RSSI unknown [00073890ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00073897ms][INFO][CELL]: AT TX ( 9): AT+CEREG? [00073902ms][INFO][CELL]: AT TX ( 1): [00073907ms][INFO][CELL]: AT RX (21): +CEREG: 2,2OK [00073916ms][DBG ][CELL]: +CEREG: SearchingNetwork, LAC -1, cell -1, RAT unknown [00073924ms][INFO][CELL]: AT TX ( 9): AT+CGREG? [00073930ms][INFO][CELL]: AT TX ( 1): [00073935ms][INFO][CELL]: AT RX (21): +CGREG: 2,4OK [00073943ms][DBG ][CELL]: +CGREG: RegistrationUnknown, LAC -1, cell -1, RAT unknown [00073952ms][INFO][CELL]: Network registration (timeout 180000 ms) [00073959ms][DBG ][CELL]: callback: 4106, err: 0, data: 4098 [00073966ms][DBG ][CELL]: Registering network: retry 6/10 [00073972ms][DBG ][CELL]: callback: CellularStateRetryEvent, err: 0, data: 4098, retrycount: 6 [00073981ms][INFO][CELL]: Continue after 64 seconds sending (AT+QCFG="band") to modem... [00137987ms][DBG ][CELL]: AT flush [00137988ms][INFO][CELL]: AT TX (14): AT+QCFG="band" [00137988ms][INFO][CELL]: AT TX ( 1): [00137990ms][INFO][CELL]: AT RX ( 2):