Boron Restarting

Hi All,

I have seen some intermittent restarting of Borons yesterday and today. Is there any chance this could be a particle service issue? Some logs from device restarting below:

 0000000862 [app] INFO: INTENDED SD IGNORE
0000000865 [system.nm] INFO: State changed: DISABLED -> IFACE_DOWN
0000000865 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000000866 [ncp.client] TRACE: Powering modem on, ncpId: 0x42
0000000866 [ncp.client] TRACE: Modem already on
0000000866 [ncp.client] TRACE: Setting UART voltage translator state 1
0000000867 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
0000000966 [ncp.client] TRACE: Setting UART voltage translator state 0
Scanning...
I2C device found at address 0x20  !
I2C device found at address 0x21  !
I2C device found at address 0x22  !
done

0000001066 [ncp.client] TRACE: Setting UART voltage translator state 1
0000001066 [ncp.client] TRACE: Muxer is not currently running
0000001067 [ncp.client] TRACE: Initialized muxer @ 921600 baudrate
0000001067 [mux] INFO: Starting GSM07.10 muxer
0000001068 [mux] INFO: GSM07.10 muxer thread started
0000001368 [mux] INFO: Stopping GSM07.10 muxer
0000001368 [mux] INFO: Gracefully stopping GSM07.10 muxer
0000001368 [mux] INFO: Closing all muxed channels
0000001369 [mux] INFO: Muxed channel 1 already closed
0000001369 [mux] INFO: Muxed channel 2 already closed
0000001369 [mux] INFO: Muxed channel 3 already closed
0000001370 [mux] INFO: Muxed channel 4 already closed
0000002020 [mux] INFO: Sending CLD (multiplexer close down)
0000002920 [mux] INFO: Received response to CLD or timed out, exiting multiplexed mode
0000002970 [mux] INFO: GSM07.10 muxer thread exiting
0000002970 [mux] INFO: GSM07.10 muxer stopped
0000002971 [ncp.at] TRACE: > AT
0000003971 [ncp.at] TRACE: > AT
0000004971 [ncp.client] TRACE: Modem is not responsive @ 921600 baudrate
0000004971 [ncp.at] TRACE: > AT
0000004974 [ncp.at] TRACE: < OK
0000004974 [ncp.client] TRACE: NCP ready to accept AT commands
0000004974 [ncp.at] TRACE: > AT
0000004976 [ncp.at] TRACE: < OK
0000004977 [ncp.at] TRACE: > ATI9
0000004991 [ncp.at] TRACE: < 23.60,A01.01
0000004991 [ncp.at] TRACE: < OK
0000004991 [ncp.client] TRACE: App firmware: 101
0000004992 [ncp.at] TRACE: > AT+UGPIOC?
0000004996 [ncp.at] TRACE: < +UGPIOC:
0000004997 [ncp.at] TRACE: < 16,255
0000004997 [ncp.at] TRACE: < 23,255
0000004998 [ncp.at] TRACE: < 24,4
0000004998 [ncp.at] TRACE: < 25,5
0000004999 [ncp.at] TRACE: < 34,12
0000004999 [ncp.at] TRACE: < 35,12
0000005000 [ncp.at] TRACE: < 36,12
0000005001 [ncp.at] TRACE: < 37,12
0000005001 [ncp.at] TRACE: < 42,7
0000005002 [ncp.at] TRACE: < OK
0000005002 [ncp.client] INFO: Using internal SIM card
0000005002 [ncp.at] TRACE: > AT+CPIN?
0000005007 [ncp.at] TRACE: < +CPIN: READY
0000005007 [ncp.at] TRACE: < OK
0000005008 [ncp.at] TRACE: > AT+CCID
0000005027 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000005027 [ncp.at] TRACE: < OK
0000005028 [ncp.at] TRACE: > AT+IFC=2,2
0000005031 [ncp.at] TRACE: < OK
0000005031 [ncp.at] TRACE: > AT
0000006032 [ncp.at] TRACE: > AT
0000006034 [ncp.at] TRACE: < OK
0000006034 [ncp.at] TRACE: > AT+IPR=921600
0000006038 [ncp.at] TRACE: < OK
0000007039 [ncp.at] TRACE: > AT
0000007041 [ncp.at] TRACE: < OK
0000007041 [ncp.at] TRACE: > AT+COPS=3,2
0000007043 [ncp.at] TRACE: < OK
0000007044 [ncp.at] TRACE: > AT+CGEREP=1,0
0000007046 [ncp.at] TRACE: < OK
0000007046 [ncp.at] TRACE: > AT+UPSV=0
0000007048 [ncp.at] TRACE: < OK
0000007049 [ncp.at] TRACE: > AT+CMUX=0,0,,1509,,,,,
0000007177 [ncp.at] TRACE: < OK
0000007178 [mux] INFO: Starting GSM07.10 muxer
0000007179 [mux] INFO: Openning mux channel 0
0000007179 [mux] INFO: GSM07.10 muxer thread started
0000007181 [mux] INFO: Openning mux channel 1
0000007184 [ncp.at] TRACE: > AT
0000007187 [ncp.at] TRACE: < OK
0000007187 [ncp.client] TRACE: NCP state changed: 1
0000007188 [net.pppncp] TRACE: NCP event 1
0000007189 [net.ppp.client] TRACE: PPP thread event ADM_UP
0000007189 [net.ppp.client] TRACE: State NONE -> READY
0000007192 [ncp.at] TRACE: > AT+CFUN?
0000007195 [ncp.at] TRACE: < +CFUN: 1,0
0000007195 [ncp.at] TRACE: < OK
0000007196 [ncp.at] TRACE: > AT+CCID
0000007214 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000007214 [ncp.at] TRACE: < OK
0000007215 [ncp.at] TRACE: > AT+CIMI
0000007218 [ncp.at] TRACE: < 204047120764699
0000007218 [ncp.at] TRACE: < OK
0000007219 [ncp.at] TRACE: > AT+CGDCONT?
0000007223 [ncp.at] TRACE: < +CGDCONT: 1,"IP","vfd1.korem2m.com","0.0.0.0",0,0
0000007224 [ncp.at] TRACE: < OK
0000007224 [ncp.at] TRACE: > AT+CFUN?
0000007227 [ncp.at] TRACE: < +CFUN: 1,0
0000007228 [ncp.at] TRACE: < OK
0000007228 [ncp.at] TRACE: > AT+CREG=2
0000007231 [ncp.at] TRACE: < OK
0000007231 [ncp.at] TRACE: > AT+CGREG=2
0000007234 [ncp.at] TRACE: < OK
0000007234 [ncp.client] TRACE: NCP connection state changed: 1
0000007234 [net.pppncp] TRACE: NCP event 2
0000007234 [net.pppncp] TRACE: State changed event: 1
0000007235 [ncp.at] TRACE: > AT+COPS?
0000007236 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN
0000007238 [ncp.at] TRACE: < +COPS: 0
0000007238 [ncp.at] TRACE: < OK
0000007239 [ncp.at] TRACE: > AT+CREG?
0000007242 [ncp.at] TRACE: < +CREG: 2,0
0000007243 [ncp.at] TRACE: < OK
0000007243 [ncp.at] TRACE: > AT+CGREG?
0000007246 [ncp.at] TRACE: < +CGREG: 2,0
0000007247 [ncp.at] TRACE: < OK

