SIM7600 Strange packet parsing behaviour (IDFGH-14949) #789

kgrimsby · 2025-03-26T13:59:29Z

Answers checklist.

I have read the documentation for esp-protocols components and the issue is not addressed there.
I have updated my esp-protocols branch (master or release) to the latest version and checked that the issue is present there.
I have searched the issue tracker for a similar issue and not found a similar issue.

General issue report

I'm trying to get SIM7600 to work on Lilygo T-SIM7600. I must be doing something very very strange - but I don't know what. First I hade problems getting an IP, but by disabling CONFIG_LWIP_PPP_VJ_HEADER_COMPRESSION it worked fine.

Trying to make a simple http call though, results in error.

D (901) intr_alloc: Connected src 24 to int 2 (cpu 0)
D (906) app_start: Starting scheduler on CPU0
I (910D (910) intr_alloc: Connected src 25 to int 1 (cpu 1)
D (910) app_start: Starting scheduler on CPU1
) main_task: Started on CPU0
D (920) heap_init: New heap initialised at 0x3ffe0440
D (920) heap_init: New heap initialised at 0x3ffe4350
D (930) intr_alloc: Connected src 16 to int 3 (cpu 0)
I (930) main_task: Calling app_main()
D (940) lwip: dns_init: initializing

D (940) esp_netif_lwip: LwIP stack has been initialized
D (940) esp_netif_lwip: esp-netif has been successfully initialized
D (950) event: running task for loop 0x3ffb8768
D (950) event: created task for loop 0x3ffb8768
D (960) event: created event loop 0x3ffb8768
I (960) app: toggling modem power...
I (970) app: status=1
E (970) app: Modem is already powered on
I (3570) app: status=1
I (4570) app: status=1
I (5570) app: status=1
I (6570) app: status=1
I (7570) app: status=1
I (8570) app: status=1
I (9570) app: status=1
I (10570) app: status=1
I (11570) app: status=1
I (12570) app: status=1
I (13570) app: status=1
I (14570) app: status=1
I (15570) app: status=1
I (16570) app: status=1
I (17570) app: status=1
I (18570) app: status=1
I (19570) app: status=1
I (20570) app: status=1
I (21570) app: status=1
I (22570) app: status=1
I (23570) app: status=1
I (24570) app: status=0
I (25870) app: waiting for modem...
I (25970) app: status=1
D (25970) esp_netif_lwip: check: remote, if=0x3ffb6430 fn=0x400e4e40
--- 0x400e4e40: esp_netif_new_api at /Users/kristiangrimsby/esp/v5.4/esp-idf/components/esp_netif/lwip/esp_netif_lwip.c:712

D (25970) esp_netif_objects: esp_netif_add_to_list_unsafe netif added successfully (total netifs: 1)
D (25970) lwip: ppp phase changed[1]: phase=0

D (25980) esp-netif_lwip-ppp: esp_netif_new_ppp: PPP connection created: 0x3ffb9968
D (25980) esp-netif_lwip-ppp: Phase Dead
D (25990) esp_netif_lwip: call api in lwip: ret=0x0, give sem
I (25990) uart: ESP_INTR_FLAG_IRAM flag not set while CONFIG_UART_ISR_IN_IRAM is enabled, flag updated
I (26000) uart: queue free spaces: 50
D (26000) intr_alloc: Connected src 35 to int 5 (cpu 0)
D (26010) uart-tx: 0x3f419c28   7e ff 03 c0 21 09 01 00  08 99 d1 35 c1 8e 2c 7e  |~...!......5..,~|
D (26220) uart-tx: 0x3f419c18   0d 0a 41 54 0d 0a 00                              |..AT...|
D (26720) uart-tx: 0x3f419c0c   f9 03 3f 01 1c f9                                 |..?...|
E (26920) app: esp_modem_sync failed with -1
D (27420) uart-tx: 0x3f419c28   7e ff 03 c0 21 09 01 00  08 99 d1 35 c1 8e 2c 7e  |~...!......5..,~|
D (27620) uart-tx: 0x3f419c18   0d 0a 41 54 0d 0a 00                              |..AT...|
D (28120) uart-tx: 0x3f419c0c   f9 03 3f 01 1c f9                                 |..?...|
E (28320) app: esp_modem_sync failed with -1
D (28820) uart-tx: 0x3f419c28   7e ff 03 c0 21 09 01 00  08 99 d1 35 c1 8e 2c 7e  |~...!......5..,~|
D (29020) uart-tx: 0x3f419c18   0d 0a 41 54 0d 0a 00                              |..AT...|
D (29520) uart-tx: 0x3f419c0c   f9 03 3f 01 1c f9                                 |..?...|
E (29720) app: esp_modem_sync failed with -1
D (30220) uart-tx: 0x3f419c28   7e ff 03 c0 21 09 01 00  08 99 d1 35 c1 8e 2c 7e  |~...!......5..,~|
D (30420) uart-tx: 0x3f419c18   0d 0a 41 54 0d 0a 00                              |..AT...|
D (30920) uart-tx: 0x3f419c0c   f9 03 3f 01 1c f9                                 |..?...|
E (31120) app: esp_modem_sync failed with -1
D (31620) uart-tx: 0x3f419c28   7e ff 03 c0 21 09 01 00  08 99 d1 35 c1 8e 2c 7e  |~...!......5..,~|
D (31820) uart-tx: 0x3f419c18   0d 0a 41 54 0d 0a 00                              |..AT...|
D (32320) uart-tx: 0x3f419c0c   f9 03 3f 01 1c f9                                 |..?...|
E (32520) app: esp_modem_sync failed with -1
D (33020) uart-tx: 0x3f419c28   7e ff 03 c0 21 09 01 00  08 99 d1 35 c1 8e 2c 7e  |~...!......5..,~|
D (33220) uart-tx: 0x3f419c18   0d 0a 41 54 0d 0a 00                              |..AT...|
D (33720) uart-tx: 0x3f419c0c   f9 03 3f 01 1c f9                                 |..?...|
E (33920) app: esp_modem_sync failed with -1
D (34420) uart-tx: 0x3f419c28   7e ff 03 c0 21 09 01 00  08 99 d1 35 c1 8e 2c 7e  |~...!......5..,~|
D (34620) uart-tx: 0x3f419c18   0d 0a 41 54 0d 0a 00                              |..AT...|
D (35120) uart-tx: 0x3f419c0c   f9 03 3f 01 1c f9                                 |..?...|
E (35320) app: esp_modem_sync failed with -1
D (35820) uart-tx: 0x3f419c28   7e ff 03 c0 21 09 01 00  08 99 d1 35 c1 8e 2c 7e  |~...!......5..,~|
D (36020) uart-tx: 0x3f419c18   0d 0a 41 54 0d 0a 00                              |..AT...|
D (36520) uart-tx: 0x3f419c0c   f9 03 3f 01 1c f9                                 |..?...|
E (36720) app: esp_modem_sync failed with -1
D (37220) uart-tx: 0x3f419c28   7e ff 03 c0 21 09 01 00  08 99 d1 35 c1 8e 2c 7e  |~...!......5..,~|
D (37420) uart-tx: 0x3f419c18   0d 0a 41 54 0d 0a 00                              |..AT...|
D (37920) uart-tx: 0x3f419c0c   f9 03 3f 01 1c f9                                 |..?...|
E (38120) app: esp_modem_sync failed with -1
D (38620) uart-tx: 0x3f419c28   7e ff 03 c0 21 09 01 00  08 99 d1 35 c1 8e 2c 7e  |~...!......5..,~|
D (38820) uart-tx: 0x3f419c18   0d 0a 41 54 0d 0a 00                              |..AT...|
D (39320) uart-tx: 0x3f419c0c   f9 03 3f 01 1c f9                                 |..?...|
E (39520) app: esp_modem_sync failed with -1
D (40020) uart-tx: 0x3f419c28   7e ff 03 c0 21 09 01 00  08 99 d1 35 c1 8e 2c 7e  |~...!......5..,~|
D (40220) uart-tx: 0x3f419c18   0d 0a 41 54 0d 0a 00                              |..AT...|
D (40720) uart-tx: 0x3f419c0c   f9 03 3f 01 1c f9                                 |..?...|
E (40920) app: esp_modem_sync failed with -1
D (41420) uart-tx: 0x3f419c28   7e ff 03 c0 21 09 01 00  08 99 d1 35 c1 8e 2c 7e  |~...!......5..,~|
D (41620) uart-tx: 0x3f419c18   0d 0a 41 54 0d 0a 00                              |..AT...|
D (42120) uart-tx: 0x3f419c0c   f9 03 3f 01 1c f9                                 |..?...|
E (42320) app: esp_modem_sync failed with -1
D (42820) uart-tx: 0x3f419c28   7e ff 03 c0 21 09 01 00  08 99 d1 35 c1 8e 2c 7e  |~...!......5..,~|
D (42990) uart-rx: 0x3ffc962c   0d 0a 52 44 59 0d 0a 0d  0a 2b 43 50 49 4e 3a 20  |..RDY....+CPIN: |
D (42990) uart-rx: 0x3ffc963c   52 45 41 44 59 0d 0a 0d  0a 53 4d 53 20 44 4f 4e  |READY....SMS DON|
D (43000) uart-rx: 0x3ffc964c   45 0d 0a 0d 0a 50 42 20  44 4f 4e 45 0d 0a        |E....PB DONE..|
D (43010) esp-modem: guess mode data:: 0x3ffb6384   0d 0a 52 44 59 0d 0a 0d  0a 2b 43 50 49 4e 3a 20  |..RDY....+CPIN: |
D (43020) uart-tx: 0x3f419c18   0d 0a 41 54 0d 0a 00                              |..AT...|
D (43030) uart-rx: 0x3ffc962c   41 54 0d 0d 0a 4f 4b 0d  0a                       |AT...OK..|
D (43030) esp-modem: guess mode data:: 0x3ffb6384   41 54 0d 0d 0a 4f 4b 0d  0a                       |AT...OK..|
D (43040) DCE mode: Detected mode: 1
I (43050) app: Mode detection completed: current mode is: 0
E (43050) app: Starting connectoin
E (43050) app: Setting mode to DATA
D (43060) command_lib: generic_command command AT+CMUX=0

