Cellular modem sample does not work on RAK5010 board

[olalonde]

I received a brand new RAK5010 board (nrf52840). I connected GPS and LTE antennas to corresponding ipex connectors and inserted a working SIM card with data from Claro (Dominican Republic). I am powering the board through USB connection.

I then connect to the board using SEGGER debugger and build/flash the "Cellular modem" sample using Nordic VSCode extension. Here's what I then see in RTT logs:

SEGGER J-Link V7.94i - Real time terminal output
SEGGER J-Link (unknown) V2.0, SN=802000342
Process: JLinkExe
*** Booting nRF Connect SDK v2.9.1-60d0d6c8d42d ***
*** Using Zephyr OS v3.7.99-ca954a6216c9 ***
Powering on modem
Bring up network interface
Waiting for L4 connected
[00:00:11.916,900] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_init_chat_script
[00:00:11.916,931] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 0
[00:00:11.916,961] <dbg> modem_chat: modem_chat_script_next: sending: ATE0
[00:00:11.938,201] <dbg> modem_chat: modem_chat_on_unknown_command_received: ATE0
[00:00:11.938,232] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.938,262] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 1
[00:00:11.938,293] <dbg> modem_chat: modem_chat_script_next: sending: AT+CFUN=4
[00:00:12.088,104] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.088,134] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_in



[00:00:12.371,673] <dbg> modem_chat: modem_chat_script_next: sending: AT+CMUX=0,0,5,127
[00:00:12.394,073] <dbg> modem_chat: modem_chat_on_unknown_command_received: OK
[00:00:12.671,813] <dbg> modem_chat: modem_chat_script_stop: quectel_bg95_init_chat_script: complete
[00:00:12.793,487] <inf> modem_at_shell: pipe connected
[00:00:12.793,548] <inf> modem_at_shell: opening pipe
[00:00:12.815,368] <inf> modem_at_shell: pipe opened
[00:00:12.815,460] <inf> modem_at_shell: chat attached
[00:00:12.936,950] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_dial_chat_script
[00:00:12.936,981] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_dial_chat_script: step: 0
[00:00:12.937,011] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGACT=0,1
[00:00:12.960,266] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.960,296] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_dial_chat_script: step: 1
[00:00:12.960,327] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGDCONT=1,"IP","internet.ideasclaro.com.do"
[00:00:13.011,138] <dbg> modem_chat: modem_chat_log_received_command: OK
--- 3 messages dropped ---
[00:00:13.011,169] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_dial_chat_script: step: 3
[00:00:13.011,199] <dbg> modem_chat: modem_chat_script_next: sending: ATD*99***1#
[00:00:13.011,322] <dbg> modem_chat: modem_chat_script_stop: quectel_bg95_dial_chat_script: complete
[00:00:13.034,942] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x0d because 0x7e was expected.
[00:00:13.034,973] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x0a because 0x7e was expected.
[00:00:13.034,973] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x43 because 0x7e was expected.
[00:00:13.035,003] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x4f because 0x7e was expected.
[00:00:13.035,034] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x4e because 0x7e was expected.
[00:00:13.035,034] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x4e because 0x7e was expected.
[00:00:13.035,064] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x45 because 0x7e was expected.
[00:00:13.035,064] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x43 because 0x7e was expected.
[00:00:13.035,095] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x54 because 0x7e was expected.
[00:00:13.035,095] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x20 because 0x7e was expected.
[00:00:13.035,125] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x31 because 0x7e was expected.
[00:00:13.035,125] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x35 because 0x7e was expected.
[00:00:13.035,156] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:13.035,156] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:13.035,186] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:13.035,186] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:13.035,217] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:13.035,217] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:13.035,247] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:13.035,278] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x0d because 0x7e was expected.
[00:00:13.035,308] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x0a because 0x7e was expected.
[00:00:15.011,596] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_periodic_chat_script
[00:00:15.011,657] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 0
[00:00:15.011,688] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?
[00:00:15.034,667] <dbg> modem_chat: modem_chat_log_received_command: +CREG:  1 0
[00:00:15.034,729] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:15.034,759] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 1
[00:00:15.034,790] <dbg> modem_chat: modem_chat_script_next: sending: AT+CEREG?
[00:00:15.057,769] <dbg> modem_chat: modem_chat_log_received_command: +CEREG:  1 4
[00:00:15.057,830] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:15.057,861] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 2
[00:00:15.057,891] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGREG?
[00:00:15.080,871] <dbg> modem_chat: modem_chat_log_received_command: +CGREG:  1 0
[00:00:15.080,963] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:15.080,993] <dbg> modem_chat: modem_chat_script_stop: quectel_bg95_periodic_chat_script: complete
[00:00:15.858,184] <dbg> modem_chat: modem_chat_log_received_command: +CREG:  2
[00:00:15.858,276] <dbg> modem_chat: modem_chat_log_received_command: +CGREG:  2
[00:00:17.081,207] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_periodic_chat_script
[00:00:17.081,268] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 0
[00:00:17.081,298] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?
[00:00:21.081,359] <wrn> modem_chat: quectel_bg95_periodic_chat_script: timed out
[00:00:23.081,542] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_periodic_chat_script
[00:00:23.081,604] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 0
[00:00:23.081,634] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?
[00:00:27.081,695] <wrn> modem_chat: quectel_bg95_periodic_chat_script: timed out
[00:00:29.081,878] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_periodic_chat_script
[00:00:29.081,939] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 0
[00:00:29.081,970] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?
[00:00:33.082,031] <wrn> modem_chat: quectel_bg95_periodic_chat_script: timed out
[00:00:35.082,214] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_periodic_chat_script
[00:00:35.082,244] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 0
[00:00:35.082,275] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?