Reboot event on Boron

Serial connection closed.  Attempting to reconnect...
Serial monitor opened successfully:
0000002502 [mux] INFO: Sending CLD (multiplexer close down)
0000003402 [mux] INFO: Received response to CLD or timed out, exiting multiplexed mode
0000003452 [mux] INFO: GSM07.10 muxer thread exiting
0000003452 [mux] INFO: GSM07.10 muxer stopped
0000003453 [ncp.at] TRACE: > AT
0000004453 [ncp.at] TRACE: > AT
0000005453 [ncp.client] TRACE: Modem is not responsive @ 921600 baudrate
0000005454 [ncp.at] TRACE: > AT
0000005456 [ncp.at] TRACE: < OK
0000005456 [ncp.client] TRACE: NCP ready to accept AT commands
0000005456 [ncp.at] TRACE: > AT
0000005459 [ncp.at] TRACE: < OK
0000005459 [ncp.at] TRACE: > ATI9
0000005472 [ncp.at] TRACE: < 23.60,A01.01
0000005473 [ncp.at] TRACE: < OK
0000005473 [ncp.client] TRACE: App firmware: 101
0000005474 [ncp.at] TRACE: > AT+UGPIOC?
0000005478 [ncp.at] TRACE: < +UGPIOC:
0000005479 [ncp.at] TRACE: < 16,255
0000005479 [ncp.at] TRACE: < 23,255
0000005480 [ncp.at] TRACE: < 24,4
0000005480 [ncp.at] TRACE: < 25,5
0000005481 [ncp.at] TRACE: < 34,12
0000005482 [ncp.at] TRACE: < 35,12
0000005482 [ncp.at] TRACE: < 36,12
0000005483 [ncp.at] TRACE: < 37,12
0000005483 [ncp.at] TRACE: < 42,7
0000005484 [ncp.at] TRACE: < OK
0000005484 [ncp.client] INFO: Using internal SIM card
0000005485 [ncp.at] TRACE: > AT+CPIN?
0000005489 [ncp.at] TRACE: < +CPIN: READY
0000005490 [ncp.at] TRACE: < OK
0000005490 [ncp.at] TRACE: > AT+CCID
0000005510 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000005510 [ncp.at] TRACE: < OK
0000005511 [ncp.at] TRACE: > AT+IFC=2,2
0000005514 [ncp.at] TRACE: < OK
0000005515 [ncp.at] TRACE: > AT
0000006515 [ncp.at] TRACE: > AT
0000006517 [ncp.at] TRACE: < OK
0000006518 [ncp.at] TRACE: > AT+IPR=921600
0000006522 [ncp.at] TRACE: < OK
0000007522 [ncp.at] TRACE: > AT
0000007523 [ncp.at] TRACE: < OK
0000007524 [ncp.at] TRACE: > AT+COPS=3,2
0000007526 [ncp.at] TRACE: < OK
0000007526 [ncp.at] TRACE: > AT+CGEREP=1,0
0000007529 [ncp.at] TRACE: < OK
0000007529 [ncp.at] TRACE: > AT+UPSV=0
0000007531 [ncp.at] TRACE: < OK
0000007531 [ncp.at] TRACE: > AT+CMUX=0,0,,1509,,,,,
0000007660 [ncp.at] TRACE: < OK
0000007660 [mux] INFO: Starting GSM07.10 muxer
0000007661 [mux] INFO: Openning mux channel 0
0000007661 [mux] INFO: GSM07.10 muxer thread started
0000007663 [mux] INFO: Openning mux channel 1
0000007666 [ncp.at] TRACE: > AT
0000007669 [ncp.at] TRACE: < OK
0000007670 [ncp.client] TRACE: NCP state changed: 1
0000007670 [net.pppncp] TRACE: NCP event 1
0000007671 [net.ppp.client] TRACE: PPP thread event ADM_UP
0000007671 [net.ppp.client] TRACE: State NONE -> READY
0000007674 [ncp.at] TRACE: > AT+CFUN?
0000007677 [ncp.at] TRACE: < +CFUN: 1,0
0000007678 [ncp.at] TRACE: < OK
0000007678 [ncp.at] TRACE: > AT+CCID
0000007696 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000007696 [ncp.at] TRACE: < OK
0000007697 [ncp.at] TRACE: > AT+CIMI
0000007700 [ncp.at] TRACE: < 204047120764699
0000007700 [ncp.at] TRACE: < OK
0000007701 [ncp.at] TRACE: > AT+CGDCONT?
0000007704 [ncp.at] TRACE: < +CGDCONT: 1,"IP","vfd1.korem2m.com","0.0.0.0",0,0
0000007705 [ncp.at] TRACE: < OK
0000007706 [ncp.at] TRACE: > AT+CFUN?
0000007709 [ncp.at] TRACE: < +CFUN: 1,0
0000007709 [ncp.at] TRACE: < OK
0000007710 [ncp.at] TRACE: > AT+CREG=2
0000007712 [ncp.at] TRACE: < OK
0000007713 [ncp.at] TRACE: > AT+CGREG=2
0000007715 [ncp.at] TRACE: < OK
0000007715 [ncp.client] TRACE: NCP connection state changed: 1
0000007716 [net.pppncp] TRACE: NCP event 2
0000007716 [net.pppncp] TRACE: State changed event: 1
0000007717 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN
0000007717 [ncp.at] TRACE: > AT+COPS?
0000007719 [ncp.at] TRACE: < +COPS: 0
0000007720 [ncp.at] TRACE: < OK
0000007720 [ncp.at] TRACE: > AT+CREG?
0000007723 [ncp.at] TRACE: < +CREG: 2,0
0000007724 [ncp.at] TRACE: < OK
0000007724 [ncp.at] TRACE: > AT+CGREG?
0000007727 [ncp.at] TRACE: < +CGREG: 2,0
0000007727 [ncp.at] TRACE: < OK

Another Reboot

1 Like

More Logs from more restart events:

Serial monitor opened successfully:
0000001219 [app] INFO: INTENDED SD IGNORE
0000001220 [system.nm] INFO: State changed: DISABLED -> IFACE_DOWN
0000001221 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000001221 [ncp.client] TRACE: Powering modem on, ncpId: 0x42
0000001222 [ncp.client] TRACE: Modem already on
0000001222 [ncp.client] TRACE: Setting UART voltage translator state 1
0000001223 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
0000001322 [ncp.client] TRACE: Setting UART voltage translator state 0
Scanning...
I2C device found at address 0x20  !
I2C device found at address 0x21  !
I2C device found at address 0x22  !
done