D (43060) uart-tx: 0x3ffb634c   41 54 2b 43 4d 55 58 3d  30 0d                    |AT+CMUX=0.|
D (43080) uart-rx: 0x3ffc962c   41 54 2b 43 4d 55 58 3d  30 0d                    |AT+CMUX=0.|
D (43080) uart-rx: 0x3ffc9636   0d 0a 4f 4b 0d 0a                                 |..OK..|
D (43090) command_lib: Response: AT+CMUX=0
OK


D (43190) uart-tx: 0x3ffb62f0   f9 03 3f 01 1c f9                                 |..?...|
D (43200) uart-rx: 0x3ffc962c   f9 f9 f9 f9 f9 03 73 01  d7 f9                    |......s...|
D (43200) CMUX: Received header: payload_offset: 3
D (43200) CMUX: Payload frame: dlci:00 type:73 payload:0 available:2
D (43210) uart-tx: 0x3ffb62f0   f9 07 3f 01 de f9                                 |..?...|
D (43220) uart-rx: 0x3ffc962c   f9 07 73 01 15 f9                                 |..s...|
D (43220) CMUX: Received header: payload_offset: 3
D (43230) CMUX: Payload frame: dlci:01 type:73 payload:0 available:2
D (43230) uart-tx: 0x3ffb62f0   f9 0b 3f 01 59 f9                                 |..?.Y.|
D (43250) uart-rx: 0x3ffc962c   f9 0b 73 01 92 f9                                 |..s...|
D (43250) CMUX: Received header: payload_offset: 3
D (43250) CMUX: Payload frame: dlci:02 type:73 payload:0 available:2
D (43260) command_lib: generic_command command ATE0

D (43260) uart-tx: 0x3ffb60e4   f9 05 ef 0b                                       |....|
D (43270) uart-tx: 0x3ffb631c   41 54 45 30 0d                                    |ATE0.|
D (43280) uart-tx: 0x3ffb60e8   bb f9                                             |..|
D (43300) uart-rx: 0x3ffc962c   f9 05 ff 0b 41 54 45 30  0d ae f9 f9 05 ff 0d 0d  |....ATE0........|
D (43300) uart-rx: 0x3ffc963c   0a 4f 4b 0d 0a 4a f9                              |.OK..J.|
D (43310) CMUX: Received header: payload_offset: 3
D (43310) CMUX: Payload frame: dlci:01 type:ff payload:5 available:19
D (43320) CMUX: Received header: payload_offset: 3
D (43320) CMUX: Payload frame: dlci:01 type:ff payload:6 available:8
D (43330) command_lib: Response: 
OK


D (43330) uart-rx: 0x3ffc962c   f9 f9 f9 f9                                       |....|
D (43340) command_lib: generic_command command AT+CGDCONT=1,"IP","onomondo"

D (43350) uart-tx: 0x3ffb6054   f9 05 ef 3b                                       |...;|
D (43350) uart-tx: 0x3ffca94c   41 54 2b 43 47 44 43 4f  4e 54 3d 31 2c 22 49 50  |AT+CGDCONT=1,"IP|
D (43360) uart-tx: 0x3ffca95c   22 2c 22 6f 6e 6f 6d 6f  6e 64 6f 22 0d           |","onomondo".|
D (43370) uart-tx: 0x3ffb6058   9f f9                                             |..|
D (43410) uart-rx: 0x3ffc962c   f9 05 ff 0d 0d 0a 4f 4b  0d 0a 4a f9              |......OK..J.|
D (43410) CMUX: Received header: payload_offset: 3
D (43410) CMUX: Payload frame: dlci:01 type:ff payload:6 available:8
D (43420) command_lib: Response: 
OK


D (43420) uart-rx: 0x3ffc962c   f9 f9 f9 f9                                       |....|
D (43430) command_lib: generic_command command ATD*99#

D (43430) uart-tx: 0x3ffb6114   f9 05 ef 11                                       |....|
D (43440) uart-tx: 0x3ffb6324   41 54 44 2a 39 39 23 0d                           |ATD*99#.|
D (43450) uart-tx: 0x3ffb6118   4a f9                                             |J.|
D (43470) uart-rx: 0x3ffc962c   f9 01 ef 0b e3 07 07 8d  01 79 f9                 |.........y.|
D (43470) CMUX: Received header: payload_offset: 3
D (43470) CMUX: Payload frame: dlci:00 type:ef payload:5 available:7
D (43480) uart-rx: 0x3ffc962c   f9 05 ff 25 0d 0a 43 4f  4e 4e 45 43 54 20 31 31  |...%..CONNECT 11|
D (43490) uart-rx: 0x3ffc963c   35 32 30 30 0d 0a 7c f9  f9 f9 f9 f9              |5200..|.....|
D (43490) CMUX: Received header: payload_offset: 3
D (43500) CMUX: Payload frame: dlci:01 type:ff payload:18 available:24
D (43500) command_lib: Response: 
CONNECT 115200


D (43510) uart-rx: 0x3ffc962c   f9 f9 f9 f9                                       |....|
D (43520) esp_netif_handlers: esp_netif action has started with netif0x3ffb9720 from event_id=0
D (43530) esp_netif_lwip: check: remote, if=0x3ffb9720 fn=0x400e5020
--- 0x400e5020: esp_netif_start_api at /Users/kristiangrimsby/esp/v5.4/esp-idf/components/esp_netif/lwip/esp_netif_lwip.c:1093

D (43530) esp_netif_lwip: esp_netif_start_api 0x3ffb9720
D (43540) esp-netif_lwip-ppp: esp_netif_start_ppp: Starting PPP connection: 0x3ffb9968
D (43540) lwip: ppp_connect[1]: holdoff=0

D (43550) lwip: ppp phase changed[1]: phase=3

D (43550) esp-netif_lwip-ppp: Phase Start
D (43560) lwip: pppos_connect: unit 1: connecting

D (43560) lwip: ppp_start[1]

D (43560) lwip: ppp phase changed[1]: phase=6

D (43570) esp-netif_lwip-ppp: Phase Establish
D (43570) lwip: ppp_send_config[1]

D (43580) lwip: pppos_send_config[1]: out_accm=FF FF FF FF

D (43580) lwip: ppp_recv_config[1]

