[MODEM ] Trigger bg95 power key [MODEM ] >> AT [MODEM ] is_modem_responsive? false [MODEM ] MODEM not ready, waking it up [MODEM ] PWR KEYED, WAIT FOR 'RDY' [MODEM ] <<< [5 @ 1722/5000] RDY [MODEM ] <<< [6] 'RDY' while waiting for 'APP RDY', remaining 5684 ms, urc 99 [MODEM ] <<< [10 @ 6960/10000] APP RDY [MODEM ] Modem was 'RDY' and 'APP RDY' [MODEM ] >> ATE1 [MODEM ] <<< [8] 'ATE1' while waiting for 'OK', remaining 500 ms, urc 99 [MODEM ] <<< [4 @ 4/500] OK [MODEM ] >> AT [MODEM ] <<< [5 @ 0/500] AT [MODEM ] <<< [5 @ 0/500] OK [MODEM ] is_modem_responsive? true [MODEM ] ANSWERS OK [MODEM ] >> ATE0 [MODEM ] <<< [8] 'ATE0' while waiting for 'OK', remaining 500 ms, urc 99 [MODEM ] <<< [4 @ 4/500] OK [MODEM ] ECHO DISABLED [MODEM ] >> AT+GMM [MODEM ] <<< [2 @ 0/500] [MODEM ] <<< [9 @ 0/500] BG95-M3 [MODEM ] <<< [4 @ 0/500] OK [MODEM ] QUECTEL BG95-M3 DETECTED [MODEM ] >> AT+QGMR [MODEM ] <<< [2 @ 0/500] [MODEM ] <<< [30 @ 0/500] BG95M3LAR02A03_01.200.01.200 [MODEM ] <<< [4 @ 0/500] OK [MODEM ] >> AT+GSN [MODEM ] <<< [2 @ 2/500] [MODEM ] <<< [17 @ 0/500] 866901062957339 [MODEM ] <<< [2 @ 0/500] [MODEM ] <<< [4 @ 0/500] OK [MODEM ] IMEI: 866901062957339 [MODEM ] QUECTEL REV: BG95M3LAR02A03_01.200.01.200 [MODEM ] >> AT+CFUN=0 [MODEM ] <<< [4 @ 492/10000] OK [MODEM ] Setting up for LTE-M modem mode [MODEM ] >> AT+QCFG="band",3,80084,80084,1 [MODEM ] <<< [4 @ 2/500] OK [MODEM ] >> AT+QCFG="nwscanseq",02,1 [MODEM ] <<< [4 @ 30/500] OK [MODEM ] >> AT+QCFG="iotopmode",0,1 [MODEM ] <<< [4 @ 0/500] OK [MODEM ] >> AT+QCFG="nwscanmode",3,1 [MODEM ] <<< [4 @ 0/500] OK [MODEM ] Enabling 2G fallback [MODEM ] >> AT+QCFG="nwscanseq",0201,1 [MODEM ] <<< [4 @ 28/500] OK [MODEM ] >> AT+QCFG="nwscanmode",0,1 [MODEM ] <<< [4 @ 2/500] OK [MODEM ] >> AT+CFUN=1 [MODEM ] <<< [4 @ 586/10000] OK [MODEM ] >> AT+CPIN? [MODEM ] <<< [2 @ 4/1000] [MODEM ] <<< [14 @ 1/100] +CPIN: READY [MODEM ] SIM ready [MODEM ] <<< [4 @ 0/500] OK [MODEM ] >> AT+QPSMS=0 [MODEM ] <<< [4 @ 6/500] OK [MODEM ] Attaching...[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 4/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 6/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [22 @ 2/500] +QNWINFO: No Service [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+QNWINFO [MODEM ] <<< [44 @ 4/500] +QNWINFO: "eMTC","21407","LTE BAND 3",1301 [MODEM ] <<< [4 @ 0/500] OK Attached to LTE-M network [MODEM ] Attached [MODEM ] (20s) [MODEM ] Registering...[MODEM ] >> AT+CEREG? [MODEM ] <<< [13 @ 2/500] +CEREG: 0,2 [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+CEREG? [MODEM ] <<< [13 @ 2/500] +CEREG: 0,2 [MODEM ] <<< [4 @ 0/500] OK .[MODEM ] >> AT+CEREG? [MODEM ] <<< [13 @ 2/500] +CEREG: 0,5 [MODEM ] <<< [4 @ 0/500] OK Roaming [MODEM ] Registered [MODEM ] (1s) [MODEM ] Activating context [MODEM ] >> AT+QICSGP=1,1,"em","","",1 [MODEM ] <<< [4 @ 4/500] OK [MODEM ] >> AT+QIACT=1 [MODEM ] <<< [4 @ 2/10000] OK [MODEM ] >> AT+QIACT? [MODEM ] <<< [30 @ 4/500] +QIACT: 1,1,1,"10.136.99.12" [MODEM ] Context activated 0s [MODEM ] >> AT+QLTS=1 [MODEM ] <<< [4] 'OK' while waiting for '+QLTS: "', remaining 500 ms, urc 99 [MODEM ] <<< [35 @ 4/500] +QLTS: "2024/08/07,12:45:56+08,1" [MODEM ] <<< [4 @ 0/500] OK [MODEM ] NETWORK TIME: 2024/08/07,12:45:56+08 [COMMS ] Entering server FSM connection 0, when finished turn off modem [COMMS ] Server FSM Initialization... [COMMS ] Should say hello every 1, count is 0 [COMMS ] SELECTED MQTT PLATFORM ... [COMMS ] SELECTED HELLO MQTT PLATFORM... [MQTT ] Connecting... [HELLO ] Grabbing otp hello configuration [MODEM ] >> AT+QMTCFG="session",1,1 [MODEM ] <<< [4 @ 0/500] OK [MODEM ] >> AT+QMTCFG="ssl",1,0,2 [MODEM ] <<< [4 @ 0/500] OK [HELLO ] Connecting to broker.datasense.es:1883 id 866901062957339 user ig4 pass ef1AlGNVzl6F [MQTT ] Opening connection with broker.datasense.es:1883... [MODEM ] >> AT+QMTCFG="recv/mode",1,1,1 [MODEM ] <<< [4 @ 2/500] OK [MODEM ] >> AT+QMTOPEN=1,"broker.datasense.es",1883 [MODEM ] <<< [4 @ 2/500] OK [MODEM ] <<< [15 @ 332/10000] +QMTOPEN: 1,0 [MQTT ] Connecting... [MODEM ] >> AT+QMTCONN=1,"866901062957339","ig4","ef1AlGNVzl6F" [MODEM ] <<< [4 @ 4/500] OK [MODEM ] <<< [17 @ 198/3000] +QMTCONN: 1,0,0 [HELLO ] PUBLISH TO onboarding/scrb/866901062957339/hello [HELLO ] SUBSCRIBE TO onboarding/scrb/866901062957339/cfg [MODEM ] >> AT+QMTSUB=1,1,"onboarding/scrb/866901062957339/cfg",1 [MODEM ] <<< [4 @ 4/500] OK [MODEM ] <<< [18 @ 178/1000] +QMTSUB: 1,1,0,1 [HELLO ] RPC publish to onboarding/scrb/866901062957339/rpc [HELLO ] Diagnostics: [meta] version = "1.0" [device] firmware_version = "vIG4" uptimes = 0 battery = 6628 config_version = 1720780436 config_updated = 10 [MODEM ] >> AT+QMTPUB=1,1,1,0,"onboarding/scrb/866901062957339/hello",147 [MODEM ] <<< [2 @ 1002/1000] > [MODEM ] >> [raw payload length 147] [MODEM ] <<< [4 @ 14/500] OK [MODEM ] <<< [16 @ 248/10000] +QMTPUB: 1,1,0 [MQTT ] Waiting 3000ms for subscription data to arrive [MODEM ] <<< [15 @ 210/3000] +QMTRECV: 1,0 [COMMS ] [MQTT] Received subscription data notification during previous request, parse [MQTT ] Grab subscription data [MODEM ] pending URC status 100 reset [MODEM ] >> AT+QMTRECV=1 [MODEM ] <<< [4 @ 1/500] OK [HELLO ] received topic 'onboarding/scrb/866901062957339/cfg' [HELLO ] Payload length 19 hash 2f543bb5 vs received 2f543bb5 [HELLO ] Received RPC request config print MODEM ] >> AT+QMTPUB=1,1,1,0,"onboarding/scrb/866901062957339/rpc",1180 [MODEM ] <<< [2 @ 1002/1000] > [MODEM ] >> [raw payload length 1180] [MODEM ] <<< [4 @ 86/500] OK [MODEM ] <<< [16 @ 996/10000] +QMTPUB: 1,1,0 [HELLO ] RPC response sent to topic onboarding/scrb/866901062957339/rpc with error code 0 [HELLO ] Finished parsing received topic [MODEM ] >> AT+QMTOPEN? [MODEM ] <<< [40 @ 4/500] +QMTOPEN: 1,"broker.datasense.es",1883 [MODEM ] <<< [4 @ 0/500] OK [MQTT ] Disconnecting from "broker.datasense.es",1883 [MODEM ] >> AT+QMTDISC=1 [MODEM ] <<< [4 @ 0/500] OK [MODEM ] <<< [15 @ 0/5000] +QMTDISC: 1,0 [MODEM ] <<< [15] '+QMTRECV: 1,0' while waiting for '+QMTSTAT: 1,5', remaining 4968 ms, urc 99 [MODEM ] Received notification of data from MQTT subscription, 15 bytes [MODEM ] <<< [15] '+QMTRECV: 1,0' while waiting for '+QMTSTAT: 1,5', remaining 4960 ms, urc 100 [MODEM ] There already is a pending URC 100, ignore this one ¯\_(ツ)_/¯ [MODEM ] <<< [15 @ 834/5000] +QMTSTAT: 1,5 [MODEM ] pending URC status 100 reset [MODEM ] >> AT+QCSQ [MODEM ] <<< [32 @ 4/500] +QCSQ: "eMTC",-73,-100,154,-12 [MODEM ] <<< [4 @ 0/500] OK [MODEM ] Signal quality: "eMTC",-73,-100,154,-12 -> 60% [MQTT ] Connecting... [MQTT ] Connecting to broker.datasense.es:1883 id 866901062957339 user ig4 pass ef1AlGNVzl6F [MQTT ] Opening connection with broker.datasense.es:1883... [MODEM ] >> AT+QMTCFG="recv/mode",1,1,1 [MODEM ] <<< [4 @ 2/500] OK [MODEM ] >> AT+QMTOPEN=1,"broker.datasense.es",1883 [MODEM ] <<< [4 @ 2/500] OK [MODEM ] <<< [15 @ 140/10000] +QMTOPEN: 1,0 [MQTT ] Connecting... [MODEM ] >> AT+QMTCONN=1,"866901062957339","ig4","ef1AlGNVzl6F" [MODEM ] <<< [4 @ 4/500] OK [MODEM ] <<< [17 @ 186/3000] +QMTCONN: 1,0,0 [MQTT ] Topic id option is network [COMMS ] [MQTT] SUBSCRIBE TO ig4/mega/866901062957339/sub [MQTT ] PUBLISH TO ig4/mega/866901062957339/pub [MODEM ] >> AT+QMTSUB=1,1,"ig4/mega/866901062957339/sub",1 [MODEM ] <<< [4 @ 2/500] OK [MODEM ] <<< [18 @ 190/1000] +QMTSUB: 1,1,0,1 [COMMS ] {"batt":6628,"batt_charge":0,"ts":"2024-08-03T16:16:21Z","uptimes":0,"connection_strength":0.000,"version":"vIG4"} [MODEM ] >> AT+QMTPUB=1,1,1,0,"ig4/mega/866901062957339/pub",114 [MODEM ] <<< [2 @ 1002/1000] > [MODEM ] >> [raw payload length 114] [MODEM ] <<< [4 @ 10/500] OK [MODEM ] <<< [16 @ 238/10000] +QMTPUB: 1,1,0 [MODEM ] >> AT+QMTOPEN? [MODEM ] <<< [40 @ 4/500] +QMTOPEN: 1,"broker.datasense.es",1883 [MODEM ] <<< [4 @ 0/500] OK [MQTT ] Disconnecting from "broker.datasense.es",1883 [MODEM ] >> AT+QMTDISC=1 [MODEM ] <<< [4 @ 0/500] OK [MODEM ] <<< [15 @ 0/5000] +QMTDISC: 1,0 [MODEM ] <<< [15 @ 384/5000] +QMTSTAT: 1,5 [COMMS ] Turning off modem or going into low power mode [MODEM ] >> AT+QIDEACT=1 [MODEM ] <<< [4 @ 0/30000] OK [MODEM ] Context deactivated [MODEM ] >> AT+QGDCNT? [MODEM ] <<< [20 @ 2/500] +QGDCNT: 4166,1076 [MODEM ] <<< [4 @ 0/500] OK [MODEM ] Data tx 4166 - rx 1076 [MODEM ] >> AT+QPOWD=1 [MODEM ] <<< [4 @ 0/500] OK [MODEM ] <<< [14 @ 790/5000] POWERED DOWN [MODEM ] >> AT