0000001422 [ncp.client] TRACE: Setting UART voltage translator state 1
0000001422 [ncp.client] TRACE: Muxer is not currently running
0000001423 [ncp.client] TRACE: Initialized muxer @ 921600 baudrate
0000001424 [mux] INFO: Starting GSM07.10 muxer
0000001425 [mux] INFO: GSM07.10 muxer thread started
0000001724 [mux] INFO: Stopping GSM07.10 muxer
0000001724 [mux] INFO: Gracefully stopping GSM07.10 muxer
0000001725 [mux] INFO: Closing all muxed channels
0000001725 [mux] INFO: Muxed channel 1 already closed
0000001725 [mux] INFO: Muxed channel 2 already closed
0000001726 [mux] INFO: Muxed channel 3 already closed
0000001726 [mux] INFO: Muxed channel 4 already closed
0000002376 [mux] INFO: Sending CLD (multiplexer close down)
0000003277 [mux] INFO: Received response to CLD or timed out, exiting multiplexed mode
0000003327 [mux] INFO: GSM07.10 muxer thread exiting
0000003327 [mux] INFO: GSM07.10 muxer stopped
0000003328 [ncp.at] TRACE: > AT
0000004328 [ncp.at] TRACE: > AT
0000005328 [ncp.client] TRACE: Modem is not responsive @ 921600 baudrate
0000005329 [ncp.at] TRACE: > AT
0000005331 [ncp.at] TRACE: < OK
0000005331 [ncp.client] TRACE: NCP ready to accept AT commands
0000005332 [ncp.at] TRACE: > AT
0000005334 [ncp.at] TRACE: < OK
0000005334 [ncp.at] TRACE: > ATI9
0000005348 [ncp.at] TRACE: < 23.60,A01.01
0000005348 [ncp.at] TRACE: < OK
0000005348 [ncp.client] TRACE: App firmware: 101
0000005349 [ncp.at] TRACE: > AT+UGPIOC?
0000005353 [ncp.at] TRACE: < +UGPIOC:
0000005354 [ncp.at] TRACE: < 16,255
0000005354 [ncp.at] TRACE: < 23,255
0000005355 [ncp.at] TRACE: < 24,4
0000005355 [ncp.at] TRACE: < 25,5
0000005356 [ncp.at] TRACE: < 34,12
0000005357 [ncp.at] TRACE: < 35,12
0000005357 [ncp.at] TRACE: < 36,12
0000005358 [ncp.at] TRACE: < 37,12
0000005358 [ncp.at] TRACE: < 42,7
0000005359 [ncp.at] TRACE: < OK
0000005359 [ncp.client] INFO: Using internal SIM card
0000005360 [ncp.at] TRACE: > AT+CPIN?
0000005364 [ncp.at] TRACE: < +CPIN: READY
0000005365 [ncp.at] TRACE: < OK
0000005365 [ncp.at] TRACE: > AT+CCID
0000005384 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000005385 [ncp.at] TRACE: < OK
0000005385 [ncp.at] TRACE: > AT+IFC=2,2
0000005389 [ncp.at] TRACE: < OK
0000005389 [ncp.at] TRACE: > AT
0000006389 [ncp.at] TRACE: > AT
0000006392 [ncp.at] TRACE: < OK
0000006392 [ncp.at] TRACE: > AT+IPR=921600
0000006397 [ncp.at] TRACE: < OK
0000007398 [ncp.at] TRACE: > AT
0000007400 [ncp.at] TRACE: < OK
0000007400 [ncp.at] TRACE: > AT+COPS=3,2
0000007403 [ncp.at] TRACE: < OK
0000007403 [ncp.at] TRACE: > AT+CGEREP=1,0
0000007405 [ncp.at] TRACE: < OK
0000007405 [ncp.at] TRACE: > AT+UPSV=0
0000007407 [ncp.at] TRACE: < OK
0000007407 [ncp.at] TRACE: > AT+CMUX=0,0,,1509,,,,,
0000007536 [ncp.at] TRACE: < OK
0000007536 [mux] INFO: Starting GSM07.10 muxer
0000007537 [mux] INFO: Openning mux channel 0
0000007537 [mux] INFO: GSM07.10 muxer thread started
0000007540 [mux] INFO: Openning mux channel 1
0000007543 [ncp.at] TRACE: > AT
0000007546 [ncp.at] TRACE: < OK
0000007546 [ncp.client] TRACE: NCP state changed: 1
0000007546 [net.pppncp] TRACE: NCP event 1
0000007547 [net.ppp.client] TRACE: PPP thread event ADM_UP
0000007547 [net.ppp.client] TRACE: State NONE -> READY
0000007550 [ncp.at] TRACE: > AT+CFUN?
0000007553 [ncp.at] TRACE: < +CFUN: 1,0
0000007553 [ncp.at] TRACE: < OK
0000007553 [ncp.at] TRACE: > AT+CCID
0000007571 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000007571 [ncp.at] TRACE: < OK
0000007572 [ncp.at] TRACE: > AT+CIMI
0000007575 [ncp.at] TRACE: < 204047120764699
0000007575 [ncp.at] TRACE: < OK
0000007576 [ncp.at] TRACE: > AT+CGDCONT?
0000007580 [ncp.at] TRACE: < +CGDCONT: 1,"IP","vfd1.korem2m.com","0.0.0.0",0,0
0000007580 [ncp.at] TRACE: < OK
0000007581 [ncp.at] TRACE: > AT+CFUN?
0000007584 [ncp.at] TRACE: < +CFUN: 1,0
0000007584 [ncp.at] TRACE: < OK
0000007585 [ncp.at] TRACE: > AT+CREG=2
0000007587 [ncp.at] TRACE: < OK
0000007588 [ncp.at] TRACE: > AT+CGREG=2
0000007590 [ncp.at] TRACE: < OK
0000007590 [ncp.client] TRACE: NCP connection state changed: 1
0000007591 [net.pppncp] TRACE: NCP event 2
0000007591 [net.pppncp] TRACE: State changed event: 1
0000007592 [ncp.at] TRACE: > AT+COPS?
0000007592 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN
0000007594 [ncp.at] TRACE: < +COPS: 0
0000007595 [ncp.at] TRACE: < OK
0000007596 [ncp.at] TRACE: > AT+CREG?
0000007598 [ncp.at] TRACE: < +CREG: 2,0
0000007598 [ncp.at] TRACE: < OK
0000007599 [ncp.at] TRACE: > AT+CGREG?
0000007602 [ncp.at] TRACE: < +CGREG: 2,0
0000007602 [ncp.at] TRACE: < OK

Reboot event

Serial monitor opened successfully:
0000000850 [app] INFO: INTENDED SD IGNORE
0000000852 [system.nm] INFO: State changed: DISABLED -> IFACE_DOWN
0000000853 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000000853 [ncp.client] TRACE: Powering modem on, ncpId: 0x42
0000000853 [net.pppncp] TRACE: NCP event 3
0000000854 [net.pppncp] TRACE: NCP power state changed: IF_POWER_STATE_POWERING_UP
0000000854 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
0000000855 [system.nm] TRACE: Interface 4 power state changed: 4
0000000865 [net.pppncp] TRACE: NCP event 3
0000000865 [net.pppncp] TRACE: NCP power state changed: IF_POWER_STATE_UP
0000000866 [system.nm] TRACE: Interface 4 power state changed: 2
0000000866 [ncp.client] TRACE: Modem powered on
0000000867 [ncp.client] TRACE: Setting UART voltage translator state 1
0000000966 [ncp.client] TRACE: Setting UART voltage translator state 0
Scanning...
I2C device found at address 0x20  !
I2C device found at address 0x21  !
I2C device found at address 0x22  !
done