D (43580) lwip: pppos_recv_config[1]: in_accm=FF FF FF FF

D (43590) lwip: ppp: auth protocols:
D (43590) lwip:  PAP=0
D (43590) lwip: 

D (43600) lwip: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x586512f6> <pcomp> <accomp>]

D (43610) DTE transmit: 0x3ffca988   7e ff 7d 23 c0 21 7d 21  7d 21 7d 20 7d 34 7d 22  |~.}#.!}!}!} }4}"|
D (43610) DTE transmit: 0x3ffca998   7d 26 7d 20 7d 20 7d 20  7d 20 7d 25 7d 26 58 65  |}&} } } } }%}&Xe|
D (43620) DTE transmit: 0x3ffca9a8   7d 32 f6 7d 27 7d 22 7d  28 7d 22 e4 b8 7e        |}2.}'}"}(}"..~|
D (43630) uart-tx: 0x3ffb81d4   f9 05 ef 5d                                       |...]|
D (43640) uart-tx: 0x3ffca988   7e ff 7d 23 c0 21 7d 21  7d 21 7d 20 7d 34 7d 22  |~.}#.!}!}!} }4}"|
D (43650) uart-tx: 0x3ffca998   7d 26 7d 20 7d 20 7d 20  7d 20 7d 25 7d 26 58 65  |}&} } } } }%}&Xe|
D (43660) uart-tx: 0x3ffca9a8   7d 32 f6 7d 27 7d 22 7d  28 7d 22 e4 b8 7e        |}2.}'}"}(}"..~|
D (43670) uart-tx: 0x3ffb81d8   33 f9                                             |3.|
D (43670) lwip: pppos_write[1]: len=24

D (43680) lwip: ppp_start[1]: finished

D (43680) esp_netif_lwip: call api in lwip: ret=0x0, give sem
D (43680) uart-rx: 0x3ffc962c   f9 05 ff                                          |...|
E (43690) app: Waiting for IP
D (43690) uart-rx: 0x3ffc962c   6d 7e ff 7d 23 c0 21 7d  21 7d 20 7d 20 7d 39 7d  |m~.}#.!}!} } }9}|
D (43710) uart-rx: 0x3ffc963c   22 7d 26 7d 20 7d 20 7d  20 7d 20 7d 23 7d 25 c2  |"}&} } } } }#}%.|
D (43720) uart-rx: 0x3ffc964c   23 7d 25 7d 25 7d 26 7d  32 d6 e3 d1 7d 27 7d 22  |#}%}%}&}2...}'}"|
D (43720) uart-rx: 0x3ffc965c   7d 28 7d 22 df 88 7e 02  f9 f9 05 ff 5f 7e ff 7d  |}(}"..~....._~.}|
D (43730) uart-rx: 0x3ffc966c   23 c0 21 7d 22 7d 21 7d  20 7d 34 7d 22 7d 26 7d  |#.!}"}!} }4}"}&}|
D (43740) uart-rx: 0x3ffc967c   20 7d 20 7d 20 7d 20 7d  25 7d 26 58 65 7d 32 f6  | } } } }%}&Xe}2.|
D (43750) uart-rx: 0x3ffc968c   7d 27 7d 22 7d 28 7d 22  7d 2f d1 7e c5 f9        |}'}"}(}"}/.~..|
D (43760) CMUX: Received header: payload_offset: 1
D (43760) CMUX: Payload frame: dlci:01 type:ff payload:54 available:109
D (43770) DTE recv: 0x3ffc962d   7e ff 7d 23 c0 21 7d 21  7d 20 7d 20 7d 39 7d 22  |~.}#.!}!} } }9}"|
D (43780) DTE recv: 0x3ffc963d   7d 26 7d 20 7d 20 7d 20  7d 20 7d 23 7d 25 c2 23  |}&} } } } }#}%.#|
D (43790) DTE recv: 0x3ffc964d   7d 25 7d 25 7d 26 7d 32  d6 e3 d1 7d 27 7d 22 7d  |}%}%}&}2...}'}"}|
D (43800) DTE recv: 0x3ffc965d   28 7d 22 df 88 7e                                 |(}"..~|
D (43810) lwip: pppos_input[1]: got 54 bytes

D (43810) lwip: rcvd [LCP ConfReq id=0x0 <asyncmap 0x0> <auth 0xc223 05> <magic 0x12d6e3d1> <pcomp> <accomp>]

D (43810) CMUX: Received header: payload_offset: 3
D (43820) lwip: No auth is possible

D (43830) CMUX: Payload frame: dlci:01 type:ff payload:47 available:49
D (43830) lwip: sent [LCP ConfRej id=0x0 <auth 0xc223 05>]

D (43830) DTE recv: 0x3ffc9669   7e ff 7d 23 c0 21 7d 22  7d 21 7d 20 7d 34 7d 22  |~.}#.!}"}!} }4}"|
D (43840) DTE transmit: 0x3ffcaff8   7e ff 7d 23 c0 21 7d 24  7d 20 7d 20 7d 29 7d 23  |~.}#.!}$} } })}#|
D (43860) DTE recv: 0x3ffc9679   7d 26 7d 20 7d 20 7d 20  7d 20 7d 25 7d 26 58 65  |}&} } } } }%}&Xe|
D (43860) DTE transmit: 0x3ffcb008   7d 25 c2 23 7d 25 f8 60  7e                       |}%.#}%.`~|
D (43870) DTE recv: 0x3ffc9689   7d 32 f6 7d 27 7d 22 7d  28 7d 22 7d 2f d1 7e     |}2.}'}"}(}"}/.~|
D (43870) uart-tx: 0x3ffb8244   f9 05 ef 33                                       |...3|
D (43890) uart-rx: 0x3ffc962c   f9 f9 f9 f9 f9 f9 f9 f9                           |........|
D (43890) uart-tx: 0x3ffcaff8   7e ff 7d 23 c0 21 7d 24  7d 20 7d 20 7d 29 7d 23  |~.}#.!}$} } })}#|
D (43910) uart-tx: 0x3ffcb008   7d 25 c2 23 7d 25 f8 60  7e                       |}%.#}%.`~|
D (43920) uart-tx: 0x3ffb8248   91 f9                                             |..|
D (43920) lwip: pppos_write[1]: len=13

D (43930) lwip: pppos_input[1]: got 47 bytes

D (43930) lwip: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x586512f6> <pcomp> <accomp>]

D (43930) uart-rx: 0x3ffc962c   f9 05 ff 5d 7e ff 7d 23  c0 21 7d 21 7d 21 7d 20  |...]~.}#.!}!}!} |
D (43950) uart-rx: 0x3ffc963c   7d 34 7d 22 7d 26 7d 20  7d 20 7d 20 7d 20 7d 25  |}4}"}&} } } } }%|
D (43960) uart-rx: 0x3ffc964c   7d 26 7d 32 d6 e3 d1 7d  27 7d 22 7d 28 7d 22 3a  |}&}2...}'}"}(}":|
D (43970) uart-rx: 0x3ffc965c   97 7e 26 f9                                       |.~&.|
D (43970) CMUX: Received header: payload_offset: 3
D (43980) CMUX: Payload frame: dlci:01 type:ff payload:46 available:48
D (43990) DTE recv: 0x3ffc9630   7e ff 7d 23 c0 21 7d 21  7d 21 7d 20 7d 34 7d 22  |~.}#.!}!}!} }4}"|
D (43990) DTE recv: 0x3ffc9640   7d 26 7d 20 7d 20 7d 20  7d 20 7d 25 7d 26 7d 32  |}&} } } } }%}&}2|
D (44000) DTE recv: 0x3ffc9650   d6 e3 d1 7d 27 7d 22 7d  28 7d 22 3a 97 7e        |...}'}"}(}":.~|
D (44010) lwip: pppos_input[1]: got 46 bytes

D (44020) uart-rx: 0x3ffc962c   f9 f9 f9 f9                                       |....|
D (44020) lwip: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x12d6e3d1> <pcomp> <accomp>]

D (44030) lwip: sent [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x12d6e3d1> <pcomp> <accomp>]

