cellular_modem: Pinnacle 100 does not work with CONFIG_UART_INTERRUPT_DRIVEN

[rerickson1]

Describe the bug
When running samples/net/cellular_modem with board pinnacle_100_dvk and option CONFIG_UART_INTERRUPT_DRIVEN, the sample hangs.

To Reproduce
Steps to reproduce the behavior:

1. cd samples/net/cellular_modem
2. change boards/pinnacle_100_dvk.conf to enable CONFIG_UART_INTERRUPT_DRIVEN instead of CONFIG_UART_ASYNC_API
3. west build -p -b pinnacle_100_dvk
4. west flash

Expected behavior
Pinnacle 100 DVK should be able to run that sample with both UART APIs.

Impact
This is an annoyance at this point.

Logs and console output
Sample hangs at this point:

*** Booting Zephyr OS build v3.6.0-1209-g95040f35a779 ***
Powering on modem
Bring up network interface
Waiting for L4 connected
[00:00:10.352,447] <dbg> modem_chat: modem_chat_script_start: running script: swir_hl7800_init_chat_script
[00:00:10.352,478] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 0
[00:00:10.352,508] <dbg> modem_chat: modem_chat_script_next: sending: AT
[00:00:10.372,558] <dbg> modem_chat: modem_chat_on_unknown_command_received: KSUP: 0
[00:00:10.372,589] <dbg> modem_chat: modem_chat_on_unknown_command_received: OK
[00:00:10.452,697] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 1
[00:00:10.452,728] <dbg> modem_chat: modem_chat_script_next: sending: AT
[00:00:10.474,029] <dbg> modem_chat: modem_chat_on_unknown_command_received: OK
[00:00:10.552,886] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 2
[00:00:10.552,917] <dbg> modem_chat: modem_chat_script_next: sending: AT
[00:00:10.574,218] <dbg> modem_chat: modem_chat_on_unknown_command_received: OK
[00:00:10.653,045] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 3
[00:00:10.653,076] <dbg> modem_chat: modem_chat_script_next: sending: AT
[00:00:10.674,285] <dbg> modem_chat: modem_chat_on_unknown_command_received: OK
[00:00:10.753,234] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 4
[00:00:10.753,265] <dbg> modem_chat: modem_chat_script_next: sending: ATE0
[00:00:10.776,153] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:10.776,184] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 5
[00:00:10.776,214] <dbg> modem_chat: modem_chat_script_next: sending: AT+CFUN=1
[00:00:11.223,632] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.223,663] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 6
[00:00:11.223,693] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGACT=0
[00:00:11.223,785] <dbg> modem_chat: modem_chat_log_received_command: +CEREG:  2
[00:00:11.247,528] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.247,558] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 7
[00:00:11.247,589] <dbg> modem_chat: modem_chat_script_next: sending: AT+CFUN=4
[00:00:11.738,128] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.738,189] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 8
[00:00:11.738,220] <dbg> modem_chat: modem_chat_script_next: sending: AT+CMEE=1
[00:00:11.738,311] <dbg> modem_chat: modem_chat_log_received_command: +CEREG:  0
[00:00:11.762,939] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.762,969] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 9
[00:00:11.763,000] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG=1
[00:00:11.786,590] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.786,621] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 10
[00:00:11.786,651] <dbg> modem_chat: modem_chat_script_next: sending: AT+CEREG=1
[00:00:11.810,455] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.810,485] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 11
[00:00:11.810,516] <dbg> modem_chat: modem_chat_script_next: sending: AT+CREG?
[00:00:11.833,099] <dbg> modem_chat: modem_chat_log_received_command: +CREG:  1 0
[00:00:11.833,160] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.833,190] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 12
[00:00:11.833,221] <dbg> modem_chat: modem_chat_script_next: sending: AT+CEREG?
[00:00:11.855,468] <dbg> modem_chat: modem_chat_log_received_command: +CEREG:  1 0
[00:00:11.855,529] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.855,560] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 13
[00:00:11.855,590] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGSN
[00:00:11.877,380] <dbg> modem_chat: modem_chat_log_received_command:  354616090299517
[00:00:11.877,410] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 14
[00:00:11.877,471] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.877,502] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 15
[00:00:11.877,532] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGMM
[00:00:11.899,871] <dbg> modem_chat: modem_chat_log_received_command:  HL7800
[00:00:11.899,902] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 16
[00:00:11.899,932] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.899,963] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 17
[00:00:11.899,993] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGMI
[00:00:11.922,302] <dbg> modem_chat: modem_chat_log_received_command:  Sierra Wireless
[00:00:11.922,332] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 18
[00:00:11.922,393] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.922,424] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 19
[00:00:11.922,454] <dbg> modem_chat: modem_chat_script_next: sending: AT+CGMR
[00:00:11.944,213] <dbg> modem_chat: modem_chat_log_received_command:  HL7800.4.7.1.0
[00:00:11.944,244] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 20
[00:00:11.944,305] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.944,335] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 21
[00:00:11.944,366] <dbg> modem_chat: modem_chat_script_next: sending: AT+CIMI
[00:00:11.966,125] <dbg> modem_chat: modem_chat_log_received_command:  001010123456789
[00:00:11.966,156] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 22
[00:00:11.966,217] <dbg> modem_chat: modem_chat_log_received_command: OK
[00:00:11.966,247] <dbg> modem_chat: modem_chat_script_next: swir_hl7800_init_chat_script: step: 23
[00:00:11.966,278] <dbg> modem_chat: modem_chat_script_next: sending: AT+CMUX=0,0,5,127
[00:00:11.966,339] <dbg> modem_chat: modem_chat_script_stop: swir_hl7800_init_chat_script: complete

[bjarki-andreasen]

Could you enable CONFIG_MODEM_LOG_LEVEL_DBG=y and run the sample? I would like to know what state the modem driver is in :)

[github-actions]

This issue has been marked as stale because it has been open (more than) 60 days with no activity. Remove the stale label or add a comment saying that you would like to have the label removed otherwise this issue will automatically be closed in 14 days. Note, that you can always re-open a closed issue at any time.

[github-actions]

This issue has been marked as stale because it has been open (more than) 60 days with no activity. Remove the stale label or add a comment saying that you would like to have the label removed otherwise this issue will automatically be closed in 14 days. Note, that you can always re-open a closed issue at any time.