0000001066 [ncp.client] TRACE: Setting UART voltage translator state 1
0000002067 [ncp.at] TRACE: > AT
0000003067 [ncp.at] TRACE: > AT
0000004067 [ncp.at] TRACE: > AT
0000005067 [ncp.at] TRACE: > AT
0000005070 [ncp.at] TRACE: < OK
0000005070 [ncp.client] TRACE: NCP ready to accept AT commands
0000005070 [ncp.at] TRACE: > AT
0000005072 [ncp.at] TRACE: < OK
0000005073 [ncp.at] TRACE: > ATI9
0000005090 [ncp.at] TRACE: < 23.60,A01.01
0000005091 [ncp.at] TRACE: < OK
0000005091 [ncp.client] TRACE: App firmware: 101
0000005091 [ncp.at] TRACE: > AT+UGPIOC?
0000005096 [ncp.at] TRACE: < +UGPIOC:
0000005096 [ncp.at] TRACE: < 16,255
0000005097 [ncp.at] TRACE: < 23,255
0000005097 [ncp.at] TRACE: < 24,4
0000005098 [ncp.at] TRACE: < 25,5
0000005099 [ncp.at] TRACE: < 34,12
0000005099 [ncp.at] TRACE: < 35,12
0000005100 [ncp.at] TRACE: < 36,12
0000005100 [ncp.at] TRACE: < 37,12
0000005101 [ncp.at] TRACE: < 42,7
0000005102 [ncp.at] TRACE: < OK
0000005102 [ncp.client] INFO: Using internal SIM card
0000005102 [ncp.at] TRACE: > AT+CPIN?
0000005106 [ncp.at] TRACE: < ERROR
0000006106 [ncp.at] TRACE: > AT+CPIN?
0000006111 [ncp.at] TRACE: < +CPIN: READY
0000006111 [ncp.at] TRACE: < OK
0000006112 [ncp.at] TRACE: > AT+CCID
0000006131 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000006132 [ncp.at] TRACE: < OK
0000006132 [ncp.at] TRACE: > AT+IFC=2,2
0000006135 [ncp.at] TRACE: < OK
0000006136 [ncp.at] TRACE: > AT
0000007136 [ncp.at] TRACE: > AT
0000007138 [ncp.at] TRACE: < OK
0000007139 [ncp.at] TRACE: > AT+IPR=921600
0000007143 [ncp.at] TRACE: < OK
0000008143 [ncp.at] TRACE: > AT
0000008145 [ncp.at] TRACE: < OK
0000008145 [ncp.at] TRACE: > AT+COPS=3,2
0000008147 [ncp.at] TRACE: < OK
0000008148 [ncp.at] TRACE: > AT+CGEREP=1,0
0000008150 [ncp.at] TRACE: < OK
0000008150 [ncp.at] TRACE: > AT+UPSV=0
0000008152 [ncp.at] TRACE: < OK
0000008153 [ncp.at] TRACE: > AT+CMUX=0,0,,1509,,,,,
0000008282 [ncp.at] TRACE: < OK
0000008282 [mux] INFO: Starting GSM07.10 muxer
0000008283 [mux] INFO: Openning mux channel 0
0000008283 [mux] INFO: GSM07.10 muxer thread started
0000008285 [mux] INFO: Openning mux channel 1
0000008288 [ncp.at] TRACE: > AT
0000008291 [ncp.at] TRACE: < OK
0000008292 [ncp.client] TRACE: NCP state changed: 1
0000008292 [net.pppncp] TRACE: NCP event 1
0000008293 [net.ppp.client] TRACE: PPP thread event ADM_UP
0000008293 [net.ppp.client] TRACE: State NONE -> READY
0000008296 [ncp.at] TRACE: > AT
0000008298 [ncp.at] TRACE: < OK
0000008298 [ncp.at] TRACE: > AT+CFUN?
0000008301 [ncp.at] TRACE: < +CFUN: 1,0
0000008301 [ncp.at] TRACE: < OK
0000008302 [ncp.at] TRACE: > AT+CCID
0000008320 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000008320 [ncp.at] TRACE: < OK
0000008321 [ncp.at] TRACE: > AT+CIMI
0000008324 [ncp.at] TRACE: < 204047120764699
0000008324 [ncp.at] TRACE: < OK
0000008325 [ncp.at] TRACE: > AT+CGDCONT?
0000008328 [ncp.at] TRACE: < +CGDCONT: 1,"IP","vfd1.korem2m.com","0.0.0.0",0,0
0000008329 [ncp.at] TRACE: < OK
0000008330 [ncp.at] TRACE: > AT+CFUN?
0000008332 [ncp.at] TRACE: < +CFUN: 1,0
0000008333 [ncp.at] TRACE: < OK
0000008334 [ncp.at] TRACE: > AT+CREG=2
0000008336 [ncp.at] TRACE: < OK
0000008337 [ncp.at] TRACE: > AT+CGREG=2
0000008339 [ncp.at] TRACE: < OK
0000008339 [ncp.client] TRACE: NCP connection state changed: 1
0000008340 [net.pppncp] TRACE: NCP event 2
0000008340 [net.pppncp] TRACE: State changed event: 1
0000008341 [ncp.at] TRACE: > AT+COPS?
0000008341 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN
0000008343 [ncp.at] TRACE: < +COPS: 0
0000008344 [ncp.at] TRACE: < OK
0000008344 [ncp.at] TRACE: > AT+CREG?
0000008347 [ncp.at] TRACE: < +CREG: 2,0
0000008347 [ncp.at] TRACE: < OK
0000008348 [ncp.at] TRACE: > AT+CGREG?
0000008351 [ncp.at] TRACE: < +CGREG: 2,0
0000008351 [ncp.at] TRACE: < OK

Reboot

Serial monitor opened successfully:
0000000861 [app] INFO: INTENDED SD IGNORE
0000000863 [system.nm] INFO: State changed: DISABLED -> IFACE_DOWN
0000000863 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000000864 [ncp.client] TRACE: Powering modem on, ncpId: 0x42
0000000864 [ncp.client] TRACE: Modem already on
0000000865 [ncp.client] TRACE: Setting UART voltage translator state 1
0000000865 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
0000000964 [ncp.client] TRACE: Setting UART voltage translator state 0
Scanning...
I2C device found at address 0x20  !
I2C device found at address 0x21  !
I2C device found at address 0x22  !
done

