Hello everyone,
We have a Boron device deployed in a remote location in Rwanda. While all our other devices are operating smoothly, any device we place in this location experiences connectivity issues. These issues involve the device being connected, and then suddenly disconnecting, sometimes never coming back online. Additionally, if it goes into sleep mode, it fails to reconnect to the network. It’s not a constant problem but occurs randomly, about 2-3 times a day. This situation is quite troublesome as it necessitates a 6-hour drive to the remote location to reset the device and restore its connection.
I’ve attached the connectivity doctor logs collected from this location. It would be greatly appreciated if any of you have encountered similar issues or have any suggestions based on your experiences. If you’ve faced this problem before and found a solution, your input would be invaluable.
Thank you in advance for your assistance.
0000010001 [app] INFO: Platform: Boron
0000010001 [app] INFO: Binary compiled for: 4.0.1
0000010002 [app] INFO: Troubleshooting firmware version 12
0000010003 [app] INFO: System version: 4.0.1
0000010004 [app] INFO: Device ID: e00fce68048d85934b4d0ec4
0000010005 [app] INFO: Connecting to the cloud
0000010022 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000010025 [net.ppp.client] TRACE: PPP thread event ADM_UP data=0
0000010026 [net.ppp.client] TRACE: State NONE -> READY
0000010028 [ncp.at] TRACE: > AT+CFUN?
0000010030 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
0000010032 [ncp.at] TRACE: < +CFUN: 1,0
0000010032 [ncp.at] TRACE: < OK
0000010033 [ncp.at] TRACE: > AT+CCID
0000010051 [ncp.at] TRACE: < +CCID: 8925010000415662632
0000010052 [ncp.at] TRACE: < OK
0000010053 [ncp.at] TRACE: > AT+CIMI
0000010056 [ncp.at] TRACE: < 635105041566263
0000010057 [ncp.at] TRACE: < OK
0000010058 [ncp.at] TRACE: > AT+CGDCONT?
0000010061 [ncp.at] TRACE: < +CGDCONT: 1,"IP","","0.0.0.0",0,0
0000010062 [ncp.at] TRACE: < OK
0000010063 [ncp.at] TRACE: > AT+CFUN=4,0
0000012372 [ncp.at] TRACE: < OK
0000012374 [ncp.at] TRACE: > AT+CGDCONT=1,"IP",""
0000012375 [ncp.at] TRACE: < +CREG: 0
0000012376 [ncp.at] TRACE: < +CGREG: 0
0000012376 [ncp.at] TRACE: < +CGEV: NW DETACH
0000012378 [ncp.at] TRACE: < OK
0000012379 [ncp.at] TRACE: > AT+CFUN?
0000012382 [ncp.at] TRACE: < +CFUN: 4,0
0000012383 [ncp.at] TRACE: < OK
0000012384 [ncp.at] TRACE: > AT+CFUN=1,0
0000012392 [ncp.at] TRACE: < OK
0000012393 [ncp.at] TRACE: > AT+CREG=2
0000012394 [ncp.at] TRACE: < +CGEV: ME CLASS B
0000012397 [ncp.at] TRACE: < OK
0000012397 [ncp.at] TRACE: > AT+CGREG=2
0000012400 [ncp.at] TRACE: < OK
0000012401 [ncp.client] TRACE: NCP connection state changed: 1
0000012401 [net.pppncp] TRACE: NCP event 2
0000012402 [net.pppncp] TRACE: State changed event: 1
0000012403 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN data=0
0000012404 [ncp.at] TRACE: > AT+COPS?
0000012407 [ncp.at] TRACE: < +COPS: 0
0000012407 [ncp.at] TRACE: < OK
0000012408 [ncp.at] TRACE: > AT+CREG?
0000012411 [ncp.at] TRACE: < +CREG: 2,0
0000012412 [ncp.at] TRACE: < OK
0000012413 [ncp.at] TRACE: > AT+CGREG?
0000012416 [ncp.at] TRACE: < +CGREG: 2,0
0000012417 [ncp.at] TRACE: < OK
0000016817 [ncp.at] TRACE: < +CGEV: NW CLASS A
0000016817 [ncp.at] TRACE: < +CREG: 1,"00F3","55319",6
0000016819 [ncp.at] TRACE: > AT+CIMI
0000016819 [ncp.at] TRACE: < +CGREG: 1,"00F3","55319",6,"01"
0000016822 [ncp.at] TRACE: < 635105041566263
0000016823 [ncp.at] TRACE: < OK
0000016923 [ncp.client] TRACE: NCP connection state changed: 2
0000016923 [mux] INFO: Openning mux channel 2
0000018090 [net.pppncp] TRACE: NCP event 100
0000018090 [net.pppncp] TRACE: New auth info
0000018093 [net.pppncp] TRACE: NCP event 2
0000018094 [net.pppncp] TRACE: State changed event: 2
0000018094 [net.ppp.client] TRACE: PPP thread event LOWER_UP data=0
0000018095 [net.ppp.client] TRACE: State READY -> CONNECT
0000018096 [net.ppp.client] TRACE: State CONNECT -> CONNECTING
0000018097 [ncp.at] TRACE: > AT+CGATT?
0000018100 [ncp.at] TRACE: < +CGATT: 1
0000018101 [ncp.at] TRACE: < OK
0000019102 [ncp.at] TRACE: > AT
0000019104 [ncp.at] TRACE: < OK
0000019105 [ncp.at] TRACE: > ATH
0000019108 [ncp.at] TRACE: < OK
0000019109 [ncp.at] TRACE: > ATD*99***1#
0000019113 [ncp.at] TRACE: < CONNECT
0000019114 [net.ppp.client] TRACE: PPP phase -> Initialize
0000019114 [net.ppp.client] TRACE: PPP phase -> Establish
0000019115 [lwip.ppp] TRACE: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x58523e8e> <pcomp> <accomp>]
0000019120 [lwip.ppp] TRACE: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xd4fb98a1> <pcomp> <accomp>]
0000019122 [lwip.ppp] TRACE: sent [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xd4fb98a1> <pcomp> <accomp>]
0000019124 [lwip.ppp] TRACE: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x58523e8e> <pcomp> <accomp>]
0000019125 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x0 magic=0x58523e8e]
0000019126 [net.ppp.client] TRACE: PPP phase -> Authenticate
0000019127 [net.ppp.client] TRACE: PPP phase -> Network
0000019128 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.4.4>]
0000019131 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x0 magic=0xd4fb98a1]
0000024126 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x1 magic=0x58523e8e]
0000024129 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x1 magic=0xd4fb98a1]
0000025130 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.4.4>]
0000025136 [lwip.ppp] TRACE: rcvd [IPCP ConfReq id=0x1]
0000025137 [lwip.ppp] TRACE: sent [IPCP ConfAck id=0x1]
0000025139 [lwip.ppp] TRACE: rcvd [IPCP ConfNak id=0x1 <addr 10.121.178.222> <msdns1 41.186.255.97> <msdns2 41.186.255.225>]
0000025140 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x2 <addr 10.121.178.222> <msdns1 41.186.255.97> <msdns2 41.186.255.225>]
0000025145 [lwip.ppp] TRACE: rcvd [IPCP ConfAck id=0x2 <addr 10.121.178.222> <msdns1 41.186.255.97> <msdns2 41.186.255.225>]
0000025146 [system.nm] INFO: State changed: IFACE_UP -> IFACE_LINK_UP
0000025148 [net.ppp.client] TRACE: PPP thread event UP data=0
0000025148 [net.ppp.client] TRACE: State CONNECTING -> CONNECTED
0000025150 [net.pppncp] TRACE: Negotiated MTU: 1500
0000025149 [system.nm] INFO: State changed: IFACE_LINK_UP -> IP_CONFIGURED
0000025152 [net.ppp.client] TRACE: PPP phase -> Running
0000025152 [system] INFO: Cloud: connecting
0000025156 [system] WARN: Failed to load session data from persistent storage
0000027484 [system] INFO: Cloud socket connected
0000027486 [comm.protocol.handshake] INFO: Establish secure connection
0000027492 [comm.dtls] INFO: (CMPL,RENEG,NO_SESS,ERR) restoreStatus=2
0000031188 [comm.protocol.handshake] INFO: Sending HELLO message
0000031913 [comm.protocol.handshake] INFO: Handshake completed
0000031915 [comm.protocol.handshake] TRACE: Updating cached session parameters
0000031916 [comm.dtls] INFO: session cmd (CLS,DIS,MOV,LOD,SAV): 4
0000031919 [comm.dtls] INFO: session cmd (CLS,DIS,MOV,LOD,SAV): 3
0000032302 [ncp.at] TRACE: > AT+CFUN?
0000032306 [ncp.at] TRACE: < +CFUN: 1,0
0000032306 [ncp.at] TRACE: < OK
0000032308 [ncp.at] TRACE: > AT+CCID
0000032326 [ncp.at] TRACE: < +CCID: 8925010000415662632
0000032326 [ncp.at] TRACE: < OK
0000032327 [ncp.at] TRACE: > AT+CGSN
0000032340 [ncp.at] TRACE: < 358887095294417
0000032341 [ncp.at] TRACE: < OK
0000032342 [ncp.at] TRACE: > ATI9
0000032354 [ncp.at] TRACE: < 23.60,A01.01
0000032355 [ncp.at] TRACE: < OK
0000032377 [comm.protocol] INFO: Sending subscriptions
0000033375 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0000033377 [comm.protocol] TRACE: message id 2 complete with code 0.00
0000033378 [comm.protocol] TRACE: Updating system DESCRIBE checksum
0000033379 [comm.dtls] INFO: session cmd (CLS,DIS,MOV,LOD,SAV): 4
0000033779 [ncp.at] TRACE: > AT+CFUN?
0000033781 [ncp.at] TRACE: < +CFUN: 1,0
0000033781 [ncp.at] TRACE: < OK
0000033782 [ncp.at] TRACE: > AT+CCID
0000033797 [ncp.at] TRACE: < +CCID: 8925010000415662632
0000033798 [ncp.at] TRACE: < OK
0000033799 [ncp.at] TRACE: > AT+CGSN
0000033805 [ncp.at] TRACE: < 358887095294417
0000033806 [ncp.at] TRACE: < OK
0000033807 [ncp.at] TRACE: > ATI9
0000033813 [ncp.at] TRACE: < 23.60,A01.01
0000033814 [ncp.at] TRACE: < OK
0000033815 [comm.dtls] INFO: session cmd (CLS,DIS,MOV,LOD,SAV): 3
0000033816 [comm.protocol] TRACE: rcv'd message type=13
0000033818 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0000033820 [comm.protocol] TRACE: message id 3 complete with code 0.00
0000033822 [comm.protocol] TRACE: rcv'd message type=13
0000033848 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0000033850 [comm.protocol] TRACE: message id 4 complete with code 0.00
0000033852 [comm.protocol] TRACE: rcv'd message type=13
0000033952 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0000033954 [comm.protocol] TRACE: message id 5 complete with code 0.00
0000033955 [comm.protocol] TRACE: rcv'd message type=13
0000034056 [comm.protocol] TRACE: Reply recieved: type=2, code=69
0000034058 [comm.protocol] TRACE: message id 6 complete with code 2.05
0000034059 [comm.protocol] INFO: Received TIME response: 1695290328
0000034061 [comm.protocol] TRACE: rcv'd message type=12
0000034162 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0000034164 [comm.protocol] TRACE: message id 7 complete with code 0.00
0000034165 [comm.protocol] TRACE: Updating application DESCRIBE checksum
0000034166 [comm.dtls] INFO: session cmd (CLS,DIS,MOV,LOD,SAV): 4
0000034168 [comm.dtls] INFO: session cmd (CLS,DIS,MOV,LOD,SAV): 3
0000034170 [comm.protocol] TRACE: rcv'd message type=13
0000034270 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0000034272 [comm.protocol] TRACE: message id 8 complete with code 0.00
0000034273 [comm.protocol] TRACE: rcv'd message type=13
0000034374 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0000034376 [comm.protocol] TRACE: message id 9 complete with code 0.00
0000034377 [comm.protocol] TRACE: Updating subscriptions checksum
0000034378 [comm.dtls] INFO: session cmd (CLS,DIS,MOV,LOD,SAV): 4
0000034380 [comm.dtls] INFO: session cmd (CLS,DIS,MOV,LOD,SAV): 3
0000034382 [comm.protocol] TRACE: rcv'd message type=13
0000034391 [comm.protocol] INFO: Received DESCRIBE request; flags: 0x04
0000034400 [ncp.at] TRACE: > AT+COPS=3,2
0000034402 [ncp.at] TRACE: < OK
0000034404 [ncp.at] TRACE: > AT+COPS?
0000034406 [ncp.at] TRACE: < +COPS: 0,2,"63510",2
0000034408 [ncp.at] TRACE: < OK
0000034410 [ncp.at] TRACE: > AT+CSQ
0000034412 [ncp.at] TRACE: < +CSQ: 19,3
0000034412 [ncp.at] TRACE: < OK
0000034414 [ncp.at] TRACE: > AT+COPS=3,2
0000034416 [ncp.at] TRACE: < OK
0000034418 [ncp.at] TRACE: > AT+COPS?
0000034420 [ncp.at] TRACE: < +COPS: 0,2,"63510",2
0000034422 [ncp.at] TRACE: < OK
0000034424 [ncp.at] TRACE: > AT+CGREG?
0000034426 [ncp.at] TRACE: < +CGREG: 2,1,"00F3","55319",6,"01"
0000034427 [ncp.at] TRACE: < OK
0000034428 [ncp.at] TRACE: > AT+CREG?
0000034430 [ncp.at] TRACE: < +CREG: 2,1,"00F3","55319",6
0000034431 [ncp.at] TRACE: < OK
0000034435 [comm.protocol] TRACE: rcv'd message type=1
0000034437 [system] INFO: Cloud connected
0000034438 [ncp.at] TRACE: > AT+COPS=3,2
0000034440 [ncp.at] TRACE: < OK
0000034442 [ncp.at] TRACE: > AT+COPS?
0000034441 [comm.protocol] TRACE: rcv'd message type=8
0000034444 [ncp.at] TRACE: < +COPS: 0,2,"63510",2
0000034445 [ncp.at] TRACE: < OK
0000034446 [ncp.at] TRACE: > AT+CGREG?
0000034448 [ncp.at] TRACE: < +CGREG: 2,1,"00F3","55319",6,"01"
0000034449 [ncp.at] TRACE: < OK
0000034450 [ncp.at] TRACE: > AT+CREG?
0000034453 [ncp.at] TRACE: < +CREG: 2,1,"00F3","55319",6
0000034453 [ncp.at] TRACE: < OK
0000034455 [ncp.at] TRACE: > AT+CFUN?
0000034457 [ncp.at] TRACE: < +CFUN: 1,0
0000034458 [ncp.at] TRACE: < OK
0000034459 [ncp.at] TRACE: > AT+CCID
0000034473 [ncp.at] TRACE: < +CCID: 8925010000415662632
0000034474 [ncp.at] TRACE: < OK
0000034475 [ncp.at] TRACE: > AT+CGSN
0000034482 [ncp.at] TRACE: < 358887095294417
0000034482 [ncp.at] TRACE: < OK
0000034483 [ncp.at] TRACE: > ATI9
0000034490 [ncp.at] TRACE: < 23.60,A01.01
0000034491 [ncp.at] TRACE: < OK
0000034492 [ncp.at] TRACE: > AT+CGED=3
0000034498 [ncp.at] TRACE: < +CGED: RAT:"UMTS",
0000034498 [ncp.at] TRACE: < MCC:635, MNC: 10, LAC:00f3, CI:0055319, DLF:2962, ULF:2737
0000034500 [ncp.at] TRACE: < OK
0000034500 [app] INFO: Technology: 3G, Band: UMTS 900
0000034501 [app] INFO: Cellular Info: cid=348953 lac=243 mcc=635 mnc=10
0000034517 [ncp.at] TRACE: > AT+COPS=3,2
0000034519 [ncp.at] TRACE: < OK
0000034519 [ncp.at] TRACE: > AT+COPS?
0000034522 [ncp.at] TRACE: < +COPS: 0,2,"63510",2
0000034522 [ncp.at] TRACE: < OK
0000034523 [ncp.at] TRACE: > AT+CGREG?
0000034526 [ncp.at] TRACE: < +CGREG: 2,1,"00F3","55319",6,"01"
0000034526 [ncp.at] TRACE: < OK
0000034527 [ncp.at] TRACE: > AT+CREG?
0000034530 [ncp.at] TRACE: < +CREG: 2,1,"00F3","55319",6
0000034530 [ncp.at] TRACE: < OK
0000034531 [app] INFO: Cellular Info: cid=348953 lac=243 mcc=635 mnc=10
0000034532 [ncp.at] TRACE: > AT+CFUN?
0000034534 [ncp.at] TRACE: < +CFUN: 1,0
0000034535 [ncp.at] TRACE: < OK
0000034536 [ncp.at] TRACE: > AT+CCID
0000034551 [ncp.at] TRACE: < +CCID: 8925010000415662632
0000034552 [ncp.at] TRACE: < OK
0000034553 [ncp.at] TRACE: > AT+CGSN
0000034559 [ncp.at] TRACE: < 358887095294417
0000034560 [ncp.at] TRACE: < OK
0000034561 [ncp.at] TRACE: > ATI9
0000034567 [ncp.at] TRACE: < 23.60,A01.01
0000034568 [ncp.at] TRACE: < OK
0000034569 [ncp.at] TRACE: > AT+CGED=3
0000034574 [ncp.at] TRACE: < +CGED: RAT:"UMTS",
0000034575 [ncp.at] TRACE: < MCC:635, MNC: 10, LAC:00f3, CI:0055319, DLF:2962, ULF:2737
0000034576 [ncp.at] TRACE: < OK
0000034577 [app] INFO: Technology: 3G, Band: UMTS 900
0000037343 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0000037345 [comm.protocol] TRACE: message id 10 complete with code 0.00
0000037346 [comm.protocol] TRACE: rcv'd message type=13
0000051991 [ncp.at] TRACE: < +CREG: 1,"00F3","556FF",6
0000052091 [ncp.at] TRACE: < +CGREG: 1,"00F3","556FF",6,"01"
0000080192 [ncp.at] TRACE: < +CREG: 1,"00F3","55702",6
0000080292 [ncp.at] TRACE: < +CGREG: 1,"00F3","55702",6,"01"
0000114696 [ncp.at] TRACE: < +CREG: 1,"00F3","55704",6
0000114796 [ncp.at] TRACE: < +CGREG: 1,"00F3","55704",6,"01"
0000128797 [ncp.at] TRACE: < +CREG: 1,"00F3","556FF",6
0000128897 [ncp.at] TRACE: < +CGREG: 1,"00F3","556FF",6,"01"
0000173602 [ncp.at] TRACE: < +CREG: 1,"00F3","55702",6
0000173702 [ncp.at] TRACE: < +CGREG: 1,"00F3","55702",6,"01"
> 0001918284 [ncp.at] TRACE: < +CREG: 1,"00F3","556FF",6
0001918384 [ncp.at] TRACE: < +CGREG: 1,"00F3","556FF",6,"01"
0001929785 [ncp.at] TRACE: < +CREG: 1,"00F3","55702",6
0001929886 [ncp.at] TRACE: < +CGREG: 1,"00F3","55702",6,"01"
0001997387 [ncp.at] TRACE: < +CGEV: NW CLASS B
0001998987 [ncp.at] TRACE: < +CREG: 1,"00E0","531A",0
0001999087 [ncp.at] TRACE: < +CGREG: 1,"00E0","531A",0,"01"
0001999406 [lwip.ppp] TRACE: rcvd [LCP TermReq id=0x2]
0001999407 [system.nm] INFO: State changed: IP_CONFIGURED -> IFACE_UP
0001999408 [net.ppp.client] TRACE: PPP phase -> Network
0001999409 [net.ppp.client] TRACE: PPP phase -> Establish
0001999409 [lwip.ppp] TRACE: sent [LCP TermAck id=0x2]
0001999411 [system] ERROR: sock_send returned -1 118
0001999412 [comm.dtls] ERROR: mbedtls_ssl_write() failed: -0x4e
0001999414 [system] ERROR: sock_send returned -1 118
0001999416 [comm.dtls] ERROR: mbedtls_ssl_read() failed: -0x4e
0001999417 [comm.protocol] ERROR: Event loop error 34
0001999418 [system] WARN: Communication loop error, closing cloud socket
0001999420 [system] INFO: Cloud: disconnecting
0001999422 [system] INFO: Cloud: disconnected
0002000788 [ncp.at] TRACE: < +CGEV: NW DEACT "IP","10.121.178.222",1
0002005409 [net.ppp.client] TRACE: PPP phase -> Disconnect
0002005409 [net.ppp.client] TRACE: PPP phase -> Dead
0002005410 [net.ppp.client] TRACE: PPP thread event ERROR data=6
0002005411 [net.pppncp] ERROR: PPP error event data=6
0002005411 [net.ppp.client] TRACE: PPP thread event DOWN data=0
0002005412 [net.ppp.client] TRACE: State CONNECTED -> DISCONNECT
0002005413 [net.ppp.client] TRACE: State DISCONNECT -> DISCONNECTING
0002005414 [net.ppp.client] TRACE: PPP thread event ERROR data=5
0002005414 [net.pppncp] ERROR: PPP error event data=5
0002005415 [net.ppp.client] TRACE: PPP thread event DOWN data=0
0002005416 [net.ppp.client] TRACE: State DISCONNECTING -> DISCONNECTED
0002005417 [net.ppp.client] TRACE: State DISCONNECTED -> CONNECT
0002005517 [net.ppp.client] TRACE: State CONNECT -> CONNECTING
0002005518 [ncp.at] TRACE: > AT+CGATT?
0002005521 [ncp.at] TRACE: < +CGATT: 1
0002005522 [ncp.at] TRACE: < OK
0002006523 [ncp.at] TRACE: > AT
0002006525 [ncp.at] TRACE: < OK
0002006526 [ncp.at] TRACE: > ATH
0002006529 [ncp.at] TRACE: < OK
0002006530 [ncp.at] TRACE: > ATD*99***1#
0002006536 [ncp.at] TRACE: < CONNECT
0002006536 [net.ppp.client] TRACE: PPP phase -> Initialize
0002006537 [net.ppp.client] TRACE: PPP phase -> Establish
0002006538 [lwip.ppp] TRACE: sent [LCP ConfReq id=0x2 <asyncmap 0x0> <magic 0x58f01ab3> <pcomp> <accomp>]
0002006543 [lwip.ppp] TRACE: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x9cf3a959> <pcomp> <accomp>]
0002006545 [lwip.ppp] TRACE: sent [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x9cf3a959> <pcomp> <accomp>]
0002006547 [lwip.ppp] TRACE: rcvd [LCP ConfAck id=0x2 <asyncmap 0x0> <magic 0x58f01ab3> <pcomp> <accomp>]
0002006548 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x0 magic=0x58f01ab3]
0002006549 [net.ppp.client] TRACE: PPP phase -> Authenticate
0002006550 [net.ppp.client] TRACE: PPP phase -> Network
0002006551 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.4.4>]
0002006554 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x0 magic=0x9cf3a959]
0002010437 [ncp.at] TRACE: < +CGREG: 1,"00F3","55319",6,"01"
0002010538 [ncp.at] TRACE: < +CGEV: NW CLASS A
0002010838 [ncp.at] TRACE: < +CREG: 1,"00F3","55319",6
0002011549 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x1 magic=0x58f01ab3]
0002011553 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x1 magic=0x9cf3a959]
0002012553 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.4.4>]
0002016550 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x2 magic=0x58f01ab3]
0002016554 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x2 magic=0x9cf3a959]
0002018554 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.4.4>]
0002021551 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x3 magic=0x58f01ab3]
0002021555 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x3 magic=0x9cf3a959]
0002024555 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.4.4>]
0002026552 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x4 magic=0x58f01ab3]
0002026555 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x4 magic=0x9cf3a959]
0002030556 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.4.4>]
0002031553 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x5 magic=0x58f01ab3]
0002031557 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x5 magic=0x9cf3a959]
0002036554 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x6 magic=0x58f01ab3]
0002036557 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.4.4>]
0002036560 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x6 magic=0x9cf3a959]
0002041555 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x7 magic=0x58f01ab3]
0002041559 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x7 magic=0x9cf3a959]
0002042558 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.4.4>]
0002042562 [lwip.ppp] TRACE: rcvd [IPCP ConfReq id=0x1]
0002042563 [lwip.ppp] TRACE: sent [IPCP ConfAck id=0x1]
0002042565 [lwip.ppp] TRACE: rcvd [IPCP ConfNak id=0x1 <addr 10.25.166.41> <msdns1 41.186.255.97> <msdns2 41.186.255.225>]
0002042566 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x2 <addr 10.25.166.41> <msdns1 41.186.255.97> <msdns2 41.186.255.225>]
0002042571 [lwip.ppp] TRACE: rcvd [IPCP ConfAck id=0x2 <addr 10.25.166.41> <msdns1 41.186.255.97> <msdns2 41.186.255.225>]
0002042572 [system.nm] INFO: State changed: IFACE_UP -> IFACE_LINK_UP
0002042574 [system.nm] INFO: State changed: IFACE_LINK_UP -> IP_CONFIGURED
0002042574 [net.ppp.client] TRACE: PPP thread event UP data=0
0002042576 [net.ppp.client] TRACE: State CONNECTING -> CONNECTED
0002042577 [net.pppncp] TRACE: Negotiated MTU: 1500
0002042576 [net.ppp.client] TRACE: PPP phase -> Running
0002042578 [system] INFO: Cloud: connecting
0002042582 [system] INFO: Cloud socket connected
0002042584 [comm.protocol.handshake] INFO: Establish secure connection
0002042590 [comm.dtls] INFO: session has 0 uses
0002042606 [comm.dtls] INFO: (CMPL,RENEG,NO_SESS,ERR) restoreStatus=0
0002042607 [comm.dtls] INFO: out_ctr 0,1,0,0,0,0,0,14, next_coap_id=b
0002042609 [comm.dtls] INFO: restored session from persisted session data. next_msg_id=11
0002042611 [comm.dtls] INFO: session cmd (CLS,DIS,MOV,LOD,SAV): 2
0002042994 [ncp.at] TRACE: > AT+CFUN?
0002042998 [ncp.at] TRACE: < +CFUN: 1,0
0002042998 [ncp.at] TRACE: < OK
0002043000 [ncp.at] TRACE: > AT+CCID
0002043017 [ncp.at] TRACE: < +CCID: 8925010000415662632
0002043018 [ncp.at] TRACE: < OK
0002043020 [ncp.at] TRACE: > AT+CGSN
0002043034 [ncp.at] TRACE: < 358887095294417
0002043034 [ncp.at] TRACE: < OK
0002043036 [ncp.at] TRACE: > ATI9
0002043050 [ncp.at] TRACE: < 23.60,A01.01
0002043050 [ncp.at] TRACE: < OK
0002043051 [comm.protocol.handshake] INFO: Skipping HELLO message
0002043057 [comm.protocol] INFO: Checksum has not changed; not sending application DESCRIBE
0002043059 [comm.protocol] INFO: Checksum has not changed; not sending subscriptions
0002043602 [comm.dtls] INFO: session cmd (CLS,DIS,MOV,LOD,SAV): 4
0002043603 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0002043604 [comm.protocol] TRACE: message id 12 complete with code 0.00
0002043606 [comm.protocol] TRACE: rcv'd message type=13
0002043752 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0002043753 [comm.protocol] TRACE: message id 13 complete with code 0.00
0002043754 [comm.protocol] TRACE: rcv'd message type=13
0002043962 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0002043962 [comm.protocol] TRACE: message id 14 complete with code 0.00
0002043964 [comm.protocol] TRACE: rcv'd message type=13
0002044066 [system] INFO: Cloud connected
0002044773 [comm.protocol] TRACE: rcv'd message type=8
0002165250 [ncp.at] TRACE: < +CREG: 1,"00F3","55702",6
0002165351 [ncp.at] TRACE: < +CGREG: 1,"00F3","55702",6,"01"
0002295052 [ncp.at] TRACE: < +CGEV: NW CLASS B
0002296652 [ncp.at] TRACE: < +CREG: 2
0002296652 [ncp.client] TRACE: NCP connection state changed: 1
0002296653 [net.pppncp] TRACE: NCP event 2
0002296654 [net.pppncp] TRACE: State changed event: 1
0002296654 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN data=0
0002296655 [net.ppp.client] TRACE: State CONNECTED -> DISCONNECT
0002296656 [net.ppp.client] TRACE: State DISCONNECT -> DISCONNECTING
0002296657 [system.nm] INFO: State changed: IP_CONFIGURED -> IFACE_UP
0002296658 [net.ppp.client] TRACE: PPP phase -> Network
0002296659 [net.ppp.client] TRACE: PPP phase -> Establish
0002296659 [net.ppp.client] TRACE: PPP phase -> Disconnect
0002296660 [net.ppp.client] TRACE: PPP phase -> Dead
0002296660 [net.ppp.client] TRACE: PPP thread event ERROR data=5
0002296661 [net.pppncp] ERROR: PPP error event data=5
0002296662 [net.ppp.client] TRACE: PPP thread event DOWN data=0
0002296663 [net.ppp.client] TRACE: State DISCONNECTING -> DISCONNECTED
0002296663 [net.ppp.client] TRACE: State DISCONNECTED -> READY
0002296664 [system] ERROR: sock_send returned -1 118
0002296666 [comm.dtls] ERROR: mbedtls_ssl_write() failed: -0x4e
0002296668 [system] ERROR: sock_send returned -1 118
0002296669 [comm.dtls] ERROR: mbedtls_ssl_read() failed: -0x4e
0002296671 [comm.protocol] ERROR: Event loop error 34
0002296672 [system] WARN: Communication loop error, closing cloud socket
0002296674 [system] INFO: Cloud: disconnecting
0002296676 [system] INFO: Cloud: disconnected
0002296754 [ncp.at] TRACE: < +CGREG: 2
0002308754 [ncp.at] TRACE: < +CREG: 1,"00E0","55702",6
0002308755 [ncp.at] TRACE: > AT+CIMI
0002308756 [ncp.at] TRACE: < +CGREG: 1,"00F3","55702",6,"01"
0002308757 [ncp.at] TRACE: < +CGEV: NW CLASS A
0002308759 [ncp.at] TRACE: < 635105041566263
0002308760 [ncp.at] TRACE: < OK
0002308860 [ncp.client] TRACE: NCP connection state changed: 2
0002308860 [mux] INFO: Mux channel 2 already opened
0002308861 [net.pppncp] TRACE: NCP event 100
0002308862 [net.pppncp] TRACE: New auth info
0002308863 [net.pppncp] TRACE: NCP event 2
0002308864 [net.pppncp] TRACE: State changed event: 2
0002308865 [net.ppp.client] TRACE: PPP thread event LOWER_UP data=0
0002308865 [net.ppp.client] TRACE: State READY -> CONNECT
0002308866 [net.ppp.client] TRACE: State CONNECT -> CONNECTING
0002308867 [ncp.at] TRACE: > AT+CGATT?
0002308870 [ncp.at] TRACE: < +CGATT: 1
0002308871 [ncp.at] TRACE: < OK
0002309873 [ncp.at] TRACE: > AT
0002309876 [ncp.at] TRACE: < OK
0002309876 [ncp.at] TRACE: > ATH
0002311258 [ncp.at] TRACE: < OK
0002311259 [ncp.at] TRACE: > ATD*99***1#
0002311263 [ncp.at] TRACE: < CONNECT
0002311264 [net.ppp.client] TRACE: PPP phase -> Initialize
0002311264 [net.ppp.client] TRACE: PPP phase -> Establish
0002311265 [lwip.ppp] TRACE: sent [LCP ConfReq id=0x3 <asyncmap 0x0> <magic 0x6cc02d22> <pcomp> <accomp>]
0002311264 [ncp.at] TRACE: < +CREG: 1,"00F3","55702",6
0002311270 [lwip.ppp] TRACE: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x4592d537> <pcomp> <accomp>]
0002311272 [lwip.ppp] TRACE: sent [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x4592d537> <pcomp> <accomp>]
0002311274 [lwip.ppp] TRACE: rcvd [LCP ConfAck id=0x3 <asyncmap 0x0> <magic 0x6cc02d22> <pcomp> <accomp>]
0002311275 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x0 magic=0x6cc02d22]
0002311276 [net.ppp.client] TRACE: PPP phase -> Authenticate
0002311277 [net.ppp.client] TRACE: PPP phase -> Network
0002311278 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.4.4>]
0002311281 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x0 magic=0x4592d537]
0002311367 [ncp.at] TRACE: < +CGEV: ME DEACT "IP","10.25.166.41",1
0002311368 [ncp.at] TRACE: < +CGEV: ME DEACT "IP","0.0.0.0",1
0002316276 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x1 magic=0x6cc02d22]
0002316279 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x1 magic=0x4592d537]
0002317280 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.4.4>]
0002317284 [lwip.ppp] TRACE: rcvd [IPCP ConfReq id=0x1]
0002317284 [lwip.ppp] TRACE: sent [IPCP ConfAck id=0x1]
0002317287 [lwip.ppp] TRACE: rcvd [IPCP ConfNak id=0x1 <addr 10.21.63.168> <msdns1 41.186.255.97> <msdns2 41.186.255.225>]
0002317288 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x2 <addr 10.21.63.168> <msdns1 41.186.255.97> <msdns2 41.186.255.225>]
0002317292 [lwip.ppp] TRACE: rcvd [IPCP ConfAck id=0x2 <addr 10.21.63.168> <msdns1 41.186.255.97> <msdns2 41.186.255.225>]
0002317294 [system.nm] INFO: State changed: IFACE_UP -> IFACE_LINK_UP
0002317295 [system.nm] INFO: State changed: IFACE_LINK_UP -> IP_CONFIGURED
0002317296 [net.ppp.client] TRACE: PPP thread event UP data=0
0002317298 [net.ppp.client] TRACE: State CONNECTING -> CONNECTED
0002317298 [net.pppncp] TRACE: Negotiated MTU: 1500
0002317297 [net.ppp.client] TRACE: PPP phase -> Running
0002317300 [system] INFO: Cloud: connecting
0002317304 [system] INFO: Cloud socket connected
0002317306 [comm.protocol.handshake] INFO: Establish secure connection
0002317312 [comm.dtls] INFO: session has 0 uses
0002317328 [comm.dtls] INFO: (CMPL,RENEG,NO_SESS,ERR) restoreStatus=0
0002317330 [comm.dtls] INFO: out_ctr 0,1,0,0,0,0,0,18, next_coap_id=e
0002317332 [comm.dtls] INFO: restored session from persisted session data. next_msg_id=14
0002317333 [comm.dtls] INFO: session cmd (CLS,DIS,MOV,LOD,SAV): 2
0002317716 [ncp.at] TRACE: > AT+CFUN?
0002317719 [ncp.at] TRACE: < +CFUN: 1,0
0002317720 [ncp.at] TRACE: < OK
0002317721 [ncp.at] TRACE: > AT+CCID
0002317740 [ncp.at] TRACE: < +CCID: 8925010000415662632
0002317740 [ncp.at] TRACE: < OK
0002317742 [ncp.at] TRACE: > AT+CGSN
0002317756 [ncp.at] TRACE: < 358887095294417
0002317757 [ncp.at] TRACE: < OK
0002317759 [ncp.at] TRACE: > ATI9
0002317766 [ncp.at] TRACE: < 23.60,A01.01
0002317767 [ncp.at] TRACE: < OK
> ```
Full logs are here. https://pastebin.com/9fdJeztc