It seems to be stuck in a loop at AT+CREG?. I tried changing CONFIG_MODEM_CELLULAR_APN in boards/rak5010_nrf52840.conf to CONFIG_MODEM_CELLULAR_APN="internet.ideasclaro.com.do" but it didn't help. There is no LTE-m or nb-iot in Dominican Republic so I expect the modem to connect to EGPRS. I used the RAK5010 firmware and connected via UART to try some UART commands and confirm that it's seeing the networks:

AT+COPS=?
+COPS: (1,"CLARO DOM","ClaroDOM","37002",0),(3,"Viva DO","Viva","37004",0),(1,"ALTICE","ALTICE","37001",0),,(0,1,2,3,4),(0,1,2)

Am I doing something wrong or is there maybe an issue with the Quectel BG95 driver?

[bjarki-andreasen]

I don't think the ussue has anything to do with the sim card or network in general, the modem stops responding to AT commands, and times out. Likely a UART, UART flow control, or CMUX issue. The simplest way to diagnose the issue is to attatch a logger to the UART tx and rx pins, and see if anything is actually sent when the script starts timing out

[olalonde]

Ok thanks. I'll see if I can do that... not sure if the modem's UART is easily accessible. I will also try connecting a battery to the board, it seems it might be a current issue.

[olalonde]

Actually, I'm still seeing timeout errors, again at the AT+CREG? step:

SEGGER J-Link V7.94i - Real time terminal output
SEGGER J-Link (unknown) V2.0, SN=802000342
Process: JLinkExe
*** Booting nRF Connect SDK v2.9.1-60d0d6c8d42d ***
*** Using Zephyr OS v3.7.99-ca954a6216c9 ***
Powering on modem
Bring up network interface
Waiting for L4 connected
[00:00:11.828,186] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_init_chat_script
[00:00:11.828,216] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 0
[00:00:11.828,247] <dbg> modem_chat: modem_chat_script_next: sending: ATE0
[00:00:11.849,426] <dbg> modem_chat: modem_chat_on_unknown_command_received: ATE0
[00:00:11.849,487] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.849,517] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 1
[00:00:11.849,548] <dbg> modem_chat: modem_chat_script_next: sending: AT+CFUN=4
[00:00:11.972,015] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.972,045] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 2
[00:00:11.972,076] <dbg> modem_chat: modem_chat_script_next: sending: AT+CMEE=1
[00:00:11.993,804] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.993,865] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 3
[00:00:11.993,896] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG=1
[00:00:12.015,533] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.015,563] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 4
[00:00:12.015,594] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGREG=1
[00:00:12.037,292] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.037,322] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 5
[00:00:12.037,353] <dbg> modem_chat: modem_chat_script_next: sending: AT+CEREG=1
[00:00:12.059,051] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.059,082] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 6
[00:00:12.059,112] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?
[00:00:12.080,718] <dbg> modem_chat: modem_chat_log_received_command: +CREG:  1 0
[00:00:12.080,780] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.080,810] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 7
[00:00:12.080,841] <dbg> modem_chat: modem_chat_script_next: sending: AT+CEREG?
[00:00:12.102,508] <dbg> modem_chat: modem_chat_log_received_command: +CEREG:  1 4
[00:00:12.102,569] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.102,600] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 8
[00:00:12.102,630] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGREG?
[00:00:12.124,328] <dbg> modem_chat: modem_chat_log_received_command: +CGREG:  1 0
[00:00:12.124,389] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.124,420] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 9
[00:00:12.124,450] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGSN
[00:00:12.146,240] <dbg> modem_chat: modem_chat_log_received_command:  864593056768822
[00:00:12.146,270] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 10
[00:00:12.146,331] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.146,331] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 11
[00:00:12.146,392] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGMM
--- 3 messages dropped ---
[00:00:12.167,968] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 13
[00:00:12.167,999] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGMI
[00:00:12.189,483] <dbg> modem_chat: modem_chat_log_received_command:  Quectel
[00:00:12.189,514] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 14
[00:00:12.189,575] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.189,605] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 15
[00:00:12.189,636] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGMR
[00:00:12.211,151] <dbg> modem_chat: modem_chat_log_received_command:  BG95M3LAR02A03
[00:00:12.211,181] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 16
[00:00:12.211,242] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.211,273] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 17
[00:00:12.211,303] <dbg> modem_chat: modem_chat_script_next: sending: AT+CIMI
[00:00:12.232,971] <dbg> modem_chat: modem_chat_log_received_command:  370021201019608
[00:00:12.233,032] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 18
[00:00:12.233,093] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.233,093] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 19
[00:00:12.233,123] <dbg> modem_chat: modem_chat_script_next: sending: AT+QCCID
[00:00:12.254,852] <dbg> modem_chat: modem_chat_log_received_command: +QCCID:  89010210524010196085
[00:00:12.254,882] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 20
[00:00:12.254,974] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.255,004] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_init_chat_script: step: 21
[00:00:12.255,035] <dbg> modem_chat: modem_chat_script_next: sending: AT+CMUX=0,0,5,127
[00:00:12.277,343] <dbg> modem_chat: modem_chat_on_unknown_command_received: OK
[00:00:12.555,206] <dbg> modem_chat: modem_chat_script_stop: quectel_bg95_init_chat_script: complete
[00:00:12.676,879] <inf> modem_at_shell: pipe connected
[00:00:12.676,940] <inf> modem_at_shell: opening pipe
[00:00:12.698,822] <inf> modem_at_shell: pipe opened
[00:00:12.698,883] <inf> modem_at_shell: chat attached
[00:00:12.820,373] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_dial_chat_script
[00:00:12.820,404] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_dial_chat_script: step: 0
[00:00:12.820,434] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGACT=0,1
[00:00:12.843,688] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:12.843,719] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_dial_chat_script: step: 1
[00:00:12.843,750] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGDCONT=1,"IP","internet"
[00:00:12.893,707] <dbg> modem_chat: modem_chat_log_received_command: OK
--- 3 messages dropped ---
[00:00:12.893,737] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_dial_chat_script: step: 3
[00:00:12.893,768] <dbg> modem_chat: modem_chat_script_next: sending: ATD*99***1#
[00:00:12.893,890] <dbg> modem_chat: modem_chat_script_stop: quectel_bg95_dial_chat_script: complete
[00:00:12.917,572] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x0d because 0x7e was expected.
[00:00:12.917,572] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x0a because 0x7e was expected.
[00:00:12.917,602] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x43 because 0x7e was expected.
[00:00:12.917,633] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x4f because 0x7e was expected.
[00:00:12.917,633] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x4e because 0x7e was expected.
[00:00:12.917,663] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x4e because 0x7e was expected.
[00:00:12.917,663] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x45 because 0x7e was expected.
[00:00:12.917,694] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x43 because 0x7e was expected.
[00:00:12.917,694] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x54 because 0x7e was expected.
[00:00:12.917,724] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x20 because 0x7e was expected.
[00:00:12.917,755] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x31 because 0x7e was expected.
[00:00:12.917,785] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x35 because 0x7e was expected.
[00:00:12.917,785] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:12.917,816] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:12.917,816] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:12.917,846] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:12.917,846] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:12.917,877] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:12.917,907] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x30 because 0x7e was expected.
[00:00:12.917,907] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x0d because 0x7e was expected.
[00:00:12.917,938] <dbg> modem_ppp: modem_ppp_is_byte_expected: Dropping byte 0x0a because 0x7e was expected.
[00:00:16.386,291] <dbg> modem_chat: modem_chat_log_received_command: +CREG:  2
[00:00:16.386,413] <dbg> modem_chat: modem_chat_log_received_command: +CGREG:  2
[00:00:16.415,832] <dbg> modem_chat: modem_chat_log_received_command: +CREG:  1
[00:00:16.440,093] <dbg> modem_ppp: modem_ppp_process_received_byte: Receiving PPP frame
[00:00:16.440,307] <dbg> modem_ppp: modem_ppp_process_received_byte: Received PPP frame (len 29)
[00:00:16.440,734] <dbg> modem_ppp: modem_ppp_process_received_byte: Receiving PPP frame
[00:00:16.440,826] <dbg> modem_ppp: modem_ppp_process_received_byte: Received PPP frame (len 12)
[00:00:16.467,163] <dbg> modem_ppp: modem_ppp_process_received_byte: Receiving PPP frame
[00:00:16.467,254] <dbg> modem_ppp: modem_ppp_process_received_byte: Received PPP frame (len 13)
[00:00:16.491,119] <dbg> modem_ppp: modem_ppp_process_received_byte: Receiving PPP frame
[00:00:16.491,241] <dbg> modem_ppp: modem_ppp_process_received_byte: Received PPP frame (len 12)
[00:00:16.519,073] <dbg> modem_ppp: modem_ppp_process_received_byte: Receiving PPP frame
[00:00:16.519,195] <dbg> modem_ppp: modem_ppp_process_received_byte: Received PPP frame (len 12)
[00:00:16.519,226] <dbg> modem_ppp: modem_ppp_process_received_byte: Receiving PPP frame
[00:00:16.519,317] <dbg> modem_ppp: modem_ppp_process_received_byte: Received PPP frame (len 9)
[00:00:31.416,076] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_periodic_chat_script
[00:00:31.416,107] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 0
[00:00:31.416,137] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?
[00:00:35.416,229] <wrn> modem_chat: quectel_bg95_periodic_chat_script: timed out
[00:00:50.416,412] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_periodic_chat_script
[00:00:50.416,473] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 0
[00:00:50.416,503] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?
[00:00:54.416,564] <wrn> modem_chat: quectel_bg95_periodic_chat_script: timed out
[00:01:09.416,748] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_periodic_chat_script
[00:01:09.416,809] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 0
[00:01:09.416,839] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?
[00:01:13.416,900] <wrn> modem_chat: quectel_bg95_periodic_chat_script: timed out
[00:01:28.417,083] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_periodic_chat_script
[00:01:28.417,144] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 0
[00:01:28.417,175] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?
[00:01:32.417,236] <wrn> modem_chat: quectel_bg95_periodic_chat_script: timed out
[00:01:47.417,419] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_periodic_chat_script
[00:01:47.417,480] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 0
[00:01:47.417,510] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?
[00:01:51.417,572] <wrn> modem_chat: quectel_bg95_periodic_chat_script: timed out
L4 was not connected in time
[00:02:06.417,755] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_periodic_chat_script
[00:02:06.417,816] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 0
[00:02:06.417,846] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?
[00:02:10.417,907] <wrn> modem_chat: quectel_bg95_periodic_chat_script: timed out
[00:02:25.418,090] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_periodic_chat_script
[00:02:25.418,151] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 0
[00:02:25.418,182] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?
[00:02:29.418,243] <wrn> modem_chat: quectel_bg95_periodic_chat_script: timed out
[00:02:44.418,426] <dbg> modem_chat: modem_chat_script_start: running script: quectel_bg95_periodic_chat_script
[00:02:44.418,487] <dbg> modem_chat: modem_chat_script_next: quectel_bg95_periodic_chat_script: step: 0
[00:02:44.418,518] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?

AFAIK I can't directly connect to the modem - nrf52840 uart connection. But maybe there's a way to get more logging in software? Really not sure how to further debug this issue.

[olalonde]

Tried increasing CONFIG_MODEM_CELLULAR_PERIODIC_SCRIPT_MS but that didn't help.