0000001064 [ncp.client] TRACE: Setting UART voltage translator state 1
0000001064 [ncp.client] TRACE: Muxer is not currently running
0000001065 [ncp.client] TRACE: Initialized muxer @ 921600 baudrate
0000001066 [mux] INFO: Starting GSM07.10 muxer
0000001067 [mux] INFO: GSM07.10 muxer thread started
0000001366 [mux] INFO: Stopping GSM07.10 muxer
0000001366 [mux] INFO: Gracefully stopping GSM07.10 muxer
0000001367 [mux] INFO: Closing all muxed channels
0000001367 [mux] INFO: Muxed channel 1 already closed
0000001367 [mux] INFO: Muxed channel 2 already closed
0000001368 [mux] INFO: Muxed channel 3 already closed
0000001368 [mux] INFO: Muxed channel 4 already closed
0000002018 [mux] INFO: Sending CLD (multiplexer close down)
0000002918 [mux] INFO: Received response to CLD or timed out, exiting multiplexed mode
0000002968 [mux] INFO: GSM07.10 muxer thread exiting
0000002968 [mux] INFO: GSM07.10 muxer stopped
0000002969 [ncp.at] TRACE: > AT
0000003969 [ncp.at] TRACE: > AT
0000004969 [ncp.client] TRACE: Modem is not responsive @ 921600 baudrate
0000004970 [ncp.at] TRACE: > AT
0000004972 [ncp.at] TRACE: < OK
0000004972 [ncp.client] TRACE: NCP ready to accept AT commands
0000004972 [ncp.at] TRACE: > AT
0000004975 [ncp.at] TRACE: < OK
0000004975 [ncp.at] TRACE: > ATI9
0000004989 [ncp.at] TRACE: < 23.60,A01.01
0000004989 [ncp.at] TRACE: < OK
0000004990 [ncp.client] TRACE: App firmware: 101
0000004990 [ncp.at] TRACE: > AT+UGPIOC?
0000004994 [ncp.at] TRACE: < +UGPIOC:
0000004995 [ncp.at] TRACE: < 16,255
0000004995 [ncp.at] TRACE: < 23,255
0000004996 [ncp.at] TRACE: < 24,4
0000004997 [ncp.at] TRACE: < 25,5
0000004997 [ncp.at] TRACE: < 34,12
0000004998 [ncp.at] TRACE: < 35,12
0000004998 [ncp.at] TRACE: < 36,12
0000004999 [ncp.at] TRACE: < 37,12
0000004999 [ncp.at] TRACE: < 42,7
0000005000 [ncp.at] TRACE: < OK
0000005000 [ncp.client] INFO: Using internal SIM card
0000005001 [ncp.at] TRACE: > AT+CPIN?
0000005005 [ncp.at] TRACE: < +CPIN: READY
0000005006 [ncp.at] TRACE: < OK
0000005006 [ncp.at] TRACE: > AT+CCID
0000005026 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000005026 [ncp.at] TRACE: < OK
0000005026 [ncp.at] TRACE: > AT+IFC=2,2
0000005030 [ncp.at] TRACE: < OK
0000005030 [ncp.at] TRACE: > AT
0000006030 [ncp.at] TRACE: > AT
0000006032 [ncp.at] TRACE: < OK
0000006032 [ncp.at] TRACE: > AT+IPR=921600
0000006037 [ncp.at] TRACE: < OK
0000007038 [ncp.at] TRACE: > AT
0000007040 [ncp.at] TRACE: < OK
0000007040 [ncp.at] TRACE: > AT+COPS=3,2
0000007042 [ncp.at] TRACE: < OK
0000007043 [ncp.at] TRACE: > AT+CGEREP=1,0
0000007045 [ncp.at] TRACE: < OK
0000007045 [ncp.at] TRACE: > AT+UPSV=0
0000007047 [ncp.at] TRACE: < OK
0000007048 [ncp.at] TRACE: > AT+CMUX=0,0,,1509,,,,,
0000007176 [ncp.at] TRACE: < OK
0000007176 [mux] INFO: Starting GSM07.10 muxer
0000007177 [mux] INFO: Openning mux channel 0
0000007178 [mux] INFO: GSM07.10 muxer thread started
0000007179 [mux] INFO: Openning mux channel 1
0000007183 [ncp.at] TRACE: > AT
0000007186 [ncp.at] TRACE: < OK
0000007186 [ncp.client] TRACE: NCP state changed: 1
0000007187 [net.pppncp] TRACE: NCP event 1
0000007187 [net.ppp.client] TRACE: PPP thread event ADM_UP
0000007187 [net.ppp.client] TRACE: State NONE -> READY
0000007190 [ncp.at] TRACE: > AT+CFUN?
0000007193 [ncp.at] TRACE: < +CFUN: 1,0
0000007193 [ncp.at] TRACE: < OK
0000007194 [ncp.at] TRACE: > AT+CCID
0000007211 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000007211 [ncp.at] TRACE: < OK
0000007212 [ncp.at] TRACE: > AT+CIMI
0000007215 [ncp.at] TRACE: < 204047120764699
0000007215 [ncp.at] TRACE: < OK
0000007216 [ncp.at] TRACE: > AT+CGDCONT?
0000007220 [ncp.at] TRACE: < +CGDCONT: 1,"IP","vfd1.korem2m.com","0.0.0.0",0,0
0000007220 [ncp.at] TRACE: < OK
0000007221 [ncp.at] TRACE: > AT+CFUN?
0000007224 [ncp.at] TRACE: < +CFUN: 1,0
0000007224 [ncp.at] TRACE: < OK
0000007225 [ncp.at] TRACE: > AT+CREG=2
0000007227 [ncp.at] TRACE: < OK
0000007228 [ncp.at] TRACE: > AT+CGREG=2
0000007231 [ncp.at] TRACE: < OK
0000007231 [ncp.client] TRACE: NCP connection state changed: 1
0000007232 [net.pppncp] TRACE: NCP event 2
0000007232 [net.pppncp] TRACE: State changed event: 1
0000007233 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN
0000007233 [ncp.at] TRACE: > AT+COPS?
0000007236 [ncp.at] TRACE: < +COPS: 0
0000007236 [ncp.at] TRACE: < OK
0000007237 [ncp.at] TRACE: > AT+CREG?
0000007239 [ncp.at] TRACE: < +CREG: 2,0
0000007240 [ncp.at] TRACE: < OK
0000007241 [ncp.at] TRACE: > AT+CGREG?
0000007243 [ncp.at] TRACE: < +CGREG: 2,0
0000007244 [ncp.at] TRACE: < OK

Reboot

The next log is when the device eventually connects

Serial monitor opened successfully:
0000000898 [app] INFO: INTENDED SD IGNORE
0000000900 [system.nm] INFO: State changed: DISABLED -> IFACE_DOWN
0000000901 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000000901 [ncp.client] TRACE: Powering modem on, ncpId: 0x42
0000000902 [net.pppncp] TRACE: NCP event 3
0000000902 [net.pppncp] TRACE: NCP power state changed: IF_POWER_STATE_POWERING_UP
0000000902 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
0000000903 [system.nm] TRACE: Interface 4 power state changed: 4
0000000913 [net.pppncp] TRACE: NCP event 3
0000000913 [net.pppncp] TRACE: NCP power state changed: IF_POWER_STATE_UP
0000000914 [system.nm] TRACE: Interface 4 power state changed: 2
0000000914 [ncp.client] TRACE: Modem powered on
0000000914 [ncp.client] TRACE: Setting UART voltage translator state 1
0000001014 [ncp.client] TRACE: Setting UART voltage translator state 0
Scanning...
I2C device found at address 0x20  !
I2C device found at address 0x21  !
I2C device found at address 0x22  !
done

