Open zafersn opened 4 days ago
Anyone ask for additional information on this issue? I am still trying to fix it but have not been able to do that so far. And also, Anyone who can shed some light on where the possible issue is and how to debug it will be appreciated
@zafersn based on the log message output you are using a modified HL7800 driver and not the one in the tree. Please provide logs from an unmodified driver.
@jukkar can I get the label platform: Laird Connectivity
changed to platform: Ezurio
.
Laird Connectivity is no longer valid because we have renamed the company.
hi @rerickson1 I was working on a custom driver based on your company's provided hl driver, thanks for that. I just created an sample app with default hl7800 driver, and the result is the same.
P.S. I have lots of debug messages in net_dns_resolve:, socket files. please ignore them..
here is the logs belongs to the hl7800 driver:
[00:00:00.064,000] <inf> modem_hl7800: Sleep State: UNINITIALIZED
[00:00:00.064,000] <inf> modem_hl7800: Network State: 0 NOT_REGISTERED
[00:00:00.064,000] <inf> modem_hl7800: Startup State: UNKNOWN
[00:00:00.064,000] <inf> modem_hl7800: Modem Run
[00:00:00.074,000] <dbg> modem_hl7800: modem_boot_handler: Initialization
[00:00:06.277,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +CEREG: (len:1)
[00:00:06.277,000] <dbg> modem_hl7800: on_cmd_network_report: Network status: 2
[00:00:06.277,000] <inf> modem_hl7800: Network State: 2 SEARCHING
[00:00:06.507,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +KSUP: (len:1)
[00:00:06.507,000] <inf> modem_hl7800: Startup State: READY
[00:00:06.507,000] <inf> modem_hl7800: Sleep State: AWAKE
[00:00:06.507,000] <inf> modem_hl7800: Modem booted!
[00:00:06.507,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CEREG=0]
[00:00:06.521,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:06.521,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT&V]
[00:00:06.589,000] <dbg> modem_hl7800: hl7800_rx: HANDLE ACTIVE PROFILE: (len:0)
[00:00:06.589,000] <dbg> modem_hl7800: wait_for_modem_data: cur:0, exp:64
[00:00:06.639,000] <dbg> modem_hl7800: profile_handler: length: 61: E0 Q0 V1 X4 &C1 &D1 &R1 &S0 +IFC=2,2 &K3 +IPR=115200 +FCLASS0
[00:00:06.639,000] <dbg> modem_hl7800: profile_handler: echo: 0
[00:00:06.639,000] <dbg> modem_hl7800: hl7800_rx: HANDLE STORED PROFILE 0: (len:0)
[00:00:06.639,000] <dbg> modem_hl7800: profile_handler: length: 61: E0 Q0 V1 X4 &C1 &D1 &R1 &S0 +IFC=2,2 &K3 +IPR=115200 +FCLASS0
[00:00:06.639,000] <dbg> modem_hl7800: profile_handler: echo: 0
[00:00:06.639,000] <dbg> modem_hl7800: hl7800_rx: HANDLE STORED PROFILE 1: (len:0)
[00:00:06.639,000] <dbg> modem_hl7800: profile_handler: length: 61: E1 Q0 V1 X4 &C1 &D1 &R1 &S0 +IFC=2,2 &K3 +IPR=115200 +FCLASS0
[00:00:06.639,000] <dbg> modem_hl7800: profile_handler: echo: 1
[00:00:06.639,000] <dbg> modem_hl7800: wait_for_modem_data: cur:59, exp:66
[00:00:06.690,000] <dbg> modem_hl7800: wait_for_modem_data: cur:59, exp:66
[00:00:06.740,000] <dbg> modem_hl7800: wait_for_modem_data: cur:59, exp:66
[00:00:06.790,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:06.790,000] <dbg> modem_hl7800: iface_status_work_cb: Wait for driver init, process network state later
[00:00:06.790,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CMEE=1]
[00:00:06.794,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:06.794,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CGMR]
[00:00:06.797,000] <dbg> modem_hl7800: hl7800_rx: HANDLE AT+CGMR (len:14)
[00:00:06.797,000] <dbg> modem_hl7800: wait_for_modem_data: cur:15, exp:31
[00:00:06.847,000] <dbg> modem_hl7800: wait_for_modem_data: cur:22, exp:31
[00:00:06.897,000] <dbg> modem_hl7800: wait_for_modem_data: cur:22, exp:31
[00:00:06.947,000] <inf> modem_hl7800: Revision: HL7800.4.6.9.4
[00:00:06.947,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:06.947,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KSRAT?]
[00:00:06.950,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +KSRAT: (len:1)
[00:00:06.950,000] <inf> modem_hl7800: +KSRAT: 1
[00:00:06.951,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:06.951,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KBNDCFG?]
[00:00:06.970,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +KBNDCFG: (len:24)
[00:00:06.974,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +KBNDCFG: (len:24)
[00:00:06.974,000] <inf> modem_hl7800: Current band configuration: 0000 00000000 00000800
[00:00:06.975,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +KBNDCFG: (len:3)
[00:00:06.975,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:06.975,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CFUN=4,0]
[00:00:07.034,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.035,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KSLEEP=2]
[00:00:07.196,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.196,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CPSMS=0]
[00:00:07.290,000] <dbg> modem_hl7800: iface_status_work_cb: Wait for driver init, process network state later
[00:00:07.361,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.361,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CEDRXS=0]
[00:00:07.365,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.365,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CGMI]
[00:00:07.370,000] <dbg> modem_hl7800: hl7800_rx: HANDLE AT+CGMI (len:15)
[00:00:07.370,000] <dbg> modem_hl7800: wait_for_modem_data: cur:16, exp:18
[00:00:07.420,000] <inf> modem_hl7800: Manufacturer: Sierra Wireless
[00:00:07.420,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.420,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CGMM]
[00:00:07.423,000] <dbg> modem_hl7800: hl7800_rx: HANDLE AT+CGMM (len:6)
[00:00:07.423,000] <dbg> modem_hl7800: wait_for_modem_data: cur:7, exp:9
[00:00:07.473,000] <inf> modem_hl7800: Model: HL7800
[00:00:07.473,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.473,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CGSN]
[00:00:07.476,000] <dbg> modem_hl7800: hl7800_rx: HANDLE AT+CGSN (len:15)
[00:00:07.476,000] <dbg> modem_hl7800: wait_for_modem_data: cur:16, exp:18
[00:00:07.526,000] <inf> modem_hl7800: IMEI: 351144440365956
[00:00:07.526,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.526,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KGSN=3]
[00:00:07.530,000] <dbg> modem_hl7800: hl7800_rx: HANDLE AT+KGSN=3 (len:21)
[00:00:07.530,000] <inf> modem_hl7800: Serial #: AN3062305707B1
[00:00:07.531,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.531,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CCID?]
[00:00:07.593,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +CCID: (len:20)
[00:00:07.593,000] <dbg> modem_hl7800: on_cmd_atcmdinfo_iccid: +CCID: 89882390000304618096
[00:00:07.593,000] <inf> modem_hl7800: ICCID: 89882390000304618096
[00:00:07.593,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.593,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CIMI]
[00:00:07.596,000] <dbg> modem_hl7800: hl7800_rx: HANDLE AT+CIMI (len:15)
[00:00:07.596,000] <dbg> modem_hl7800: wait_for_modem_data: cur:16, exp:17
[00:00:07.646,000] <inf> modem_hl7800: IMSI: 901288820006464
[00:00:07.646,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.646,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CGDCONT?]
[00:00:07.652,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +CGDCONT: 1 (len:28)
[00:00:07.652,000] <dbg> modem_hl7800: wait_for_modem_data: cur:29, exp:162
[00:00:07.703,000] <dbg> modem_hl7800: wait_for_modem_data: cur:79, exp:162
[00:00:07.753,000] <dbg> modem_hl7800: wait_for_modem_data: cur:79, exp:162
[00:00:07.803,000] <dbg> modem_hl7800: on_cmd_atcmdinfo_pdp_context: length: 28: ,"IPV4V6",,,0,0,0,0,0,,0,,,,
[00:00:07.803,000] <dbg> modem_hl7800: on_cmd_atcmdinfo_pdp_context: PDP address family: IPV4V6
[00:00:07.803,000] <dbg> modem_hl7800: hl7800_rx: UNHANDLED RX
2b 43 47 44 43 4f 4e 54 3a 20 32 2c 22 49 50 56 |+CGDCONT : 2,"IPV
34 56 36 22 2c 2c 2c 30 2c 30 2c 30 2c 30 2c 30 |4V6",,,0 ,0,0,0,0
2c 2c 30 2c 2c 2c 2c |,,0,,,,
[00:00:07.803,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.803,000] <dbg> modem_hl7800: iface_status_work_cb: Wait for driver init, process network state later
[00:00:07.803,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+WPPP?]
[00:00:07.813,000] <dbg> modem_hl7800: hl7800_rx: UNHANDLED RX
2b 57 50 50 50 3a 20 30 2c 31 2c 22 22 2c 22 22 |+WPPP: 0 ,1,"",""
[00:00:07.817,000] <dbg> modem_hl7800: hl7800_rx: UNHANDLED RX
2b 57 50 50 50 3a 20 30 2c 32 2c 22 22 2c 22 22 |+WPPP: 0 ,2,"",""
[00:00:07.818,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.818,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KCNXCFG=1,"GPRS","",,,"IPV4V6"]
[00:00:07.837,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.837,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CEREG?]
[00:00:07.840,000] <dbg> modem_hl7800: hl7800_rx: HANDLE AT+CEREG? (len:11)
[00:00:07.840,000] <inf> modem_hl7800: Network State: 0 NOT_REGISTERED
[00:00:07.841,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.841,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CEREG=5]
[00:00:07.854,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.854,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KTCPCFG?]
[00:00:07.860,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.860,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KUDPCFG?]
[00:00:07.866,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:07.866,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CFUN=1,0]
[00:00:08.341,000] <dbg> modem_hl7800: iface_status_work_cb: Wait for driver init, process network state later
[00:00:08.413,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:08.413,000] <inf> modem_hl7800: Modem ready!
[00:00:08.413,000] <dbg> net_dns_resolve: dns_resolve_init_locked: (main): No DNS servers defined.
*** Booting Zephyr OS build v3.7.0-rc2-71-ga6e672f9b236 ***
[00:00:08.414,000] <inf> modem_hl7800: Startup State: READY
[00:00:08.414,000] <dbg> APP_MAIN_C: modemEventCallback: 362 main.c
[00:00:08.414,000] <dbg> APP_MAIN_C: modemEventCallback: 366 main.c
[00:00:08.414,000] <inf> modem_hl7800: Network State: 0 NOT_REGISTERED
[00:00:08.414,000] <dbg> APP_MAIN_C: modemEventCallback: HL7800_SEARCHING
[00:00:08.414,000] <inf> modem_hl7800: Sleep State: AWAKE
[00:00:08.414,000] <dbg> APP_MAIN_C: modemEventCallback: 380 main.c
[00:00:08.414,000] <dbg> APP_MAIN_C: modemEventCallback: 354 main.c 0
[00:00:08.414,000] <dbg> APP_MAIN_C: modemEventCallback: 358 main.c
[00:00:08.414,000] <dbg> APP_MAIN_C: modemEventCallback: 350 main.c
[00:00:08.414,000] <dbg> APP_MAIN_C: modemEventCallback: 384 main.c
[00:00:08.414,000] <dbg> APP_MAIN_C: modemEventCallback: 388 main.c
[00:00:08.414,000] <dbg> APP_MAIN_C: modemEventCallback: 392 main.c
[00:00:08.414,000] <dbg> APP_MAIN_C: modemEventCallback: 406 main.c
[00:00:08.414,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +CEREG: (len:1)
[00:00:08.414,000] <dbg> modem_hl7800: on_cmd_network_report: Network status: 2
[00:00:08.414,000] <inf> modem_hl7800: Network State: 2 SEARCHING
[00:00:08.414,000] <dbg> APP_MAIN_C: modemEventCallback: HL7800_SEARCHING
[00:00:08.414,000] <dbg> APP_MAIN_C: main: imei: 351144440365956
[00:00:08.414,000] <dbg> APP_MAIN_C: my_entry_point: 619
[00:00:08.414,000] <dbg> APP_MAIN_C: my_entry_point: 622
[00:00:08.414,000] <dbg> net_sock_svc: socket_service_thread: (net_socket_): Service WEST_TOPDIR/zephyr/subsys/net/lib/dns/resolve.c:38 has 1 pollable sockets
[00:00:08.414,000] <dbg> net_sock_svc: socket_service_thread: (net_socket_): Monitoring 1 socket entries
[00:00:08.914,000] <dbg> modem_hl7800: iface_status_work_cb: Updating network state...
[00:00:08.914,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+COPS?]
[00:00:08.917,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +COPS: (len:1)
[00:00:08.917,000] <inf> modem_hl7800: Operator: 0
[00:00:08.918,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:08.918,000] <dbg> modem_hl7800: iface_status_work_cb: HL7800 iface DOWN
[00:00:08.918,000] <dbg> modem_hl7800: iface_status_work_cb: Network state updated
[00:00:09.705,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +CEREG: (len:1)
[00:00:09.705,000] <dbg> modem_hl7800: on_cmd_network_report: Network status: 0
[00:00:09.705,000] <inf> modem_hl7800: Network State: 0 NOT_REGISTERED
[00:00:09.705,000] <dbg> APP_MAIN_C: modemEventCallback: HL7800_SEARCHING
[00:00:09.714,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +CEREG: (len:1)
[00:00:09.714,000] <dbg> modem_hl7800: on_cmd_network_report: Network status: 2
[00:00:09.714,000] <inf> modem_hl7800: Network State: 2 SEARCHING
[00:00:09.714,000] <dbg> APP_MAIN_C: modemEventCallback: HL7800_SEARCHING
[00:00:10.214,000] <dbg> modem_hl7800: iface_status_work_cb: Updating network state...
[00:00:10.214,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+COPS?]
[00:00:11.473,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +COPS: (len:19)
[00:00:11.473,000] <inf> modem_hl7800: Operator: 0,0,"vodafone IE",9
[00:00:11.473,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:11.473,000] <dbg> modem_hl7800: iface_status_work_cb: HL7800 iface DOWN
[00:00:11.473,000] <dbg> modem_hl7800: iface_status_work_cb: Network state updated
[00:00:11.476,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +CEREG: (len:18)
[00:00:11.476,000] <dbg> modem_hl7800: on_cmd_network_report: Network status: 2,,,,,,,"00101000"
[00:00:11.476,000] <inf> modem_hl7800: Network State: 2 SEARCHING
[00:00:11.476,000] <dbg> APP_MAIN_C: modemEventCallback: HL7800_SEARCHING
[00:00:11.480,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +CEREG: (len:35)
[00:00:11.480,000] <dbg> modem_hl7800: on_cmd_network_report: Network status: 5,"2332","02E9F81F",9,,,,"00101000"
[00:00:11.480,000] <inf> modem_hl7800: Network State: 5 ROAMING
[00:00:11.480,000] <dbg> APP_MAIN_C: modemEventCallback: HL7800_ROAMING
[00:00:11.980,000] <dbg> modem_hl7800: iface_status_work_cb: Updating network state...
[00:00:11.980,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+COPS?]
[00:00:11.984,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +COPS: (len:16)
[00:00:11.984,000] <inf> modem_hl7800: Operator: 0,0,"Vodafone",9
[00:00:11.985,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:11.985,000] <dbg> modem_hl7800: iface_status_work_cb: HL7800 iface UP
[00:00:11.985,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CGCONTRDP=1]
[00:00:11.999,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +CGCONTRDP: (len:121)
[00:00:12.000,000] <dbg> modem_hl7800: on_cmd_atcmdinfo_ipaddr: IP info: 1,5,"netf***********",10.149.32.240.255.255.255.224,10.149.32.225,10.105.16.254,10.105.144.254,,,,,,,,,
[00:00:12.000,000] <dbg> modem_hl7800: on_cmd_atcmdinfo_ipaddr: IP string len: 30
[00:00:12.000,000] <dbg> modem_hl7800: on_cmd_atcmdinfo_ipaddr: IP addr: 10.149.32.240
[00:00:12.000,000] <dbg> modem_hl7800: on_cmd_atcmdinfo_ipaddr: IPv4 DNS addr: 10.105.16.254
[00:00:12.000,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:12.000,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KBND?]
[00:00:12.006,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +KBND: (len:24)
[00:00:12.006,000] <dbg> APP_MAIN_C: modemEventCallback: 392 main.c
[00:00:12.006,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:12.006,000] <dbg> modem_hl7800: iface_status_work_cb: Network state updated
[00:00:12.006,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KCELLMEAS=0]
[00:00:12.014,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +KCELLMEAS: (len:23)
[00:00:12.014,000] <inf> modem_hl7800: RSSI (RSRP): -103 SINR: 13
[00:00:12.014,000] <dbg> APP_MAIN_C: modemEventCallback: 354 main.c -103
[00:00:12.014,000] <dbg> APP_MAIN_C: modemEventCallback: 358 main.c
[00:00:12.014,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:12.014,000] <dbg> modem_hl7800: dns_work_cb: Refresh DNS resolver
[00:00:12.014,000] <dbg> modem_hl7800: dns_work_cb: Initializing DNS resolver
[00:00:12.015,000] <dbg> net_dns_resolve: dns_resolve_init_locked: (hl7800_work): [0] 10.105.16.254
[00:00:12.015,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KCNXCFG=1,"GPRS","",,,"IPV4V6"]
[00:00:12.034,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:12.034,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KTCPCFG?]
[00:00:12.040,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:12.040,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KUDPCFG?]
[00:00:12.046,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:13.046,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KUDPCFG=1,0,,,,,0,0]
[00:00:13.083,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +KUDPCFG: (len:1)
[00:00:13.083,000] <dbg> modem_hl7800: on_cmd_sockcreate: +KUDPCFG: 1
[00:00:13.083,000] <dbg> modem_hl7800: on_cmd_sockcreate: look up new socket by creation id
[00:00:13.083,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:13.101,000] <dbg> modem_hl7800: hl7800_rx: UNHANDLED RX
2b 4b 43 4e 58 5f 49 4e 44 3a 20 31 2c 31 2c 30 |+KCNX_IN D: 1,1,0
[00:00:14.117,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +KUDP_IND: (len:3)
[00:00:14.117,000] <dbg> modem_hl7800: on_cmd_sock_ind: +KUDP_IND ID: 1
[00:00:14.117,000] <dbg> net_sock: zsock_socket_internal: (hl7800_work): socket: ctx=0x200021d0, fd=1
[00:00:14.117,000] <dbg> modem_hl7800: dns_work_cb: DNS ready
[00:00:14.117,000] <dbg> APP_MAIN_C: iface_ready_evt_handler: LTE is ready!
[00:00:14.117,000] <dbg> APP_MAIN_C: lteEvent: 506
[00:00:14.117,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+CCLK?]
[00:00:14.118,000] <dbg> APP_MAIN_C: my_entry_point: 625
[00:00:14.118,000] <dbg> net_sock_svc: socket_service_thread: (net_socket_): Received restart event.
[00:00:14.122,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +CCLK: (len:22)
[00:00:14.122,000] <dbg> modem_hl7800: wait_for_modem_data: cur:23, exp:25
[00:00:14.172,000] <inf> modem_hl7800: RTC string: '"24/07/05,20:59:28+08"'
[00:00:14.172,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:14.172,000] <dbg> APP_MAIN_C: getLocalTimeFromModemWorkHandler: LOCAL TIME: 5 20 59
[00:00:24.118,000] <dbg> APP_MAIN_C: my_entry_point: Performing DNS lookup of broker.hivemq.com
sample dns request
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1230 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1238 resolve.c
[00:00:24.118,000] <dbg> net_dns_cache: dns_cache_find: (my_tid): Find "broker.hivemq.com"
[00:00:24.118,000] <dbg> net_dns_cache: dns_cache_find: (my_tid): Could not find "broker.hivemq.com"
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1302 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1304 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1309 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1315 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1323 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1325 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1331 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1337 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1343 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1360 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: (my_tid): DNS id will be 56756
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1376 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1384 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_resolve_name: 1395 resolve.c
[00:00:24.118,000] <dbg> net_dns_resolve: dns_write: (my_tid): [0] submitting work to server idx 0 for id 56756 hash 9827
[00:00:24.118,000] <dbg> modem_hl7800: send_data: 1427 hl7800.c
[00:00:24.118,000] <dbg> modem_hl7800: send_data: 1439 hl7800.c
[00:00:24.118,000] <dbg> modem_hl7800: send_data: 1445 hl7800.c
[00:00:24.119,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KUDPSND=1,"10.105.16.254",53,35]
[00:00:24.121,000] <dbg> modem_hl7800: send_data: 1451 hl7800.c AT+KUDPSND=1,"10.105.16.254",53,35
[00:00:24.133,000] <dbg> modem_hl7800: hl7800_rx: HANDLE CONNECT (len:0)
[00:00:24.133,000] <dbg> modem_hl7800: on_cmd_connect: 4233 hl7800.c
[00:00:24.133,000] <dbg> modem_hl7800: on_cmd_connect: 4239 hl7800.c
[00:00:24.133,000] <dbg> modem_hl7800: on_cmd_connect: 4244 hl7800.c
[00:00:24.133,000] <dbg> modem_hl7800: send_data: 1464 hl7800.c
[00:00:24.136,000] <dbg> modem_hl7800: send_data: Sent 35 bytes
[00:00:24.136,000] <dbg> modem_hl7800: send_data: 1476 hl7800.c
[00:00:24.142,000] <dbg> modem_hl7800: hl7800_rx: HANDLE OK (len:0)
[00:00:24.142,000] <dbg> net_dns_resolve: dns_resolve_name: 1405 resolve.c
[00:00:24.142,000] <dbg> net_dns_resolve: dns_resolve_name: 1412 resolve.c
[00:00:24.142,000] <dbg> net_dns_resolve: dns_resolve_name: 1421 resolve.c
[00:00:24.142,000] <dbg> net_dns_resolve: dns_resolve_name: 1434 WEST_TOPDIR/zephyr/subsys/net/lib/dns/resolve.c
[00:00:24.142,000] <dbg> net_dns_resolve: dns_resolve_name: 1442 resolve.c
[00:00:24.142,000] <dbg> net_dns_resolve: dns_resolve_name: 1445 resolve.c
[00:00:24.142,000] <dbg> APP_MAIN_C: sample_dns_request: sample dns request
[00:00:25.322,000] <dbg> modem_hl7800: hl7800_rx: HANDLE +KUDP_DATA: (len:4)
[00:00:25.322,000] <dbg> modem_hl7800: start_socket_rx: Start socket RX ID:1 size:67
[00:00:25.322,000] <dbg> modem_hl7800: send_at_cmd: OUT: [AT+KUDPRCV=1,67]
[00:00:25.333,000] <dbg> modem_hl7800: hl7800_rx: HANDLE CONNECT (len:0)
[00:00:25.333,000] <dbg> modem_hl7800: on_cmd_connect: 4233 hl7800.c
[00:00:25.333,000] <dbg> modem_hl7800: on_cmd_connect: 4236 hl7800.c
[00:00:25.333,000] <dbg> modem_hl7800: sock_read: Socket 1 RX 67 bytes
[00:00:25.333,000] <dbg> modem_hl7800: wait_for_modem_data: cur:0, exp:1
[00:00:25.383,000] <dbg> modem_hl7800: sock_read: Processing RX, buf len: 109
[00:00:25.383,000] <dbg> modem_hl7800: sock_read: Got all data, get EOF and OK (buf len:42)
[00:00:25.383,000] <dbg> modem_hl7800: sock_read: Sock 1 RX done
[00:00:25.384,000] <dbg> modem_hl7800: on_cmd_connect: 4244 hl7800.c
[00:00:25.384,000] <dbg> modem_hl7800: hl7800_rx: UNHANDLED RX
2b 4b 55 44 50 5f 52 43 56 3a 20 22 31 30 2e 31 |+KUDP_RC V: "10.1
30 35 2e 31 36 2e 32 35 34 22 2c 35 33 |05.16.25 4",53
[00:00:25.384,000] <dbg> modem_hl7800: sockreadrecv_cb_work: Sock 1 RX CB (size: 95)
[00:00:25.384,000] <dbg> net_sock: zsock_received_cb: (hl7800_work): ctx=0x200021d0, pkt=0x20037640, st=0, user_data=(nil)
[00:00:25.515,000] <dbg> net_sock: zsock_recv_dgram: 1449 socket.c
[00:00:25.515,000] <dbg> net_sock: zsock_recv_dgram: 1454 socket.c
[00:00:25.515,000] <dbg> net_sock: zsock_recv_dgram: 1464 socket.c
[00:00:25.515,000] <dbg> net_sock: zsock_recv_dgram: 1478 socket.c
[00:00:25.515,000] <dbg> net_sock: zsock_recv_dgram: 1480 socket.c
[00:00:25.515,000] <dbg> net_sock: zsock_recv_dgram: 1485 socket.c
[00:00:25.515,000] <dbg> net_sock: zsock_recv_dgram: 1487 socket.c
[00:00:25.515,000] <dbg> net_sock: zsock_recv_dgram: 1489 socket.c 0 0
[00:00:25.515,000] <dbg> net_sock: zsock_recv_dgram: 1493 socket.c
[00:00:25.515,000] <dbg> net_sock: sock_get_offload_pkt_src_addr: 1219 socket.c
[00:00:25.515,000] <dbg> net_sock: net_pkt_remote_addr_is_unspecified: 1200 socket.c 1
[00:00:25.515,000] <dbg> net_sock: net_pkt_remote_addr_is_unspecified: 1205 socket.c
[00:00:25.515,000] <dbg> net_sock: sock_get_offload_pkt_src_addr: 1244 socket.c
[00:00:25.515,000] <dbg> net_sock: zsock_recv_dgram: (net_socket_): sock_get_offload_pkt_src_addr -134
[00:00:25.516,000] <dbg> net_sock: zsock_recv_dgram: 1631 socket.c
[00:00:25.516,000] <dbg> net_sock: zsock_recv_dgram: 1634 socket.c
[00:00:25.516,000] <err> net_dns_dispatcher: recv failed on IPv4 socket (134)
[00:00:25.516,000] <err> net_dns_dispatcher: DNS recv error (-134)
Thanks for the logs, could you share the sample app so I can try to reproduce?
@rerickson1
Thanks for the logs, could you share the sample app so I can try to reproduce?
this is exactly what i was doing for you :)
@zafersn I cannot get the sample to build, it gets in an endless cmake loop. Also, the sample isnt configured to use the HL7800 driver... build.log
@rerickson1 in my case, I used nucleo_u575zi_q.overlay
and nucleo_u575zi_q.conf.
So, it must be configured in my case, which I got all the logs shared them with you, and lte nbiot connection. please see the logs here :https://github.com/zephyrproject-rtos/zephyr/issues/75398#issuecomment-2211302024 . ILL have a look at your logs.
I see in your logs, I just sent the prj.conf file to you with a different cellular apn config, just in case, wanted to hide some information, I am sharing everything publicly here. Please set this config something else.
CONFIG_MODEM_CELLULAR_APN="netfe*****"
and rest of the cmake warning is because of you probably didnt use board.conf file. Sorry for this comlexity, I just built the test app on already exist zephyr sample for modem test
@rerickson1 please try this sample app,
I have reproduced the dns issue with the http get sample. Patch: hl7800_http_get.patch Log: http_get_3.7.0-rc2.log
It seems the socket packet source address may not be set correctly due to a change in the stack. @jukkar any tips?
Describe the bug Previously, mqtt was working with hl7800 with zehyr3.6.0. After migration, i started getting this error:
Any thought?
more info:
Please also mention any information which could help others to understand the problem you're facing:
To Reproduce Steps to reproduce the behavior: because I am working on custom board, I cant exactly provide steps to reproduce it.
Expected behavior at 3.6.0 version, I had succesfull getaddrinfo return and mqtt connection
Environment (please complete the following information):