D (44040) DTE transmit: 0x3ffcaff8   7e ff 7d 23 c0 21 7d 22  7d 21 7d 20 7d 34 7d 22  |~.}#.!}"}!} }4}"|
D (44050) DTE transmit: 0x3ffcb008   7d 26 7d 20 7d 20 7d 20  7d 20 7d 25 7d 26 7d 32  |}&} } } } }%}&}2|
D (44060) DTE transmit: 0x3ffcb018   d6 e3 d1 7d 27 7d 22 7d  28 7d 22 d1 fe 7e        |...}'}"}(}"..~|
D (44070) uart-tx: 0x3ffb8244   f9 05 ef 5d                                       |...]|
D (44080) uart-tx: 0x3ffcaff8   7e ff 7d 23 c0 21 7d 22  7d 21 7d 20 7d 34 7d 22  |~.}#.!}"}!} }4}"|
D (44080) uart-tx: 0x3ffcb008   7d 26 7d 20 7d 20 7d 20  7d 20 7d 25 7d 26 7d 32  |}&} } } } }%}&}2|
D (44090) uart-tx: 0x3ffcb018   d6 e3 d1 7d 27 7d 22 7d  28 7d 22 d1 fe 7e        |...}'}"}(}"..~|
D (44100) uart-tx: 0x3ffb8248   33 f9                                             |3.|
D (44110) lwip: pppos_write[1]: len=24

D (44110) lwip: ppp_netif_set_mtu[1]: mtu=1500

D (44120) lwip: ppp_send_config[1]

D (44120) lwip: pppos_send_config[1]: out_accm=0 0 0 0

D (44120) uart-rx: 0x3ffc962c   f9 05 ff 2d 7e ff 7d 23  c0 21 7d 2b 7d 22 7d 20  |...-~.}#.!}+}"} |
D (44130) lwip: ppp_recv_config[1]

D (44140) lwip: pppos_recv_config[1]: in_accm=0 0 0 0

D (44140) lwip: ppp phase changed[1]: phase=7

D (44150) uart-rx: 0x3ffc963c   7d 28 7d 32 d6 e3 d1 9b  f5 7e 72 f9              |}(}2.....~r.|
D (44150) esp-netif_lwip-ppp: Phase Authenticate
D (44160) lwip: ppp phase changed[1]: phase=9

D (44160) CMUX: Received header: payload_offset: 3
D (44160) esp-netif_lwip-ppp: Phase Network
D (44170) lwip: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]

D (44180) CMUX: Payload frame: dlci:01 type:ff payload:22 available:24
D (44180) DTE transmit: 0x3ffcaffc   7e ff 03 80 21 01 01 00  16 03 06 00 00 00 00 81  |~...!...........|
D (44190) DTE recv: 0x3ffc9630   7e ff 7d 23 c0 21 7d 2b  7d 22 7d 20 7d 28 7d 32  |~.}#.!}+}"} }(}2|
D (44200) DTE transmit: 0x3ffcb00c   06 00 00 00 00 83 06 00  00 00 00 6e db 7e        |...........n.~|
D (44220) DTE recv: 0x3ffc9640   d6 e3 d1 9b f5 7e                                 |.....~|
D (44220) uart-tx: 0x3ffb8164   f9 05 ef 3d                                       |...=|
D (44220) uart-rx: 0x3ffc962c   f9 f9 f9 f9                                       |....|
D (44230) uart-tx: 0x3ffcaffc   7e ff 03 80 21 01 01 00  16 03 06 00 00 00 00 81  |~...!...........|
D (44250) uart-tx: 0x3ffcb00c   06 00 00 00 00 83 06 00  00 00 00 6e db 7e        |...........n.~|
D (44260) uart-tx: 0x3ffb8168   7b f9                                             |{.|
D (44260) lwip: pppos_write[1]: len=26

D (44270) lwip: pppos_input[1]: got 22 bytes

D (44270) lwip: rcvd [LCP DiscReq id=0x2 magic=0x12d6e3d1]

D (44280) uart-rx: 0x3ffc962c   f9 05                                             |..|
D (44290) uart-rx: 0x3ffc962c   ff 15 7e 80 21 01 00 00  04 67 c3 7e 58 f9 f9 05  |..~.!....g.~X...|
D (44290) uart-rx: 0x3ffc963c   ff 39 7e 80 21 03 01 00  16 03 06 64 59 f4 e4 81  |.9~.!......dY...|
D (44300) uart-rx: 0x3ffc964c   06 ac 1e 08 05 83 06 ac  1e 08 06 94 a2 7e 69 f9  |.............~i.|
D (44310) CMUX: Received header: payload_offset: 2
D (44320) CMUX: Payload frame: dlci:01 type:ff payload:10 available:46
D (44320) DTE recv: 0x3ffc962e   7e 80 21 01 00 00 04 67  c3 7e                    |~.!....g.~|
D (44330) lwip: pppos_input[1]: got 10 bytes

D (44330) CMUX: Received header: payload_offset: 3
D (44340) lwip: rcvd [IPCP ConfReq id=0x0]

D (44340) lwip: sent [IPCP ConfNak id=0x0 <addr 0.0.0.0>]

D (44350) CMUX: Payload frame: dlci:01 type:ff payload:28 available:30
D (44350) DTE transmit: 0x3ffcafe0   7e ff 03 80 21 03 00 00  0a 03 06 00 00 00 00 a0  |~...!...........|
D (44360) DTE transmit: 0x3ffcaff0   3d 7e                                             |=~|
D (44360) DTE recv: 0x3ffc963e   7e 80 21 03 01 00 16 03  06 64 59 f4 e4 81 06 ac  |~.!......dY.....|
D (44370) uart-tx: 0x3ffb8244   f9 05 ef 25                                       |...%|
D (44390) DTE recv: 0x3ffc964e   1e 08 05 83 06 ac 1e 08  06 94 a2 7e              |...........~|
D (44390) uart-tx: 0x3ffcafe0   7e ff 03 80 21 03 00 00  0a 03 06 00 00 00 00 a0  |~...!...........|
D (44400) uart-rx: 0x3ffc962c   f9 f9 f9 f9                                       |....|
D (44410) uart-tx: 0x3ffcaff0   3d 7e                                             |=~|
D (44420) uart-tx: 0x3ffb8248   69 f9                                             |i.|
D (44430) lwip: pppos_write[1]: len=14

D (44430) lwip: pppos_input[1]: got 28 bytes

D (44440) uart-rx: 0x3ffc962c   f9 05 ff 15 7e 80 21 01  01 00 04 bb 99 7e 58 f9  |....~.!......~X.|
D (44440) lwip: rcvd [IPCP ConfNak id=0x1 <addr 100.89.244.228> <ms-dns1 172.30.8.5> <ms-dns2 172.30.8.6>]

D (44450) CMUX: Received header: payload_offset: 3
D (44460) lwip: sent [IPCP ConfReq id=0x2 <addr 100.89.244.228> <ms-dns1 172.30.8.5> <ms-dns2 172.30.8.6>]

D (44470) CMUX: Payload frame: dlci:01 type:ff payload:10 available:12
D (44470) DTE transmit: 0x3ffca98c   7e ff 03 80 21 01 02 00  16 03 06 64 59 f4 e4 81  |~...!......dY...|
D (44480) DTE recv: 0x3ffc9630   7e 80 21 01 01 00 04 bb  99 7e                    |~.!......~|
D (44490) DTE transmit: 0x3ffca99c   06 ac 1e 08 05 83 06 ac  1e 08 06 eb 31 7e        |............1~|
D (44500) uart-rx: 0x3ffc962c   f9 f9 f9 f9                                       |....|
D (44500) uart-tx: 0x3ffb8254   f9 05 ef 3d                                       |...=|
D (44520) uart-tx: 0x3ffca98c   7e ff 03 80 21 01 02 00  16 03 06 64 59 f4 e4 81  |~...!......dY...|
D (44530) uart-tx: 0x3ffca99c   06 ac 1e 08 05 83 06 ac  1e 08 06 eb 31 7e        |............1~|
D (44540) uart-tx: 0x3ffb8258   7b f9                                             |{.|
D (44540) lwip: pppos_write[1]: len=26

D (44550) lwip: pppos_input[1]: got 10 bytes

D (44550) lwip: rcvd [IPCP ConfReq id=0x1]

D (44560) lwip: sent [IPCP ConfAck id=0x1]

D (44560) uart-rx: 0x3ffc962c   f9 05 ff 3b 7e 80 21 02  02 00 16 03 06 64 59 f4  |...;~.!......dY.|
D (44560) DTE transmit: 0x3ffcafe0   7e ff 03 80 21 02 01 00  04 cd 92 7e              |~...!......~|
D (44580) uart-rx: 0x3ffc963c   e4 81 06 ac 1e 08 05 83  06 ac 1e 08 06 7d 5d 52  |.............}]R|
D (44580) uart-tx: 0x3ffb8244   f9 05 ef 19                                       |....|
D (44590) uart-tx: 0x3ffcafe0   7e ff 03 80 21 02 01 00  04 cd 92 7e              |~...!......~|
D (44590) uart-rx: 0x3ffc964c   7e 8a f9                                          |~..|
D (44600) uart-tx: 0x3ffb8248   44 f9                                             |D.|
D (44620) CMUX: Received header: payload_offset: 3
D (44620) lwip: pppos_write[1]: len=8