0000001114 [ncp.client] TRACE: Setting UART voltage translator state 1
0000002115 [ncp.at] TRACE: > AT
0000003115 [ncp.at] TRACE: > AT
0000004115 [ncp.at] TRACE: > AT
0000005115 [ncp.at] TRACE: > AT
0000005118 [ncp.at] TRACE: < OK
0000005118 [ncp.client] TRACE: NCP ready to accept AT commands
0000005119 [ncp.at] TRACE: > AT
0000005121 [ncp.at] TRACE: < OK
0000005121 [ncp.at] TRACE: > ATI9
0000005139 [ncp.at] TRACE: < 23.60,A01.01
0000005139 [ncp.at] TRACE: < OK
0000005140 [ncp.client] TRACE: App firmware: 101
0000005140 [ncp.at] TRACE: > AT+UGPIOC?
0000005144 [ncp.at] TRACE: < +UGPIOC:
0000005145 [ncp.at] TRACE: < 16,255
0000005145 [ncp.at] TRACE: < 23,255
0000005146 [ncp.at] TRACE: < 24,4
0000005146 [ncp.at] TRACE: < 25,5
0000005147 [ncp.at] TRACE: < 34,12
0000005148 [ncp.at] TRACE: < 35,12
0000005148 [ncp.at] TRACE: < 36,12
0000005149 [ncp.at] TRACE: < 37,12
0000005149 [ncp.at] TRACE: < 42,7
0000005150 [ncp.at] TRACE: < OK
0000005150 [ncp.client] INFO: Using internal SIM card
0000005151 [ncp.at] TRACE: > AT+CPIN?
0000005156 [ncp.at] TRACE: < ERROR
0000006156 [ncp.at] TRACE: > AT+CPIN?
0000006161 [ncp.at] TRACE: < +CPIN: READY
0000006161 [ncp.at] TRACE: < OK
0000006162 [ncp.at] TRACE: > AT+CCID
0000006181 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000006182 [ncp.at] TRACE: < OK
0000006182 [ncp.at] TRACE: > AT+IFC=2,2
0000006185 [ncp.at] TRACE: < OK
0000006186 [ncp.at] TRACE: > AT
0000007186 [ncp.at] TRACE: > AT
0000007188 [ncp.at] TRACE: < OK
0000007189 [ncp.at] TRACE: > AT+IPR=921600
0000007193 [ncp.at] TRACE: < OK
0000008193 [ncp.at] TRACE: > AT
0000009193 [ncp.at] TRACE: > AT
0000010193 [ncp.at] TRACE: > AT
0000011193 [ncp.at] TRACE: > AT
0000012193 [ncp.at] TRACE: > AT
0000013193 [ncp.at] TRACE: > AT
0000014193 [ncp.at] TRACE: > AT
0000015193 [ncp.at] TRACE: > AT
0000016193 [ncp.at] TRACE: > AT
0000017193 [ncp.at] TRACE: > AT
0000018193 [ncp.client] ERROR: Failed to perform early initialization
0000018193 [ncp.client] TRACE: Setting UART voltage translator state 0
0000018194 [ncp.client] TRACE: Hard resetting the modem
0000019243 [net.pppncp] TRACE: NCP event 3
0000019243 [net.pppncp] TRACE: NCP power state changed: IF_POWER_STATE_POWERING_UP
0000019244 [system.nm] TRACE: Interface 4 power state changed: 4
0000019244 [net.pppncp] ERROR: Failed to initialize cellular NCP client: -210
0000019344 [ncp.client] TRACE: Powering modem on, ncpId: 0x42
0000019344 [ncp.client] TRACE: Modem already on
0000019345 [net.pppncp] TRACE: NCP event 3
0000019345 [net.pppncp] TRACE: NCP power state changed: IF_POWER_STATE_UP
0000019345 [system.nm] TRACE: Interface 4 power state changed: 2
0000019346 [ncp.client] TRACE: Setting UART voltage translator state 1
0000019446 [ncp.client] TRACE: Setting UART voltage translator state 0
0000019546 [ncp.client] TRACE: Setting UART voltage translator state 1
0000019546 [ncp.client] TRACE: Muxer is not currently running
0000019547 [ncp.client] TRACE: Initialized muxer @ 921600 baudrate
0000019547 [mux] INFO: Starting GSM07.10 muxer
0000019548 [mux] INFO: GSM07.10 muxer thread started
0000019848 [mux] INFO: Stopping GSM07.10 muxer
0000019848 [mux] INFO: Gracefully stopping GSM07.10 muxer
0000019848 [mux] INFO: Closing all muxed channels
0000019849 [mux] INFO: Muxed channel 1 already closed
0000019849 [mux] INFO: Muxed channel 2 already closed
0000019849 [mux] INFO: Muxed channel 3 already closed
0000019850 [mux] INFO: Muxed channel 4 already closed
0000020500 [mux] INFO: Sending CLD (multiplexer close down)
0000021400 [mux] INFO: Received response to CLD or timed out, exiting multiplexed mode
0000021450 [mux] INFO: GSM07.10 muxer thread exiting
0000021450 [mux] INFO: GSM07.10 muxer stopped
0000021451 [ncp.at] TRACE: > AT
0000022451 [ncp.at] TRACE: > AT
0000023451 [ncp.client] TRACE: Modem is not responsive @ 921600 baudrate
0000023452 [ncp.at] TRACE: > AT
0000023454 [ncp.at] TRACE: < OK
0000023454 [ncp.client] TRACE: NCP ready to accept AT commands
0000023455 [ncp.at] TRACE: > AT
0000023457 [ncp.at] TRACE: < OK
0000023457 [ncp.at] TRACE: > ATI9
0000023471 [ncp.at] TRACE: < 23.60,A01.01
0000023471 [ncp.at] TRACE: < OK
0000023471 [ncp.client] TRACE: App firmware: 101
0000023472 [ncp.at] TRACE: > AT+UGPIOC?
0000023476 [ncp.at] TRACE: < +UGPIOC:
0000023476 [ncp.at] TRACE: < 16,255
0000023477 [ncp.at] TRACE: < 23,255
0000023477 [ncp.at] TRACE: < 24,4
0000023478 [ncp.at] TRACE: < 25,5
0000023479 [ncp.at] TRACE: < 34,12
0000023479 [ncp.at] TRACE: < 35,12
0000023480 [ncp.at] TRACE: < 36,12
0000023480 [ncp.at] TRACE: < 37,12
0000023481 [ncp.at] TRACE: < 42,7
0000023482 [ncp.at] TRACE: < OK
0000023482 [ncp.client] INFO: Using internal SIM card
0000023482 [ncp.at] TRACE: > AT+CPIN?
0000023487 [ncp.at] TRACE: < +CPIN: READY
0000023487 [ncp.at] TRACE: < OK
0000023488 [ncp.at] TRACE: > AT+CCID
0000023507 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000023508 [ncp.at] TRACE: < OK
0000023508 [ncp.at] TRACE: > AT+IFC=2,2
0000023512 [ncp.at] TRACE: < OK
0000023512 [ncp.at] TRACE: > AT
0000024512 [ncp.at] TRACE: > AT
0000024514 [ncp.at] TRACE: < OK
0000024514 [ncp.at] TRACE: > AT+IPR=921600
0000024518 [ncp.at] TRACE: < OK
0000025519 [ncp.at] TRACE: > AT
0000025521 [ncp.at] TRACE: < OK
0000025522 [ncp.at] TRACE: > AT+COPS=3,2
0000025524 [ncp.at] TRACE: < OK
0000025524 [ncp.at] TRACE: > AT+CGEREP=1,0
0000025527 [ncp.at] TRACE: < OK
0000025527 [ncp.at] TRACE: > AT+UPSV=0
0000025529 [ncp.at] TRACE: < OK
0000025529 [ncp.at] TRACE: > AT+CMUX=0,0,,1509,,,,,
0000025658 [ncp.at] TRACE: < OK
0000025658 [mux] INFO: Starting GSM07.10 muxer
0000025659 [mux] INFO: Openning mux channel 0
0000025659 [mux] INFO: GSM07.10 muxer thread started
0000025661 [mux] INFO: Openning mux channel 1
0000025665 [ncp.at] TRACE: > AT
0000025667 [ncp.at] TRACE: < OK
0000025667 [ncp.client] TRACE: NCP state changed: 1
0000025667 [net.pppncp] TRACE: NCP event 1
0000025668 [net.ppp.client] TRACE: PPP thread event ADM_UP
0000025668 [net.ppp.client] TRACE: State NONE -> READY
0000025671 [ncp.at] TRACE: > AT+CFUN?
0000025674 [ncp.at] TRACE: < +CFUN: 1,0
0000025675 [ncp.at] TRACE: < OK
0000025675 [ncp.at] TRACE: > AT+CCID
0000025693 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000025693 [ncp.at] TRACE: < OK
0000025694 [ncp.at] TRACE: > AT+CIMI
0000025697 [ncp.at] TRACE: < 204047120764699
0000025698 [ncp.at] TRACE: < OK
0000025699 [ncp.at] TRACE: > AT+CGDCONT?
0000025702 [ncp.at] TRACE: < +CGDCONT: 1,"IP","vfd1.korem2m.com","0.0.0.0",0,0
0000025703 [ncp.at] TRACE: < OK
0000025704 [ncp.at] TRACE: > AT+CFUN?
0000025707 [ncp.at] TRACE: < +CFUN: 1,0
0000025707 [ncp.at] TRACE: < OK
0000025708 [ncp.at] TRACE: > AT+CREG=2
0000025710 [ncp.at] TRACE: < OK
0000025711 [ncp.at] TRACE: > AT+CGREG=2
0000025713 [ncp.at] TRACE: < OK
0000025713 [ncp.client] TRACE: NCP connection state changed: 1
0000025714 [net.pppncp] TRACE: NCP event 2
0000025714 [net.pppncp] TRACE: State changed event: 1
0000025715 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN
0000025715 [ncp.at] TRACE: > AT+COPS?
0000025718 [ncp.at] TRACE: < +COPS: 0
0000025718 [ncp.at] TRACE: < OK
0000025719 [ncp.at] TRACE: > AT+CREG?
0000025721 [ncp.at] TRACE: < +CREG: 2,0
0000025722 [ncp.at] TRACE: < OK
0000025722 [ncp.at] TRACE: > AT+CGREG?
0000025725 [ncp.at] TRACE: < +CGREG: 2,0
0000025726 [ncp.at] TRACE: < OK
0000029826 [ncp.at] TRACE: < +CREG: 5,"001E","919F",3
0000029827 [ncp.at] TRACE: > AT+CIMI
0000029828 [ncp.at] TRACE: < +CGREG: 5,"001E","919F",3,"01"
0000029829 [ncp.at] TRACE: < 204047120764699
0000029830 [ncp.at] TRACE: < OK
0000029930 [ncp.client] TRACE: NCP connection state changed: 2
0000029930 [mux] INFO: Openning mux channel 2
0000030662 [net.pppncp] TRACE: NCP event 100
0000030663 [net.pppncp] TRACE: New auth info
0000030665 [net.pppncp] TRACE: NCP event 2
0000030665 [net.pppncp] TRACE: State changed event: 2
0000030666 [net.ppp.client] TRACE: PPP thread event LOWER_UP
0000030666 [net.ppp.client] TRACE: State READY -> CONNECT
0000030666 [net.ppp.client] TRACE: State CONNECT -> CONNECTING
0000031667 [ncp.at] TRACE: > AT
0000031669 [ncp.at] TRACE: < OK
0000031670 [ncp.at] TRACE: > ATH
0000031671 [ncp.at] TRACE: < OK
0000031672 [ncp.at] TRACE: > ATD*99***1#
0000031675 [ncp.at] TRACE: < CONNECT
0000031675 [net.ppp.client] TRACE: PPP phase -> 3
0000031675 [net.ppp.client] TRACE: PPP phase -> 6
0000031680 [net.ppp.client] TRACE: PPP phase -> 7
0000031683 [net.ppp.client] TRACE: PPP phase -> 9
0000037689 [system.nm] INFO: State changed: IFACE_UP -> IFACE_LINK_UP
0000037690 [net.ppp.client] TRACE: PPP thread event UP
0000037690 [net.ppp.client] TRACE: State CONNECTING -> CONNECTED
0000037691 [net.pppncp] TRACE: Negotiated MTU: 1500
0000037691 [system.nm] INFO: State changed: IFACE_LINK_UP -> IP_CONFIGURED
0000037692 [net.ppp.client] TRACE: PPP phase -> 10
0000037693 [system] INFO: Cloud: connecting
0000037697 [system] INFO: Cloud socket connected
0000037697 [comm.protocol.handshake] INFO: Establish secure connection
0000037702 [comm.dtls] INFO: session has 0 uses
0000037718 [comm.dtls] INFO: (CMPL,RENEG,NO_SESS,ERR) restoreStatus=0
0000037718 [comm.dtls] INFO: out_ctr 0,1,0,0,0,0,0,14, next_coap_id=b
0000037719 [comm.dtls] INFO: restored session from persisted session data. next_msg_id=11
0000037720 [comm.dtls] INFO: session cmd (CLS,DIS,MOV,LOD,SAV): 2
0000038096 [ncp.at] TRACE: > AT+CFUN?
0000038100 [ncp.at] TRACE: < +CFUN: 1,0
0000038100 [ncp.at] TRACE: < OK
0000038102 [ncp.at] TRACE: > AT+CCID
0000038119 [ncp.at] TRACE: < +CCID: 89314404000710740227
0000038120 [ncp.at] TRACE: < OK
0000038121 [ncp.at] TRACE: > AT+CGSN
0000038136 [ncp.at] TRACE: < 358887097724387
0000038136 [ncp.at] TRACE: < OK
0000038138 [ncp.at] TRACE: > ATI9
0000038149 [ncp.at] TRACE: < 23.60,A01.01
0000038150 [ncp.at] TRACE: < OK
0000038151 [comm.protocol.handshake] INFO: Skipping HELLO message
0000038158 [comm.protocol] INFO: Checksum has not changed; not sending application DESCRIBE
0000038159 [comm.protocol] INFO: Checksum has not changed; not sending subscriptions
0000038681 [comm.dtls] INFO: session cmd (CLS,DIS,MOV,LOD,SAV): 4
0000038682 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0000038683 [comm.protocol] TRACE: message id 12 complete with code 0.00
0000038683 [comm.protocol] TRACE: rcv'd message type=13
0000038718 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0000038719 [comm.protocol] TRACE: message id 13 complete with code 0.00
0000038720 [comm.protocol] TRACE: rcv'd message type=13
0000038820 [comm.protocol] TRACE: Reply recieved: type=2, code=0
0000038821 [comm.protocol] TRACE: message id 14 complete with code 0.00
0000038822 [comm.protocol] TRACE: rcv'd message type=13
0000038839 [comm.protocol] TRACE: Reply recieved: type=2, code=69
0000038840 [comm.protocol] TRACE: message id 15 complete with code 2.05
0000038841 [comm.protocol] INFO: Received TIME response: 1650187536
0000038841 [comm.protocol] TRACE: rcv'd message type=12
0000038941 [system] INFO: Cloud connected
0000038941 [app] INFO: connected to the cloud in 38941 ms
0000038942 [app] INFO: FIRST IDLE
0000038943 [app] INFO: CHECKING SYSTEM
0000038943 [app] INFO: STORING SYS READINGS
0000038944 [app] INFO: SIZE OF S: 400

Is the device resetting with an SOS code? You can log this after reboot by using System.resetReason()

It’s impossible to say why the device is resetting, however Device OS will never reset the entire device except when requested (after an OTA, or if System.reset is called).

If there is a connection problem, the modem will be reset after 10 minutes of failing to connect, but this only powers down the modem, and the device will not go through setup() again.

1 Like

No SOS code, but I have a LED that is mirrored to the onboard LED, and that LED very briefly flashes red before reset.

I will start logging the resetReason and get back with more information when I have it. I just had a series of restarts while trying to connect, see logs below. One interesting thing is that I manually put the device into safemode twice during this series of restarts. Both times it attempted to connect but then restarts just as it does when my firmware running.

The fact that the device is also restarting in safemode suggests to me that it is potentially a hardware issue? In the logs below the device seems to restart after opening mux channel 1.

Serial monitor opened successfully:


Serial connection closed.  Attempting to reconnect...
Serial monitor opened successfully:
0000000860 [app] INFO: INTENDED SD IGNORE
0000000862 [system.nm] INFO: State changed: DISABLED -> IFACE_DOWN
0000000862 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000000863 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
Scanning...
I2C device found at address 0x20  !
I2C device found at address 0x21  !
I2C device found at address 0x22  !
done

0000005097 [ncp.client] INFO: Using internal SIM card
0000007475 [mux] INFO: Starting GSM07.10 muxer
0000007476 [mux] INFO: Openning mux channel 0
0000007476 [mux] INFO: GSM07.10 muxer thread started
0000007478 [mux] INFO: Openning mux channel 1