D (44620) CMUX: Payload frame: dlci:01 type:ff payload:29 available:31
D (44630) DTE recv: 0x3ffc9630   7e 80 21 02 02 00 16 03  06 64 59 f4 e4 81 06 ac  |~.!......dY.....|
D (44640) DTE recv: 0x3ffc9640   1e 08 05 83 06 ac 1e 08  06 7d 5d 52 7e           |.........}]R~|
D (44650) lwip: pppos_input[1]: got 29 bytes

D (44650) lwip: rcvd [IPCP ConfAck id=0x2 <addr 100.89.244.228> <ms-dns1 172.30.8.5> <ms-dns2 172.30.8.6>]

D (44660) uart-rx: 0x3ffc962c   f9 f9 f9 f9                                       |....|
D (44660) lwip: Could not determine remote IP address: defaulting to 10.64.64.64

D (44680) esp_netif_lwip: esp_netif_internal_dhcpc_cb lwip-netif:0x3ffb97ac
D (44690) esp_netif_lwip: check: local, if=0x3ffb9720 fn=0x400e55a4
--- 0x400e55a4: esp_netif_update_default_netif_lwip at /Users/kristiangrimsby/esp/v5.4/esp-idf/components/esp_netif/lwip/esp_netif_lwip.c:313

D (44690) esp_netif_lwip: esp_netif_update_default_netif_lwip 0x3ffb9720
D (44700) esp_netif_lwip: if0x3ffb9720 ip changed=1
D (44700) event: running post IP_EVENT:6 with handler 0x400d8194 and context 0x3ffb96cc on loop 0x3ffb8768
--- 0x400d8194: on_ip_event at /Users/kristiangrimsby/Dev/sbtech/sim7600-test/main/sim7600-test.c:100

D (44700) lwip: sifup[1]: err_code=0

E (44710) app: IP event! 6
I (44720) esp-netif_lwip-ppp: Connected
I (44720) app: Modem Connect to PPP Server
D (44720) lwip: local  IP address 100.89.244.228

I (44720) app: ~~~~~~~~~~~~~~
D (44730) lwip: remote IP address 10.64.64.64

I (44730) app: IP          : 100.89.244.228
D (44740) lwip: primary   DNS address 172.30.8.5

I (44740) app: Netmask     : 255.255.255.255
D (44740) lwip: secondary DNS address 172.30.8.6

I (44750) app: Gateway     : 10.64.64.64
D (44750) lwip: ppp phase changed[1]: phase=10

D (44760) esp_netif_lwip: check: remote, if=0x3ffb9720 fn=0x400e4bb0
--- 0x400e4bb0: esp_netif_get_dns_info_api at /Users/kristiangrimsby/esp/v5.4/esp-idf/components/esp_netif/lwip/esp_netif_lwip.c:2039

D (44760) esp-netif_lwip-ppp: Phase Running
D (44770) esp_netif_lwip: esp_netif_get_dns_info: esp_netif=0x3ffb9720 type=0
D (44780) esp_netif_lwip: call api in lwip: ret=0x0, give sem
I (44780) app: Name Server1: 172.30.8.5
D (44790) esp_netif_lwip: check: remote, if=0x3ffb9720 fn=0x400e4bb0
--- 0x400e4bb0: esp_netif_get_dns_info_api at /Users/kristiangrimsby/esp/v5.4/esp-idf/components/esp_netif/lwip/esp_netif_lwip.c:2039

D (44790) esp_netif_lwip: esp_netif_get_dns_info: esp_netif=0x3ffb9720 type=1
D (44800) esp_netif_lwip: call api in lwip: ret=0x0, give sem
I (44810) app: Name Server2: 172.30.8.6
I (44810) app: ~~~~~~~~~~~~~~
I (44810) app: GOT ip event!!!
D (44810) event: running post IP_EVENT:6 with handler 0x400e41d4 and context 0x3ffca7bc on loop 0x3ffb8768
--- 0x400e41d4: esp_netif_action_connected at /Users/kristiangrimsby/esp/v5.4/esp-idf/components/esp_netif/esp_netif_handlers.c:36

D (44820) esp_netif_handlers: esp_netif action connected with netif0x3ffb9720 from event_id=6
D (44830) esp_netif_lwip: check: remote, if=0x3ffb9720 fn=0x400e525c
--- 0x400e525c: esp_netif_up_api at /Users/kristiangrimsby/esp/v5.4/esp-idf/components/esp_netif/lwip/esp_netif_lwip.c:1723

D (44840) esp_netif_lwip: esp_netif_up_api esp_netif:0x3ffb9720
D (44840) esp_netif_lwip: check: local, if=0x3ffb9720 fn=0x400e55a4
--- 0x400e55a4: esp_netif_update_default_netif_lwip at /Users/kristiangrimsby/esp/v5.4/esp-idf/components/esp_netif/lwip/esp_netif_lwip.c:313

D (44850) esp_netif_lwip: esp_netif_update_default_netif_lwip 0x3ffb9720
D (44860) esp_netif_lwip: call api in lwip: ret=0x0, give sem
D (44860) event: running post NETIF_PPP_STATUS:0 with handler 0x400d8164 and context 0x3ffb9700 on loop 0x3ffb8768
--- 0x400d8164: on_ppp_changed at /Users/kristiangrimsby/Dev/sbtech/sim7600-test/main/sim7600-test.c:88

E (44870) app: PPP state changed event 0
D (44880) event: running post NETIF_PPP_STATUS:0 with handler 0x400dbbfc and context 0x3ffca78c on loop 0x3ffb8768
--- 0x400dbbfc: esp_modem::Netif::on_ppp_changed(void*, char const*, long, void*) at /Users/kristiangrimsby/Dev/sbtech/sim7600-test/managed_components/espressif__esp_modem/src/esp_modem_netif.cpp:22

D (46840) esp_netif_lwip: esp_netif_get_ip_info esp_netif:0x3ffb9720
E (46840) app: Connection IP: 100.89.244.228
D (46840) HTTP_CLIENT: Begin connect to: http://httpbin.org:80
D (46840) esp-tls: host:httpbin.org: strlen 11
D (46840) lwip: dns_enqueue: "httpbin.org": use DNS entry 0

D (46850) lwip: dns_enqueue: "httpbin.org": use DNS pcb 0

D (46850) lwip: dns_send: dns_servers[0] "httpbin.org": request

D (46860) lwip: sending DNS request ID 8860 for name "httpbin.org" to server 0

D (46870) DTE transmit: 0x3ffcb3a8   7e 21 45 00 00 39 00 00  00 00 40 11 6d 53 64 59  |[email protected]|
D (46880) DTE transmit: 0x3ffcb3b8   f4 e4 ac 1e 08 05 ac 72  00 35 00 25 54 7a 22 9c  |.......r.5.%Tz".|
D (46890) DTE transmit: 0x3ffcb3c8   01 00 00 01 00 00 00 00  00 00 07 68 74 74 70 62  |...........httpb|
D (46900) DTE transmit: 0x3ffcb3d8   69 6e 03 6f 72 67 00 00  01 00 01 5f 01 7e        |in.org....._.~|
D (46900) uart-tx: 0x3ffb8134   f9 05 ef 7d                                       |...}|
D (46910) uart-tx: 0x3ffcb3a8   7e 21 45 00 00 39 00 00  00 00 40 11 6d 53 64 59  |[email protected]|
D (46920) uart-tx: 0x3ffcb3b8   f4 e4 ac 1e 08 05 ac 72  00 35 00 25 54 7a 22 9c  |.......r.5.%Tz".|
D (46930) uart-tx: 0x3ffcb3c8   01 00 00 01 00 00 00 00  00 00 07 68 74 74 70 62  |...........httpb|
D (46940) uart-tx: 0x3ffcb3d8   69 6e 03 6f 72 67 00 00  01 00 01 5f 01 7e        |in.org....._.~|
D (46950) uart-tx: 0x3ffb8138   0b f9                                             |..|
D (46960) lwip: pppos_netif_output[1]: proto=0x21, len = 57