Serial connection closed.  Attempting to reconnect...
Serial monitor opened successfully:
0000000860 [app] INFO: INTENDED SD IGNORE
0000000862 [system.nm] INFO: State changed: DISABLED -> IFACE_DOWN
0000000862 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000000863 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
Scanning...
I2C device found at address 0x20  !
I2C device found at address 0x21  !
I2C device found at address 0x22  !
done

0000005098 [ncp.client] INFO: Using internal SIM card
0000007476 [mux] INFO: Starting GSM07.10 muxer
0000007477 [mux] INFO: Openning mux channel 0
0000007477 [mux] INFO: GSM07.10 muxer thread started
0000007479 [mux] INFO: Openning mux channel 1


Serial connection closed.  Attempting to reconnect...
Serial monitor opened successfully:
0000000861 [app] INFO: INTENDED SD IGNORE
0000000863 [system.nm] INFO: State changed: DISABLED -> IFACE_DOWN
0000000863 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000000864 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
Scanning...
I2C device found at address 0x20  !
I2C device found at address 0x21  !
I2C device found at address 0x22  !
done

0000001064 [mux] INFO: Starting GSM07.10 muxer
0000001065 [mux] INFO: GSM07.10 muxer thread started
0000001364 [mux] INFO: Stopping GSM07.10 muxer
0000001364 [mux] INFO: Gracefully stopping GSM07.10 muxer
0000001365 [mux] INFO: Closing all muxed channels
0000001365 [mux] INFO: Muxed channel 1 already closed
0000001365 [mux] INFO: Muxed channel 2 already closed
0000001366 [mux] INFO: Muxed channel 3 already closed
0000001366 [mux] INFO: Muxed channel 4 already closed
0000002017 [mux] INFO: Sending CLD (multiplexer close down)
0000002918 [mux] INFO: Received response to CLD or timed out, exiting multiplexed mode
0000002968 [mux] INFO: GSM07.10 muxer thread exiting
0000002968 [mux] INFO: GSM07.10 muxer stopped
0000004999 [ncp.client] INFO: Using internal SIM card


Serial connection closed.  Attempting to reconnect...
Serial monitor opened successfully:
0000000862 [app] INFO: INTENDED SD IGNORE
0000000864 [system.nm] INFO: State changed: DISABLED -> IFACE_DOWN
0000000864 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000000865 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
Scanning...
I2C device found at address 0x20  !
I2C device found at address 0x21  !
I2C device found at address 0x22  !
done

0000005103 [ncp.client] INFO: Using internal SIM card
0000007486 [mux] INFO: Starting GSM07.10 muxer
0000007487 [mux] INFO: Openning mux channel 0
0000007487 [mux] INFO: GSM07.10 muxer thread started
0000007489 [mux] INFO: Openning mux channel 1


Serial connection closed.  Attempting to reconnect...
Serial monitor opened successfully:
0000000858 [app] INFO: INTENDED SD IGNORE
0000000860 [system.nm] INFO: State changed: DISABLED -> IFACE_DOWN
0000000860 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000000861 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
Scanning...
I2C device found at address 0x20  !
I2C device found at address 0x21  !
I2C device found at address 0x22  !
done

0000001062 [mux] INFO: Starting GSM07.10 muxer
0000001063 [mux] INFO: GSM07.10 muxer thread started
0000001362 [mux] INFO: Stopping GSM07.10 muxer
0000001363 [mux] INFO: Gracefully stopping GSM07.10 muxer
0000001363 [mux] INFO: Closing all muxed channels
0000001363 [mux] INFO: Muxed channel 1 already closed
0000001364 [mux] INFO: Muxed channel 2 already closed
0000001364 [mux] INFO: Muxed channel 3 already closed
0000001364 [mux] INFO: Muxed channel 4 already closed
0000002015 [mux] INFO: Sending CLD (multiplexer close down)
0000002916 [mux] INFO: Received response to CLD or timed out, exiting multiplexed mode
0000002966 [mux] INFO: GSM07.10 muxer thread exiting
0000002967 [mux] INFO: GSM07.10 muxer stopped
0000004997 [ncp.client] INFO: Using internal SIM card
0000007171 [mux] INFO: Starting GSM07.10 muxer
0000007172 [mux] INFO: Openning mux channel 0
0000007172 [mux] INFO: GSM07.10 muxer thread started
0000007174 [mux] INFO: Openning mux channel 1

A hardware or power issue is a possibility. Around the time the device resets is when the cellular modem starts to use higher amounts of power.

Hi, is the boron powering some sensors? Could these sensors be drawing so much current for the Boron, that it “faints” then resets?

I have swapped to a new Boron, so will see if the issue arises again. I have added in the resetReason also, so at least if it happens again I should capture that information.

Yes the Boron is powering a few sensors, I am currently using a power supply though. I will try get some scope readings and see if I can see a surge in power usage around when the resets were occurring.

This is a Boron 2G/3G, correct? How are you powering from a power supply? USB, external supply on VUSB, or via the LiPo connector? And do you have a LiPo battery attached?

Yes this is a Boron 2G/3G, powered by desktop external supply, 12V which we regulate down to 5V and supply via VUSB, there is no LiPo battery currently attached. In practice we use a 12V battery, but currently on the bench I am using a power supply.

Are you setting the maximum input current in the PMIC, powerSourceMaxCurrent?

The default is 900 mA, and this isn’t enough to power the Boron 2G/3G without a LiPo battery attached. You should set it to 1500. This is only necessary when powering by VUSB/VIN and not using a battery.

3 Likes

Perfect, wasn’t aware of this. Will set PMIC setting and monitor.

Thank you