D (47160) uart-rx: 0x3ffc962c   f9 05 ff bd 7e 21 45 00  00 59 bf 72 00 00 3e af  |....~!E..Y.r..>.|
D (47160) uart-rx: 0x3ffc963c   c0 ac 1e 08 05 64 59 f4  e4 00 35 ac 72 00 45 d2  |.....dY...5.r.E.|
D (47160) uart-rx: 0x3ffc964c   21 22 9c 81 80 00 01 00  02 00 00 00 00 07 68 74  |!"............ht|
D (47170) uart-rx: 0x3ffc965c   74 70 62 69 6e 03 6f 72  67 00 00 01 00 01 c0 0c  |tpbin.org.......|
D (47180) CMUX: Received header: payload_offset: 3
D (47180) CMUX: Payload frame: dlci:01 type:ff payload:94 available:60
D (47190) uart-rx: 0x3ffc966c   00 01 00 01 00 00 00 3c  00 04 03 e0 07 40 c0 0c  |.......<.....@..|
D (47200) uart-rx: 0x3ffc967c   00 01 00 01 00 00 00 3c  00 04 64 1c a6 27 b5 3c  |.......<..d..'.<|
D (47210) uart-rx: 0x3ffc968c   7e 8e f9 f9                                       |~...|
D (47220) CMUX: Payload frame: dlci:01 type:ff payload:34 available:36
D (47220) DTE recv: 0x3ffc9630   7e 21 45 00 00 59 bf 72  00 00 3e af c0 ac 1e 08  |~!E..Y.r..>.....|
D (47230) DTE recv: 0x3ffc9640   05 64 59 f4 e4 00 35 ac  72 00 45 d2 21 22 9c 81  |.dY...5.r.E.!"..|
D (47240) DTE recv: 0x3ffc9650   80 00 01 00 02 00 00 00  00 07 68 74 74 70 62 69  |..........httpbi|
D (47250) DTE recv: 0x3ffc9660   6e 03 6f 72 67 00 00 01  00 01 c0 0c 00 01 00 01  |n.org...........|
D (47260) DTE recv: 0x3ffc9670   00 00 00 3c 00 04 03 e0  07 40 c0 0c 00 01 00 01  |...<.....@......|
D (47270) DTE recv: 0x3ffc9680   00 00 00 3c 00 04 64 1c  a6 27 b5 3c 7e 8e        |...<..d..'.<~.|
D (47280) lwip: pppos_input[1]: got 94 bytes

D (47280) lwip: pppos_input[1]: Dropping bad fcs 0x5565 proto=0x21

D (47280) uart-rx: 0x3ffc962c   f9 f9 f9 f9 f9 f9 f9                              |.......|
D (47290) lwip: pppos_input_drop: pbuf len=92, addr 0x3ffcb3a0

D (47960) lwip: dns_tmr: dns_check_entries

D (47960) lwip: dns_send: dns_servers[0] "httpbin.org": request

D (47960) lwip: sending DNS request ID 8860 for name "httpbin.org" to server 0

D (47960) DTE transmit: 0x3ffcb3a8   7e 21 45 00 00 39 00 01  00 00 40 11 6d 52 64 59  |[email protected]|
D (47970) DTE transmit: 0x3ffcb3b8   f4 e4 ac 1e 08 05 ac 72  00 35 00 25 54 7a 22 9c  |.......r.5.%Tz".|
D (47980) DTE transmit: 0x3ffcb3c8   01 00 00 01 00 00 00 00  00 00 07 68 74 74 70 62  |...........httpb|
D (47990) DTE transmit: 0x3ffcb3d8   69 6e 03 6f 72 67 00 00  01 00 01 db f1 7e        |in.org.......~|
D (48000) uart-tx: 0x3ffb8134   f9 05 ef 7d                                       |...}|
D (48010) uart-tx: 0x3ffcb3a8   7e 21 45 00 00 39 00 01  00 00 40 11 6d 52 64 59  |[email protected]|
D (48010) uart-tx: 0x3ffcb3b8   f4 e4 ac 1e 08 05 ac 72  00 35 00 25 54 7a 22 9c  |.......r.5.%Tz".|
D (48020) uart-tx: 0x3ffcb3c8   01 00 00 01 00 00 00 00  00 00 07 68 74 74 70 62  |...........httpb|
D (48030) uart-tx: 0x3ffcb3d8   69 6e 03 6f 72 67 00 00  01 00 01 db f1 7e        |in.org.......~|
D (48040) uart-tx: 0x3ffb8138   0b f9                                             |..|
D (48050) lwip: pppos_netif_output[1]: proto=0x21, len = 57

D (48140) uart-rx: 0x3ffc962c   f9 05 ff                                          |...|
D (48140) uart-rx: 0x3ffc962c   bd 7e 21 45 00 00 59 c0  21 00 00 3e af ac 1e 08  |.~!E..Y.!..>....|
D (48140) uart-rx: 0x3ffc963c   05 64 59 f4 e4 00 35 ac  72 00 45 d4 21 22 9c 81  |.dY...5.r.E.!"..|
D (48150) uart-rx: 0x3ffc964c   80 00 01 00 02 00 00 00  00 07 68 74 74 70 62 69  |..........httpbi|
D (48160) uart-rx: 0x3ffc965c   6e 03 6f 72 67 00 00 01  00 01 c0 0c 00 01 00 01  |n.org...........|
D (48170) CMUX: Received header: payload_offset: 1
D (48170) CMUX: Payload frame: dlci:01 type:ff payload:94 available:63
D (48180) uart-rx: 0x3ffc966c   00 00 00 3b 00 04 03 e0  07 40 c0 0c 00 01 00 01  |...;.....@......|
D (48190) uart-rx: 0x3ffc967c   00 00 00 3b 00 04 64 1c  a6 27 b3 fd 7e 8e f9 f9  |...;..d..'..~...|
D (48200) uart-rx: 0x3ffc968c   f9                                                |.|
D (48210) CMUX: Payload frame: dlci:01 type:ff payload:31 available:33
D (48210) DTE recv: 0x3ffc962d   7e 21 45 00 00 59 c0 21  00 00 3e af ac 1e 08 05  |~!E..Y.!..>.....|
D (48220) DTE recv: 0x3ffc963d   64 59 f4 e4 00 35 ac 72  00 45 d4 21 22 9c 81 80  |dY...5.r.E.!"...|
D (48230) DTE recv: 0x3ffc964d   00 01 00 02 00 00 00 00  07 68 74 74 70 62 69 6e  |.........httpbin|
D (48240) DTE recv: 0x3ffc965d   03 6f 72 67 00 00 01 00  01 c0 0c 00 01 00 01 00  |.org............|
D (48250) DTE recv: 0x3ffc966d   00 00 3b 00 04 03 e0 07  40 c0 0c 00 01 00 01 00  |..;.....@.......|
D (48260) DTE recv: 0x3ffc967d   00 00 3b 00 04 64 1c a6  27 b3 fd 7e 8e f9        |..;..d..'..~..|
D (48270) lwip: pppos_input[1]: got 94 bytes

D (48270) lwip: pppos_input[1]: Dropping incomplete packet 4

D (48270) uart-rx: 0x3ffc962c   f9 f9                                             |..|
D (48280) lwip: pppos_input[1]: Dropping bad fcs 0xee65 proto=0x21

D (48290) lwip: pppos_input_drop: pbuf len=91, addr 0x3ffcb3a0

D (49050) lwip: dns_tmr: dns_check_entries

D (49050) lwip: dns_send: dns_servers[0] "httpbin.org": request

D (49050) lwip: sending DNS request ID 8860 for name "httpbin.org" to server 0

D (49050) DTE transmit: 0x3ffcb3a8   7e 21 45 00 00 39 00 02  00 00 40 11 6d 51 64 59  |[email protected]|
D (49060) DTE transmit: 0x3ffcb3b8   f4 e4 ac 1e 08 05 ac 72  00 35 00 25 54 7a 22 9c  |.......r.5.%Tz".|
D (49070) DTE transmit: 0x3ffcb3c8   01 00 00 01 00 00 00 00  00 00 07 68 74 74 70 62  |...........httpb|
D (49080) DTE transmit: 0x3ffcb3d8   69 6e 03 6f 72 67 00 00  01 00 01 46 e8 7e        |in.org.....F.~|
D (49090) uart-tx: 0x3ffb8134   f9 05 ef 7d                                       |...}|
D (49100) uart-tx: 0x3ffcb3a8   7e 21 45 00 00 39 00 02  00 00 40 11 6d 51 64 59  |[email protected]|
D (49100) uart-tx: 0x3ffcb3b8   f4 e4 ac 1e 08 05 ac 72  00 35 00 25 54 7a 22 9c  |.......r.5.%Tz".|
D (49110) uart-tx: 0x3ffcb3c8   01 00 00 01 00 00 00 00  00 00 07 68 74 74 70 62  |...........httpb|
D (49120) uart-tx: 0x3ffcb3d8   69 6e 03 6f 72 67 00 00  01 00 01 46 e8 7e        |in.org.....F.~|
D (49130) uart-tx: 0x3ffb8138   0b f9                                             |..|
D (49140) lwip: pppos_netif_output[1]: proto=0x21, len = 57

D (49220) uart-rx: 0x3ffc962c   f9 05 ff bd 7e 21 45 00  00 59 c0 81 00 00 3e ae  |....~!E..Y....>.|
D (49220) uart-rx: 0x3ffc963c   b1 ac 1e 08 05 64 59 f4  e4 00 35 ac 72 00 45 d6  |.....dY...5.r.E.|
D (49220) uart-rx: 0x3ffc964c   21 22 9c 81 80 00 01 00  02 00 00 00 00 07 68 74  |!"............ht|
D (49230) uart-rx: 0x3ffc965c   74 70 62 69 6e 03 6f 72  67 00 00 01 00 01 c0 0c  |tpbin.org.......|
D (49240) CMUX: Received header: payload_offset: 3
D (49240) CMUX: Payload frame: dlci:01 type:ff payload:94 available:60
D (49250) uart-rx: 0x3ffc966c   00 01 00 01 00 00 00 3a  00 04 03 e0 07 40 c0 0c  |.......:.....@..|
D (49260) uart-rx: 0x3ffc967c   00 01 00 01 00 00 00 3a  00 04 64 1c a6 27 ae 86  |.......:..d..'..|
D (49270) uart-rx: 0x3ffc968c   7e 8e f9 f9                                       |~...|
D (49280) CMUX: Payload frame: dlci:01 type:ff payload:34 available:36
D (49280) DTE recv: 0x3ffc9630   7e 21 45 00 00 59 c0 81  00 00 3e ae b1 ac 1e 08  |~!E..Y....>.....|
D (49290) DTE recv: 0x3ffc9640   05 64 59 f4 e4 00 35 ac  72 00 45 d6 21 22 9c 81  |.dY...5.r.E.!"..|
D (49300) DTE recv: 0x3ffc9650   80 00 01 00 02 00 00 00  00 07 68 74 74 70 62 69  |..........httpbi|
D (49310) DTE recv: 0x3ffc9660   6e 03 6f 72 67 00 00 01  00 01 c0 0c 00 01 00 01  |n.org...........|
D (49320) DTE recv: 0x3ffc9670   00 00 00 3a 00 04 03 e0  07 40 c0 0c 00 01 00 01  |...:.....@......|
D (49330) DTE recv: 0x3ffc9680   00 00 00 3a 00 04 64 1c  a6 27 ae 86 7e 8e        |...:..d..'..~.|
D (49340) lwip: pppos_input[1]: got 94 bytes

D (49340) lwip: pppos_input[1]: Dropping bad fcs 0x8c2a proto=0x8ef9

D (49340) uart-rx: 0x3ffc962c   f9 f9 f9 f9 f9 f9 f9                              |.......|
D (49350) lwip: pppos_input[1]: Dropping bad fcs 0x148b proto=0x21

D (49360) lwip: pppos_input_drop: pbuf len=92, addr 0x3ffcb3a0

Code

#include <string.h>
#include "freertos/FreeRTOS.h"
#include "freertos/event_groups.h"
#include "esp_netif.h"
#include "esp_netif_ppp.h"
#include "esp_log.h"
#include "esp_event.h"
#include "esp_modem_config.h"
#include "esp_modem_api.h"
#include "driver/gpio.h"
#include "esp_http_client.h"
#include "netif/ppp/pppos.h"

#define MODEM_TX GPIO_NUM_27
#define MODEM_RX GPIO_NUM_26
#define MODEM_PWRKEY GPIO_NUM_4
#define MODEM_DTR GPIO_NUM_32
#define MODEM_RI GPIO_NUM_33
#define MODEM_FLIGHT GPIO_NUM_25
#define MODEM_STATUS GPIO_NUM_34
#define UART_BAUD 115200
#define UART_BUF_SIZE 128

// using namespace esp_modem;

static const char *TAG = "app";

static bool has_got_ip = false;

void power_toggle_modem()
{
  ESP_LOGI(TAG, "toggling modem power...");
  // gpio_reset_pin(MODEM_PWRKEY);
  // gpio_reset_pin(MODEM_FLIGHT);
  // gpio_reset_pin(MODEM_STATUS);
  gpio_set_direction(MODEM_PWRKEY, GPIO_MODE_OUTPUT);

  gpio_set_direction(MODEM_FLIGHT, GPIO_MODE_OUTPUT);
  gpio_set_level(MODEM_FLIGHT, 1);

  gpio_set_direction(MODEM_STATUS, GPIO_MODE_INPUT);
  int status = gpio_get_level(MODEM_STATUS);
  ESP_LOGI(TAG, "status=%d", status);

  if (status == 1)
  {
    ESP_LOGE(TAG, "Modem is already powered on");

    gpio_set_level(MODEM_PWRKEY, 1);
    vTaskDelay(pdMS_TO_TICKS(2600));
    gpio_set_level(MODEM_PWRKEY, 0);

    status = gpio_get_level(MODEM_STATUS);
    while (status == 1)
    {
      status = gpio_get_level(MODEM_STATUS);
      ESP_LOGI(TAG, "status=%d", status);
      vTaskDelay(pdMS_TO_TICKS(1000));
    }
  }

  /*
  gpio_set_level(MODEM_PWRKEY, 0);
  vTaskDelay(pdMS_TO_TICKS(500));
  gpio_set_level(MODEM_PWRKEY, 1);
  vTaskDelay(pdMS_TO_TICKS(500));
  gpio_set_level(MODEM_PWRKEY, 0);
  vTaskDelay(pdMS_TO_TICKS(500));
  */
  gpio_set_level(MODEM_PWRKEY, 1);
  vTaskDelay(pdMS_TO_TICKS(300));
  gpio_set_level(MODEM_PWRKEY, 0);

  ESP_LOGI(TAG, "waiting for modem...");

  while (status == 0)
  {
    vTaskDelay(pdMS_TO_TICKS(100));
    status = gpio_get_level(MODEM_STATUS);
    ESP_LOGI(TAG, "status=%d", status);
  }

  // vTaskDelay(pdMS_TO_TICKS(5000));
}

static void on_ppp_changed(void *arg, esp_event_base_t event_base,
                           int32_t event_id, void *event_data)
{
  ESP_LOGE(TAG, "PPP state changed event %" PRIu32, event_id);
  if (event_id == NETIF_PPP_ERRORUSER)
  {
    /* User interrupted event from esp-netif */
    esp_netif_t **p_netif = event_data;
    ESP_LOGE(TAG, "User interrupted event from netif:%p", *p_netif);
  }
}

static void on_ip_event(void *arg, esp_event_base_t event_base,
                        int32_t event_id, void *event_data)
{
  ESP_LOGE(TAG, "IP event! %" PRIu32, event_id);
  if (event_id == IP_EVENT_PPP_GOT_IP)
  {
    has_got_ip = true;

    esp_netif_dns_info_t dns_info;

    ip_event_got_ip_t *event = (ip_event_got_ip_t *)event_data;
    esp_netif_t *netif = event->esp_netif;

    ESP_LOGI(TAG, "Modem Connect to PPP Server");
    ESP_LOGI(TAG, "~~~~~~~~~~~~~~");
    ESP_LOGI(TAG, "IP          : " IPSTR, IP2STR(&event->ip_info.ip));
    ESP_LOGI(TAG, "Netmask     : " IPSTR, IP2STR(&event->ip_info.netmask));
    ESP_LOGI(TAG, "Gateway     : " IPSTR, IP2STR(&event->ip_info.gw));
    esp_netif_get_dns_info(netif, 0, &dns_info);
    ESP_LOGI(TAG, "Name Server1: " IPSTR, IP2STR(&dns_info.ip.u_addr.ip4));
    esp_netif_get_dns_info(netif, 1, &dns_info);
    ESP_LOGI(TAG, "Name Server2: " IPSTR, IP2STR(&dns_info.ip.u_addr.ip4));
    ESP_LOGI(TAG, "~~~~~~~~~~~~~~");

    ESP_LOGI(TAG, "GOT ip event!!!");
  }
  else if (event_id == IP_EVENT_PPP_LOST_IP)
  {
    ESP_LOGI(TAG, "Modem Disconnect from PPP Server");
  }
  else if (event_id == IP_EVENT_GOT_IP6)
  {
    ESP_LOGI(TAG, "GOT IPv6 event!");

    ip_event_got_ip6_t *event = (ip_event_got_ip6_t *)event_data;
    ESP_LOGI(TAG, "Got IPv6 address " IPV6STR, IPV62STR(event->ip6_info.ip));
  }
}

esp_err_t _http_event_handler(esp_http_client_event_t *evt)
{
  ESP_LOGI(TAG, "HTTP_EVENT_ON_DATA, len=%d", evt->event_id);

  return ESP_OK;
}

void http_test()
{
  char local_response_buffer[2048 + 1] = {0};

  esp_http_client_config_t config = {
      .host = "httpbin.org",
      .path = "/get",
      .query = "esp",
      .event_handler = _http_event_handler,
      .user_data = local_response_buffer, // Pass address of local buffer to get response
      .disable_auto_redirect = true,
  };
  esp_http_client_handle_t client = esp_http_client_init(&config);

  esp_err_t err = esp_http_client_perform(client);
  if (err == ESP_OK)
  {
    ESP_LOGI(TAG, "HTTP GET Status = %d, content_length = %" PRId64,
             esp_http_client_get_status_code(client),
             esp_http_client_get_content_length(client));
  }
  else
  {
    ESP_LOGE(TAG, "HTTP GET request failed: %s", esp_err_to_name(err));
  }
}

void app_main(void)
{
  esp_err_t err;

  /* Init and register system/core components */
  ESP_ERROR_CHECK(esp_netif_init());
  ESP_ERROR_CHECK(esp_event_loop_create_default());
  ESP_ERROR_CHECK(esp_event_handler_register(IP_EVENT, ESP_EVENT_ANY_ID, &on_ip_event, NULL));
  ESP_ERROR_CHECK(esp_event_handler_register(NETIF_PPP_STATUS, ESP_EVENT_ANY_ID, &on_ppp_changed, NULL));

  power_toggle_modem();

  esp_modem_dce_config_t dce_config = ESP_MODEM_DCE_DEFAULT_CONFIG("onomondo");
  esp_netif_config_t netif_ppp_config = ESP_NETIF_DEFAULT_PPP();
  esp_netif_t *esp_netif = esp_netif_new(&netif_ppp_config);

  /* Configure and create the DTE */
  esp_modem_dte_config_t dte_config = ESP_MODEM_DTE_DEFAULT_CONFIG();
  dte_config.uart_config.flow_control = ESP_MODEM_FLOW_CONTROL_SW;
  dte_config.uart_config.tx_io_num = MODEM_TX;
  dte_config.uart_config.rx_io_num = MODEM_RX;
  //dte_config.uart_config.rts_io_num = MODEM_DTR;
  dte_config.uart_config.baud_rate = UART_BAUD;
  dte_config.uart_config.port_num = UART_NUM_1;
  dte_config.uart_config.rx_buffer_size = 4096 * 12;
  dte_config.uart_config.tx_buffer_size = 4096 * 2;
  dte_config.uart_config.event_queue_size = 50;
  dte_config.dte_buffer_size = 4096;

  esp_modem_dce_t *dce = esp_modem_new_dev(ESP_MODEM_DCE_SIM7600, &dte_config, &dce_config, esp_netif);
  
  assert(dce);

  err = esp_modem_set_mode(dce, ESP_MODEM_MODE_DETECT);
  uint32_t count = 0;
  while (err != ESP_OK)
  {
    ESP_LOGE(TAG, "esp_modem_sync failed with %d", err);
    vTaskDelay(pdMS_TO_TICKS(500));
    err = esp_modem_set_mode(dce, ESP_MODEM_MODE_DETECT);
    count++;

    if (count % 20 == 0)
    {
      power_toggle_modem();
    }
  }

  esp_modem_dce_mode_t mode = esp_modem_get_mode(dce);
  ESP_LOGI(TAG, "Mode detection completed: current mode is: %d", mode);

  ESP_LOGE(TAG, "Starting connectoin");

  if (mode != ESP_MODEM_MODE_CMUX)
  {
    ESP_LOGE(TAG, "Setting mode to DATA");
    err = esp_modem_set_mode(dce, ESP_MODEM_MODE_CMUX);
    if (err != ESP_OK)
    {
      ESP_LOGE(TAG, "esp_modem_set_mode(ESP_MODEM_MODE_DATA) failed with %d", err);
      // return;
    }
  }

  ESP_LOGE(TAG, "Waiting for IP");
  while (!has_got_ip)
  {
    vTaskDelay(pdMS_TO_TICKS(1000));
  }

  while (1)
  {
    esp_netif_ip_info_t ip_info;
    esp_err_t err2 = esp_netif_get_ip_info(esp_netif, &ip_info);
    if (err2 == ESP_OK)
    {
      ESP_LOGE(TAG, "Connection IP: " IPSTR, IP2STR(&ip_info.ip));
    }

    http_test();
  }
}

What am I doing wrong here?

The text was updated successfully, but these errors were encountered:

david-cermak · 2025-03-26T17:25:35Z

dte_config.uart_config.flow_control = ESP_MODEM_FLOW_CONTROL_SW;

Can you try to turn off the flow control?

err = esp_modem_set_mode(dce, ESP_MODEM_MODE_CMUX);

Also would it work without the CMUX mode? switching directly to ESP_MODEM_MODE_DATA ?

kgrimsby · 2025-03-26T17:35:19Z

Oh my, of course it was the flow control. About exhausted every option but missed that one.

That seems to work flawless!

github-actions bot changed the title ~~SIM7600 Strange packet parsing behaviour~~ SIM7600 Strange packet parsing behaviour (IDFGH-14949) Mar 26, 2025

espressif-bot added the Status: Opened Issue is new label Mar 26, 2025

kgrimsby closed this as completed Mar 26, 2025

espressif-bot assigned david-cermak Mar 28, 2025

espressif-bot added Status: Done Resolution: Won't Do and removed Status: Opened Issue is new labels Mar 28, 2025

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

SIM7600 Strange packet parsing behaviour (IDFGH-14949) #789

SIM7600 Strange packet parsing behaviour (IDFGH-14949) #789

kgrimsby commented Mar 26, 2025 •

edited

Loading

david-cermak commented Mar 26, 2025

kgrimsby commented Mar 26, 2025

SIM7600 Strange packet parsing behaviour (IDFGH-14949) #789

SIM7600 Strange packet parsing behaviour (IDFGH-14949) #789

Comments

kgrimsby commented Mar 26, 2025 • edited Loading

Answers checklist.

General issue report

david-cermak commented Mar 26, 2025

kgrimsby commented Mar 26, 2025

kgrimsby commented Mar 26, 2025 •

edited

Loading