用USB CDC 4G Module时，卡在了Modem state STAGE_WAIT_IP步骤 (AEGHB-586)

[tyrael-cui]

Answers checklist.

• I have read the documentation ESP-IDF Programming Guide and the issue is not addressed there.
• I have updated my IDF 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

我的硬件是ESP32-S2连接EC600M-CN模块，直接用了USB_CDC_MODULE中的例程。IDF版本 5.1.2。开机log如下：

ESP-ROM:esp32s2-rc4-20191025
Build:Oct 25 2019
rst:0x1 (POWERON),boot:0x8 (SPI_FAST_FLASH_BOOT)
SPIWP:0xee
mode:DIO, clock div:1
load:0x3ffe6110,len:0x19c8
load:0x4004b000,len:0x4
load:0x4004b004,len:0xc34
load:0x4004f000,len:0x3428
entry 0x4004b1ec
I (23) boot: ESP-IDF v5.1.2-dirty 2nd stage bootloader
I (24) boot: compile time Mar 7 2024 15:01:58
I (24) boot: chip revision: v0.0
I (28) qio_mode: Enabling default flash chip QIO
I (33) boot.esp32s2: SPI Speed : 80MHz
I (38) boot.esp32s2: SPI Mode : QIO
I (42) boot.esp32s2: SPI Flash Size : 4MB
I (47) boot: Enabling RNG early entropy source...
I (53) boot: Partition Table:
I (56) boot: ## Label Usage Type ST Offset Length
I (63) boot: 0 nvs WiFi data 01 02 00009000 00006000
I (71) boot: 1 phy_init RF data 01 01 0000f000 00001000
I (78) boot: 2 factory factory app 00 00 00010000 00100000
I (86) boot: 3 storage Unknown data 01 82 00110000 00100000
I (93) boot: End of partition table
I (98) esp_image: segment 0: paddr=00010020 vaddr=3f000020 size=2c848h (182344) map
I (137) esp_image: segment 1: paddr=0003c870 vaddr=3ffcbed0 size=03520h ( 13600) load
I (140) esp_image: segment 2: paddr=0003fd98 vaddr=40024000 size=00280h ( 640) load
I (143) esp_image: segment 3: paddr=00040020 vaddr=40080020 size=9f53ch (652604) map
I (263) esp_image: segment 4: paddr=000df564 vaddr=40024280 size=17c50h ( 97360) load
I (298) boot: Loaded app from partition at offset 0x10000
I (298) boot: Disabling RNG early entropy source...
I (309) cpu_start: Unicore app
I (309) cache: Instruction cache : size 8KB, 4Ways, cache line size 32Byte
I (309) cache: Data cache : size 8KB, 4Ways, cache line size 32Byte
I (316) cpu_start: Pro cpu up.
I (339) cpu_start: Pro cpu start user code
I (339) cpu_start: cpu freq: 240000000 Hz
I (339) cpu_start: Application information:
I (342) cpu_start: Project name: usb_cat1
I (347) cpu_start: App version: 1
I (351) cpu_start: Compile time: Mar 12 2024 13:50:53
I (357) cpu_start: ELF file SHA256: e4141d4cdf55fffb...
I (363) cpu_start: ESP-IDF: v5.1.2-dirty
I (369) cpu_start: Min chip rev: v0.0
I (373) cpu_start: Max chip rev: v1.99
I (378) cpu_start: Chip rev: v0.0
I (383) heap_init: Initializing. RAM available for dynamic allocation:
I (390) heap_init: At 3FFD3200 len 00028E00 (163 KiB): DRAM
I (396) heap_init: At 3FFFC000 len 00003A10 (14 KiB): DRAM
I (402) heap_init: At 3FF9E000 len 00001FE8 (7 KiB): RTCRAM
I (409) spi_flash: detected chip: generic
I (413) spi_flash: flash io: qio
I (418) app_start: Starting scheduler on CPU0
I (422) main_task: Started on CPU0
I (426) main_task: Calling app_main()
I (430) led_indicator: LED Indicator Version: 0.3.0
I (436) gpio: GPIO[5]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (445) led_indicator: blink_lists is null, use default blink list
W (452) led_indicator: ./managed_components/espressif__led_indicator/src/led_indicator.c:280 (_led_indicator_create_com):LED indicator does not have the hal_indicator_set_brightness function
I (469) led_indicator: Indicator create successfully. type:GPIO mode, hardware_data:0x5, is_active_level_high:1, blink_lists:default
I (485) 4g_main: ====================================
I (488) 4g_main: ESP 4G Cat.1 Wi-Fi Router
I (493) 4g_main: ====================================
I (498) modem_board: iot_usbh_modem, version: 0.2.1
I (504) modem_board: Power the module first....
I (509) gpio: GPIO[9]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (518) gpio: GPIO[10]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (528) modem_board: Power modem using io=10, level=1
I (833) modem_board: Force reset modem board....
I (833) modem_board: Resetting modem using io=9, level=1
I (1633) modem_board: Waiting for modem initialize ready
I (2633) modem_board: Waiting for modem 11 seconds left.....
I (3633) modem_board: Waiting for modem 10 seconds left.....
I (4633) modem_board: Waiting for modem 9 seconds left.....
I (5633) modem_board: Waiting for modem 8 seconds left.....
I (6633) modem_board: Waiting for modem 7 seconds left.....
I (7633) modem_board: Waiting for modem 6 seconds left.....
I (8633) modem_board: Waiting for modem 5 seconds left.....
I (9633) modem_board: Waiting for modem 4 seconds left.....
I (10633) modem_board: Waiting for modem 3 seconds left.....
I (11633) modem_board: Waiting for modem 2 seconds left.....
I (12633) modem_board: Waiting for modem 1 seconds left.....
I (12633) USB_HCDC: iot_usbh_cdc, version: 0.2.2
I (12633) IOT_USBH: iot_usbh, version: 0.2.1
I (12668) IOT_USBH: USB Port=1 init succeed, fifo strategy=0
I (12668) IOT_USBH: USB Processing Start
I (12668) IOT_USBH: Waiting USB Connection
I (12672) IOT_USBH: Port power: ON Succeed
I (12677) IOT_USBH: usb port start succeed
I (12682) USB_HCDC: usbh cdc driver install succeed
I (12687) USB_HCDC: Waiting CDC Device Connection
I (12693) USB_HCDC: CDC task start
I (12927) IOT_USBH: line 131 PORT_EVENT_CONNECTION
I (13027) IOT_USBH: Resetting Port
I (13087) IOT_USBH: Port reset succeed
I (13087) IOT_USBH: Getting Port Speed
I (13087) IOT_USBH: USB Speed: full-speed
I (13089) IOT_USBH: Pipe init succeed, addr: 00
I (13094) IOT_USBH: Set Device Addr = 1
I (13099) IOT_USBH: Set Device Addr Done
I (13103) IOT_USBH: get device desc
I (13108) IOT_USBH: get device desc, actual_num_bytes:26
*** Device descriptor ***
bLength 18
bDescriptorType 1
bcdUSB 2.00
bDeviceClass 0xef
bDeviceSubClass 0x2
bDeviceProtocol 0x1
bMaxPacketSize0 64
idVendor 0x2c7c
idProduct 0x6002
bcdDevice 3.10
iManufacturer 1
iProduct 2
iSerialNumber 3
bNumConfigurations 1
I (13137) IOT_USBH: get short config desc
I (13142) IOT_USBH: get config desc, actual_num_bytes:16
I (13148) IOT_USBH: get full config desc
I (13153) IOT_USBH: get full config desc, actual_num_bytes:217
*** Configuration descriptor ***
bLength 9
bDescriptorType 2
wTotalLength 209
bNumInterfaces 5
bConfigurationValue 1
iConfiguration 0
bmAttributes 0xe0
bMaxPower 500mA
*** Interface Association Descriptor ***
bLength 8
bDescriptorType 11
bFirstInterface 0
bInterfaceCount 2
bFunctionClass 0x2
bFunctionSubClass 0x6
bFunctionProtocol 0x0
iFunction 5
*** Interface descriptor ***
bLength 9
bDescriptorType 4
bInterfaceNumber 0
bAlternateSetting 0
bNumEndpoints 1
bInterfaceClass 0x2
bInterfaceSubClass 0x6
bInterfaceProtocol 0x0
iInterface 5
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x87 EP 7 IN
bmAttributes 0x3 INT
wMaxPacketSize 64
bInterval 16
*** Interface descriptor ***
bLength 9
bDescriptorType 4
bInterfaceNumber 1
bAlternateSetting 0
bNumEndpoints 0
bInterfaceClass 0xa
bInterfaceSubClass 0x0
bInterfaceProtocol 0x0
iInterface 5
*** Interface descriptor ***
bLength 9
bDescriptorType 4
bInterfaceNumber 1
bAlternateSetting 1
bNumEndpoints 2
bInterfaceClass 0xa
bInterfaceSubClass 0x0
bInterfaceProtocol 0x0
iInterface 0
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x83 EP 3 IN
bmAttributes 0x2 BULK
wMaxPacketSize 512
bInterval 0
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0xc EP 12 OUT
bmAttributes 0x2 BULK
wMaxPacketSize 512
bInterval 0
*** Interface descriptor ***
bLength 9
bDescriptorType 4
bInterfaceNumber 2
bAlternateSetting 0
bNumEndpoints 2
bInterfaceClass 0xff
bInterfaceSubClass 0x0
bInterfaceProtocol 0x0
iInterface 8
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x82 EP 2 IN
bmAttributes 0x2 BULK
wMaxPacketSize 64
bInterval 0
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0xb EP 11 OUT
bmAttributes 0x2 BULK
wMaxPacketSize 64
bInterval 0
*** Interface descriptor ***
bLength 9
bDescriptorType 4
bInterfaceNumber 3
bAlternateSetting 0
bNumEndpoints 3
bInterfaceClass 0xff
bInterfaceSubClass 0x0
bInterfaceProtocol 0x0
iInterface 11
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x89 EP 9 IN
bmAttributes 0x3 INT
wMaxPacketSize 64
bInterval 16
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x86 EP 6 IN
bmAttributes 0x2 BULK
wMaxPacketSize 64
bInterval 0
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0xf EP 15 OUT
bmAttributes 0x2 BULK
wMaxPacketSize 64
bInterval 0
*** Interface descriptor ***
bLength 9
bDescriptorType 4
bInterfaceNumber 4
bAlternateSetting 0
bNumEndpoints 3
bInterfaceClass 0xff
bInterfaceSubClass 0x0
bInterfaceProtocol 0x0
iInterface 11
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x88 EP 8 IN
bmAttributes 0x3 INT
wMaxPacketSize 64
bInterval 16
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x81 EP 1 IN
bmAttributes 0x2 BULK
wMaxPacketSize 64
bInterval 0
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0xa EP 10 OUT
bmAttributes 0x2 BULK
wMaxPacketSize 64
bInterval 0
I (13453) IOT_USBH: Set Device Configuration = 1
I (13459) IOT_USBH: Set Device Configuration Done
I (13464) IOT_USBH: Pipe init succeed, addr: 81
I (13469) IOT_USBH: Pipe init succeed, addr: 0A
I (13474) USB_HCDC: CDC Device Connected
I (13479) esp-modem: --------- Modem PreDefined Info ------------------
I (13486) esp-modem: Model: EC600NCNLC-N06
I (13491) esp-modem: Modem itf: IN Addr:0x81, OUT Addr:0x0A
I (13497) esp-modem: ----------------------------------------------------
I (13505) gpio: GPIO[10]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (13514) gpio: GPIO[9]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (13524) modem_board: DTE reconnect, reconnecting ...

I (13525) USB_HCDC: rx0 flush -0 = 0
I (14530) modem_board: reconnect after 5s...
I (15530) modem_board: reconnect after 4s...
I (16530) modem_board: reconnect after 3s...
I (17530) modem_board: reconnect after 2s...
I (18530) modem_board: reconnect after 1s...
I (18530) modem_board: Modem state STAGE_SYNC, Start
I (18544) modem_board: Network Auto reconnecting ...
W (18544) 4g_main: Modem Board Event: Network disconnected
E (18544) led_indicator: ./managed_components/espressif__led_indicator/src/led_indicator.c:461 (led_indicator_stop):invalid p_handle
I (18557) modem_board: Modem state STAGE_SYNC, Success!
I (18663) modem_board: Modem state STAGE_CHECK_SIM, Start
I (18666) modem_board: SIM Card Ready
I (18666) 4g_main: Modem Board Event: SIM Card Connected
I (18668) modem_board: Modem state STAGE_CHECK_SIM, Success!
I (18774) modem_board: Modem state STAGE_CHECK_SIGNAL, Start
I (18776) modem_board: Signal quality: rssi=28, ber=99
I (18776) modem_board: Modem state STAGE_CHECK_SIGNAL, Success!
I (18881) modem_board: Modem state STAGE_CHECK_REGIST, Start
I (18883) modem_board: Network registed, Operator: "CHINA MOBILE"
I (18883) modem_board: Modem state STAGE_CHECK_REGIST, Success!
I (18989) modem_board: Modem state STAGE_START_PPP, Start
I (18996) modem_board: Modem state STAGE_START_PPP, Success!
W (18997) modem_board: Modem event! 0
I (19096) modem_board: Modem state STAGE_WAIT_IP, Start
I (78997) esp-netif_lwip-ppp: Connection lost
I (78997) modem_board: IP event! 7
I (78997) modem_board: Modem Disconnect from PPP Server
W (79096) modem_board: Modem Got IP timeout, retry from start!
E (79096) modem_board: Modem state STAGE_WAIT_IP, retry 5, timeout !
W (79098) modem_board: Retry From Start !
I (79103) modem_board: Modem state STAGE_SYNC, Start
W (79118) esp-modem-dte: Not support sending command in ppp mode, please exit ppp mode first
response timeoutem_dce: esp_modem_dce_generic_command(26): Command:AT
I (79136) modem_board: Current timeouts: 1 and errors: 0
W (79136) modem_board: Try to exit ppp mode........
E (89138) esp-modem-dte: esp_modem_dte_send_cmd(272): process command timeout
W (89138) esp_modem_dce: esp_modem_dce_generic_command(26): Command:+++ response timeout
I (89148) modem_board: Network Auto reconnecting ...
W (89149) 4g_main: Modem Board Event: Network disconnected
E (89155) led_indicator: ./managed_components/espressif__led_indicator/src/led_indicator.c:461 (led_indicator_stop):invalid p_handle
I (89168) modem_board: Modem state STAGE_SYNC, Success!
I (89273) modem_board: Modem state STAGE_CHECK_SIM, Start
I (89275) modem_board: SIM Card Ready
I (89275) 4g_main: Modem Board Event: SIM Card Connected
I (89278) modem_board: Modem state STAGE_CHECK_SIM, Success!
I (89384) modem_board: Modem state STAGE_CHECK_SIGNAL, Start
I (89386) modem_board: Signal quality: rssi=25, ber=99
I (89386) modem_board: Modem state STAGE_CHECK_SIGNAL, Success!
I (89491) modem_board: Modem state STAGE_CHECK_REGIST, Start
I (89493) modem_board: Network registed, Operator: "CHINA MOBILE"
I (89493) modem_board: Modem state STAGE_CHECK_REGIST, Success!
I (89599) modem_board: Modem state STAGE_START_PPP, Start
I (89605) modem_board: Modem state STAGE_START_PPP, Success!
W (89606) modem_board: Modem event! 0
I (89705) modem_board: Modem state STAGE_WAIT_IP, Start
I (149606) esp-netif_lwip-ppp: Connection lost
I (149606) modem_board: IP event! 7
I (149606) modem_board: Modem Disconnect from PPP Server
W (149705) modem_board: Modem Got IP timeout, retry from start!
E (149705) modem_board: Modem state STAGE_WAIT_IP, retry 5, timeout !
W (149707) modem_board: Retry From Start !
I (149712) modem_board: Modem state STAGE_SYNC, Start
W (149728) esp-modem-dte: Not support sending command in ppp mode, please exit ppp mode first
response timeoutdem_dce: esp_modem_dce_generic_command(26): Command:AT
I (149746) modem_board: Current timeouts: 1 and errors: 0
W (149746) modem_board: Try to exit ppp mode........
E (159747) esp-modem-dte: esp_modem_dte_send_cmd(272): process command timeout
W (159747) esp_modem_dce: esp_modem_dce_generic_command(26): Command:+++ response timeout
I (159757) modem_board: Network Auto reconnecting ...
W (159758) 4g_main: Modem Board Event: Network disconnected
E (159764) led_indicator: ./managed_components/espressif__led_indicator/src/led_indicator.c:461 (led_indicator_stop):invalid p_handle
I (159777) modem_board: Modem state STAGE_SYNC, Success!
I (159883) modem_board: Modem state STAGE_CHECK_SIM, Start
I (159885) modem_board: SIM Card Ready
I (159885) 4g_main: Modem Board Event: SIM Card Connected
I (159888) modem_board: Modem state STAGE_CHECK_SIM, Success!
I (159995) modem_board: Modem state STAGE_CHECK_SIGNAL, Start
I (159997) modem_board: Signal quality: rssi=25, ber=99
I (159997) modem_board: Modem state STAGE_CHECK_SIGNAL, Success!
I (160103) modem_board: Modem state STAGE_CHECK_REGIST, Start
I (160105) modem_board: Network registed, Operator: "CHINA MOBILE"
I (160105) modem_board: Modem state STAGE_CHECK_REGIST, Success!
I (160211) modem_board: Modem state STAGE_START_PPP, Start
I (160217) modem_board: Modem state STAGE_START_PPP, Success!
W (160218) modem_board: Modem event! 0
I (160317) modem_board: Modem state STAGE_WAIT_IP, Start

========================================================
目前已经检查过EC600M模块，是正常工作的，SIM卡也可以注册上网，但就是无法获取IP。
请大家指教是什么原因造成的？
谢谢！

[leeebo]

@tyrael-cui Please try with other SIM Card or change the APN same as the operator suggest.

[tyrael-cui]

@leeebo 我设置了APN名字，打开了debug信息，发现已经连接成功了，但仍然无法获取IP。Log如下：

I (689) app_start: Starting scheduler on CPU0
I (694) main_task: Started on CPU0
D (698) heap_init: New heap initialised at 0x3fffc000
I (703) main_task: Calling app_main()
I (707) led_indicator: LED Indicator Version: 0.3.0
I (713) gpio: GPIO[5]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (722) led_indicator: blink_lists is null, use default blink list
W (729) led_indicator: ./managed_components/espressif__led_indicator/src/led_indicator.c:280 (_led_indicator_create_com):LED indicator does not have the hal_indicator_set_brightness functionI (746) led_indicator: Indicator create successfully. type:GPIO mode, hardware_data:0x5, is_active_level_high:1, blink_lists:default
D (762) esp_netif_lwip: LwIP stack has been initialized
D (764) esp_netif_lwip: check: remote, if=0x0 fn=0x40119f78
0x40119f78: alloc_client_data_id at D:/Espressif/frameworks/esp-idf-v5.1.2/components/esp_netif/lwip/esp_netif_lwip.c:166
D (770) esp_netif_lwip: call api in lwip: ret=0x0, give sem
D (776) esp_netif_lwip: esp-netif has been successfully initialized
D (782) event: running task for loop 0x3ffd8fe8
D (787) event: created task for loop 0x3ffd8fe8
D (791) event: created event loop 0x3ffd8fe8
I (796) 4g_main: ====================================
I (801) 4g_main: ESP 4G Cat.1 Wi-Fi Router
I (806) 4g_main: ====================================
I (812) modem_board: iot_usbh_modem, version: 0.2.1
s_modem_evt_hdl ptr= 0x3ffcfc84
I (821) modem_board: Power the module first....
I (826) gpio: GPIO[9]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (835) gpio: GPIO[10]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (844) modem_board: Power modem using io=10, level=1
I (1150) modem_board: Force reset modem board....
I (1150) modem_board: Resetting modem using io=9, level=1
I (1950) modem_board: Waiting for modem initialize ready
I (2950) modem_board: Waiting for modem 11 seconds left.....
I (3950) modem_board: Waiting for modem 10 seconds left.....
I (4950) modem_board: Waiting for modem 9 seconds left.....
I (5950) modem_board: Waiting for modem 8 seconds left.....
I (6950) modem_board: Waiting for modem 7 seconds left.....
I (7950) modem_board: Waiting for modem 6 seconds left.....
I (8950) modem_board: Waiting for modem 5 seconds left.....
I (9950) modem_board: Waiting for modem 4 seconds left.....
I (10950) modem_board: Waiting for modem 3 seconds left.....
I (11950) modem_board: Waiting for modem 2 seconds left.....
I (12950) modem_board: Waiting for modem 1 seconds left.....
D (12950) event: created event loop 0x3ffdba10
I (12950) USB_HCDC: iot_usbh_cdc, version: 0.2.2
I (12955) IOT_USBH: iot_usbh, version: 0.2.1
D (12960) intr_alloc: Connected src 48 to int 9 (cpu 0)
I (12995) IOT_USBH: USB Port=1 init succeed, fifo strategy=0
I (12995) IOT_USBH: USB Processing Start
I (12995) IOT_USBH: Waiting USB Connection
I (12999) IOT_USBH: Port power: ON Succeed
I (13004) IOT_USBH: usb port start succeed
I (13009) USB_HCDC: usbh cdc driver install succeed
I (13014) USB_HCDC: Waiting CDC Device Connection
I (13020) USB_HCDC: CDC task start
D (13024) IOT_USBH: urb alloced
D (13027) IOT_USBH: urb alloced
D (13030) IOT_USBH: urb alloced
D (13034) IOT_USBH: urb alloced
D (13037) IOT_USBH: urb alloced
D (13040) IOT_USBH: urb alloced
D (13043) IOT_USBH: urb alloced
D (13046) IOT_USBH: urb alloced
D (13050) IOT_USBH: urb alloced
D (13053) IOT_USBH: urb alloced
I (13254) IOT_USBH: line 131 PORT_EVENT_CONNECTION
I (13354) IOT_USBH: Resetting Port
I (13414) IOT_USBH: Port reset succeed
I (13414) IOT_USBH: Getting Port Speed
I (13414) IOT_USBH: USB Speed: full-speed
I (13416) IOT_USBH: Pipe init succeed, addr: 00
D (13421) IOT_USBH: urb alloced
I (13424) IOT_USBH: Set Device Addr = 1
D (13429) IOT_USBH: line 162 Pipe: default PIPE_EVENT_URB_DONE
I (13435) IOT_USBH: Set Device Addr Done
D (13439) IOT_USBH: urb free
D (13442) IOT_USBH: urb alloced
I (13446) IOT_USBH: get device desc
D (13450) IOT_USBH: line 162 Pipe: default PIPE_EVENT_URB_DONE
I (13456) IOT_USBH: get device desc, actual_num_bytes:26
*** Device descriptor ***
bLength 18
bDescriptorType 1
bcdUSB 2.00
bDeviceClass 0xef
bDeviceSubClass 0x2
bDeviceProtocol 0x1
bMaxPacketSize0 64
idVendor 0x2c7c
idProduct 0x6002
bcdDevice 3.10
iManufacturer 1
iProduct 2
iSerialNumber 3
bNumConfigurations 1
D (13485) IOT_USBH: urb free
D (13488) IOT_USBH: urb alloced
I (13491) IOT_USBH: get short config desc
D (13496) IOT_USBH: line 162 Pipe: default PIPE_EVENT_URB_DONE
I (13502) IOT_USBH: get config desc, actual_num_bytes:16
I (13508) IOT_USBH: get full config desc
D (13513) IOT_USBH: line 162 Pipe: default PIPE_EVENT_URB_DONE
I (13518) IOT_USBH: get full config desc, actual_num_bytes:217
*** Configuration descriptor ***
bLength 9
bDescriptorType 2
wTotalLength 209
bNumInterfaces 5
bConfigurationValue 1
iConfiguration 0
bmAttributes 0xe0
bMaxPower 500mA
*** Interface Association Descriptor ***
bLength 8
bDescriptorType 11
bFirstInterface 0
bInterfaceCount 2
bFunctionClass 0x2
bFunctionSubClass 0x6
bFunctionProtocol 0x0
iFunction 5
*** Interface descriptor ***
bLength 9
bDescriptorType 4
bInterfaceNumber 0
bAlternateSetting 0
bNumEndpoints 1
bInterfaceClass 0x2
bInterfaceSubClass 0x6
bInterfaceProtocol 0x0
iInterface 5
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x87 EP 7 IN
bmAttributes 0x3 INT
wMaxPacketSize 64
bInterval 16
*** Interface descriptor ***
bLength 9
bDescriptorType 4
bInterfaceNumber 1
bAlternateSetting 0
bNumEndpoints 0
bInterfaceClass 0xa
bInterfaceSubClass 0x0
bInterfaceProtocol 0x0
iInterface 5
*** Interface descriptor ***
bLength 9
bDescriptorType 4
bInterfaceNumber 1
bAlternateSetting 1
bNumEndpoints 2
bInterfaceClass 0xa
bInterfaceSubClass 0x0
bInterfaceProtocol 0x0
iInterface 0
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x83 EP 3 IN
bmAttributes 0x2 BULK
wMaxPacketSize 512
bInterval 0
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0xc EP 12 OUT
bmAttributes 0x2 BULK
wMaxPacketSize 512
bInterval 0
*** Interface descriptor ***
bLength 9
bDescriptorType 4
bInterfaceNumber 2
bAlternateSetting 0
bNumEndpoints 2
bInterfaceClass 0xff
bInterfaceSubClass 0x0
bInterfaceProtocol 0x0
iInterface 8
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x82 EP 2 IN
bmAttributes 0x2 BULK
wMaxPacketSize 64
bInterval 0
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0xb EP 11 OUT
bmAttributes 0x2 BULK
wMaxPacketSize 64
bInterval 0
*** Interface descriptor ***
bLength 9
bDescriptorType 4
bInterfaceNumber 3
bAlternateSetting 0
bNumEndpoints 3
bInterfaceClass 0xff
bInterfaceSubClass 0x0
bInterfaceProtocol 0x0
iInterface 11
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x89 EP 9 IN
bmAttributes 0x3 INT
wMaxPacketSize 64
bInterval 16
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x86 EP 6 IN
bmAttributes 0x2 BULK
wMaxPacketSize 64
bInterval 0
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0xf EP 15 OUT
bmAttributes 0x2 BULK
wMaxPacketSize 64
bInterval 0
*** Interface descriptor ***
bLength 9
bDescriptorType 4
bInterfaceNumber 4
bAlternateSetting 0
bNumEndpoints 3
bInterfaceClass 0xff
bInterfaceSubClass 0x0
bInterfaceProtocol 0x0
iInterface 11
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x88 EP 8 IN
bmAttributes 0x3 INT
wMaxPacketSize 64
bInterval 16
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0x81 EP 1 IN
bmAttributes 0x2 BULK
wMaxPacketSize 64
bInterval 0
*** Endpoint descriptor ***
bLength 7
bDescriptorType 5
bEndpointAddress 0xa EP 10 OUT
bmAttributes 0x2 BULK
wMaxPacketSize 64
bInterval 0
D (13819) IOT_USBH: urb free
D (13822) IOT_USBH: urb alloced
I (13826) IOT_USBH: Set Device Configuration = 1
D (13831) IOT_USBH: line 162 Pipe: default PIPE_EVENT_URB_DONE
I (13837) IOT_USBH: Set Device Configuration Done
D (13842) IOT_USBH: urb free
D (13845) event: no handlers have been registered for event MODEM_BOARD_EVENT:0 posted to loop 0x3ffd8fe8
D (13855) USB_HCDC: Creating bulk in pipe itf = 0, ep = 0x81,
I (13861) IOT_USBH: Pipe init succeed, addr: 81
D (13866) USB_HCDC: Creating bulk out pipe itf = 0, ep = 0x0A
I (13872) IOT_USBH: Pipe init succeed, addr: 0A
I (13877) USB_HCDC: CDC Device Connected
I (13882) esp-modem: --------- Modem PreDefined Info ------------------
I (13889) esp-modem: Model: EC600NCNLC-N06
I (13894) esp-modem: Modem itf: IN Addr:0x81, OUT Addr:0x0A
I (13900) esp-modem: ----------------------------------------------------
I (13907) gpio: GPIO[10]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (13917) gpio: GPIO[9]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
D (13926) esp_netif_objects: esp_netif_add_to_list 0x3ffeb4a8
D (13932) esp_netif_objects: esp_netif_add_to_list netif added successfully (total netifs: 1)
D (13941) esp-netif_lwip-ppp: esp_netif_new_ppp: PPP connection created: 0x3ffec418
D (13948) esp-netif_lwip-ppp: Phase Dead
D (13952) esp_netif_lwip: check: remote, if=0x0 fn=0x40119f8c
0x40119f8c: set_lwip_netif_callback at D:/Espressif/frameworks/esp-idf-v5.1.2/components/esp_netif/lwip/esp_netif_lwip.c:174

D (13958) esp_netif_lwip: call api in lwip: ret=0x0, give sem
dte ptr = 0x3ffdacdc
,esp_dte ptr = 0x3ffdacc0
,event_loop_hdl ptr = 0x3ffdba10
dte ptr = 0x3ffdacdc
,esp_dte ptr = 0x3ffdacc0
,event_loop_hdl ptr = 0x3ffdba10
dte ptr = 0x3ffdacdc
,esp_dte ptr = 0x3ffdacc0
,event_loop_hdl ptr = 0x3ffdba10
config.handler ptr = 0x3ffd5ffc
on_modem_event = 0x40091d34
0x40091d34: on_modem_event at D:/Espressif/esp-iot-solution/components/usb/iot_usbh_modem/src/usbh_modem_board.c:329

D (13991) modem_board: Handling bits = 0080, stage = 1, retry = 0
I (13997) modem_board: DTE reconnect, reconnecting ...

I (13980) USB_HCDC: rx0 flush -0 = 0
I (15003) modem_board: reconnect after 5s...
I (16003) modem_board: reconnect after 4s...
I (17003) modem_board: reconnect after 3s...
I (18003) modem_board: reconnect after 2s...
I (19003) modem_board: reconnect after 1s...
I (19003) modem_board: Modem state STAGE_SYNC, Start
D (19013) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:AT

D (19013) USB_HCDC: cdc write itf: 0, buf = 0x3f00aaf8, len = 3
D (19018) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 9, read = 9
D (19023) esp-modem: debug_data: 0x3ffdad28 41 54 0d 0d 0a 4f 4b 0d 0a |AT...OK..|
D (19032) esp_modem_dce: esp_modem_dce_generic_command(35): Command:AT
succeeded
D (19040) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:ATE0

D (19048) USB_HCDC: cdc write itf: 0, buf = 0x3f00ab04, len = 5
D (19056) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 11, read = 11
D (19061) esp-modem: debug_data: 0x3ffdad28 41 54 45 30 0d 0d 0a 4f 4b 0d 0a |ATE0...OK..|
D (19071) esp_modem_dce: esp_modem_dce_generic_command(35): Command:ATE0
succeeded
I (19078) modem_board: Network Auto reconnecting ...
D (19084) event: running post MODEM_BOARD_EVENT:7 with handler 0x400893a0 and context 0x3ffeaf1c on loop 0x3ffd8fe8
0x400893a0: on_modem_event at E:/Project/eps32/example_usb_cat1/usb_cat1/main/app_main.c:140

W (19095) 4g_main: Modem Board Event: Network disconnected
E (19101) led_indicator: ./managed_components/espressif__led_indicator/src/led_indicator.c:461 (led_indicator_stop):invalid p_handle
I (19113) modem_board: Modem state STAGE_SYNC, Success!
D (19219) modem_board: Handling bits = 0100, stage = 3, retry = 0
I (19219) modem_board: Modem state STAGE_CHECK_SIM, Start
D (19220) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:AT+CPIN?

D (19228) USB_HCDC: cdc write itf: 0, buf = 0x3f00ab58, len = 9
D (19237) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 22, read = 22
D (19241) esp-modem: debug_data: 0x3ffdad28 0d 0a 2b 43 50 49 4e 3a 20 52 45 41 44 59 0d 0a |..+CPIN: READY..|
D (19251) esp-modem: debug_data: 0x3ffdad38 0d 0a 4f 4b 0d 0a |..OK..|
D (19261) esp_modem_dce: esp_modem_dce_generic_command(35): Command:AT+CPIN?
succeeded
I (19269) modem_board: SIM Card Ready
D (19273) event: running post MODEM_BOARD_EVENT:2 with handler 0x400893a0 and context 0x3ffeaf1c on loop 0x3ffd8fe8
0x400893a0: on_modem_event at E:/Project/eps32/example_usb_cat1/usb_cat1/main/app_main.c:140

I (19284) 4g_main: Modem Board Event: SIM Card Connected
I (19290) modem_board: Modem state STAGE_CHECK_SIM, Success!
D (19396) modem_board: Handling bits = 0100, stage = 4, retry = 0
I (19396) modem_board: Modem state STAGE_CHECK_SIGNAL, Start
D (19397) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:AT+CSQ

D (19405) USB_HCDC: cdc write itf: 0, buf = 0x3f00aba0, len = 7
D (19413) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 21, read = 21
D (19418) esp-modem: debug_data: 0x3ffdad28 0d 0a 2b 43 53 51 3a 20 32 32 2c 39 39 0d 0a 0d |..+CSQ: 22,99...|
D (19428) esp-modem: debug_data: 0x3ffdad38 0a 4f 4b 0d 0a |.OK..|
D (19438) esp_modem_dce: esp_modem_dce_generic_command(35): Command:AT+CSQ
succeeded
I (19446) modem_board: Signal quality: rssi=22, ber=99
I (19452) modem_board: Modem state STAGE_CHECK_SIGNAL, Success!
D (19558) modem_board: Handling bits = 0100, stage = 5, retry = 0
I (19558) modem_board: Modem state STAGE_CHECK_REGIST, Start
D (19559) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:AT+COPS?

D (19567) USB_HCDC: cdc write itf: 0, buf = 0x3f00ab30, len = 9
D (19576) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 37, read = 37
D (19580) esp-modem: debug_data: 0x3ffdad28 0d 0a 2b 43 4f 50 53 3a 20 30 2c 30 2c 22 43 48 |..+COPS: 0,0,"CH|
D (19591) esp-modem: debug_data: 0x3ffdad38 49 4e 41 20 4d 4f 42 49 4c 45 22 2c 37 0d 0a 0d |INA MOBILE",7...|
D (19601) esp-modem: debug_data: 0x3ffdad48 0a 4f 4b 0d 0a |.OK..|
D (19611) esp_modem_dce: esp_modem_dce_generic_command(35): Command:AT+COPS?
succeeded
I (19619) modem_board: Network registed, Operator: "CHINA MOBILE"
I (19625) modem_board: Modem state STAGE_CHECK_REGIST, Success!
D (19732) modem_board: Handling bits = 0100, stage = 6, retry = 0
I (19732) modem_board: Modem state STAGE_START_PPP, Start
D (19733) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:AT+CGDCONT=1,"IP","cmnet"

D (19742) USB_HCDC: cdc write itf: 0, buf = 0x3ffeaf3c, len = 26
D (19753) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 6, read = 6
D (19755) esp-modem: debug_data: 0x3ffdad28 0d 0a 4f 4b 0d 0a |..OK..|
D (19765) esp_modem_dce: esp_modem_dce_generic_command(35): Command:AT+CGDCONT=1,"IP","cmnet"
succeeded
D (19775) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:ATD*99#

D (19782) USB_HCDC: cdc write itf: 0, buf = 0x3f00abb0, len = 8
D (19791) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 11, read = 11
D (19796) esp-modem: debug_data: 0x3ffdad28 0d 0a 43 4f 4e 4e 45 43 54 0d 0a |..CONNECT..|
D (19805) esp_modem_dce: esp_modem_dce_generic_command(35): Command:ATD*99#
succeeded
I (19814) modem_board: Modem state STAGE_START_PPP, Success!
D (19815) event: running post ESP_MODEM_EVENT:0 with handler 0x400b3208 and context 0x3ffeb370 on loop 0x3ffdba10
0x400b3208: esp_netif_action_start at D:/Espressif/frameworks/esp-idf-v5.1.2/components/esp_netif/esp_netif_handlers.c:24

D (19830) esp_netif_handlers: esp_netif action has started with netif0x3ffeb4a8 from event_id=0
D (19839) esp-netif_lwip-ppp: esp_netif_start_ppp: Starting PPP connection: 0x3ffec418
D (19847) esp-netif_lwip-ppp: Phase Start
D (19851) event: running post NETIF_PPP_STATUS:259 with handler 0x400949e8 and context 0x3ffeae80 on loop 0x3ffd8fe8
0x400949e8: on_ppp_changed at D:/Espressif/esp-iot-solution/components/usb/iot_usbh_modem/src/esp_modem_netif.c:73

D (19862) esp-netif_lwip-ppp: Phase Establish
D (19866) event: running post NETIF_PPP_STATUS:262 with handler 0x400949e8 and context 0x3ffeae80 on loop 0x3ffd8fe8
0x400949e8: on_ppp_changed at D:/Espressif/esp-iot-solution/components/usb/iot_usbh_modem/src/esp_modem_netif.c:73

D (19877) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 46
D (19883) esp_netif_lwip: check: remote, if=0x3ffeb4a8 fn=0x4011ab6c
0x4011ab6c: esp_netif_update_default_netif_lwip at D:/Espressif/frameworks/esp-idf-v5.1.2/components/esp_netif/lwip/esp_netif_lwip.c:327

D (19889) esp_netif_lwip: esp_netif_update_default_netif_lwip 0x3ffeb4a8
D (19896) esp_netif_lwip: call api in lwip: ret=0x0, give sem
D (19902) event: running post ESP_MODEM_EVENT:0 with handler 0x40091d34 and context 0x3ffeaeb4 on loop 0x3ffdba10
0x40091d34: on_modem_event at D:/Espressif/esp-iot-solution/components/usb/iot_usbh_modem/src/usbh_modem_board.c:329

W (19912) modem_board: Modem event! 0
D (19920) modem_board: Handling bits = 0100, stage = 7, retry = 0
I (19923) modem_board: Modem state STAGE_WAIT_IP, Start
D (25883) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 46
D (31883) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 46
D (37883) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 46
D (43883) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 46
D (49883) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 46
D (55883) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 46
D (61883) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 46
D (67883) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 46
D (73883) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 46
D (79883) esp-netif_lwip-ppp: Phase Disconnect
D (79883) event: running post NETIF_PPP_STATUS:268 with handler 0x400949e8 and context 0x3ffeae80 on loop 0x3ffd8fe8
0x400949e8: on_ppp_changed at D:/Espressif/esp-iot-solution/components/usb/iot_usbh_modem/src/esp_modem_netif.c:73

D (79887) esp-netif_lwip-ppp: Phase Dead
D (79891) event: running post NETIF_PPP_STATUS:256 with handler 0x400949e8 and context 0x3ffeae80 on loop 0x3ffd8fe8
0x400949e8: on_ppp_changed at D:/Espressif/esp-iot-solution/components/usb/iot_usbh_modem/src/esp_modem_netif.c:73

I (79901) esp-netif_lwip-ppp: Connection lost
D (79906) event: running post IP_EVENT:7 with handler 0x400b3398 and context 0x3ffeae4c on loop 0x3ffd8fe8
0x400b3398: esp_netif_action_disconnected at D:/Espressif/frameworks/esp-idf-v5.1.2/components/esp_netif/esp_netif_handlers.c:83

D (79916) esp_netif_handlers: esp_netif action disconnected with netif0x3ffeb4a8 from event_id=7
D (79925) esp_netif_lwip: check: remote, if=0x3ffeb4a8 fn=0x4011a970
0x4011a970: esp_netif_down_api at D:/Espressif/frameworks/esp-idf-v5.1.2/components/esp_netif/lwip/esp_netif_lwip.c:1638

D (79932) esp_netif_lwip: esp_netif_down_api esp_netif:0x3ffeb4a8
D (79938) esp_netif_lwip: esp_netif_internal_dhcpc_cb lwip-netif:0x3ffeb52c
D (79945) esp_netif_lwip: check: local, if=0x3ffeb4a8 fn=0x4011ab6c
0x4011ab6c: esp_netif_update_default_netif_lwip at D:/Espressif/frameworks/esp-idf-v5.1.2/components/esp_netif/lwip/esp_netif_lwip.c:327

D (79951) esp_netif_lwip: esp_netif_update_default_netif_lwip 0x3ffeb4a8
D (79958) esp_netif_lwip: call api in lwip: ret=0x0, give sem
D (79964) event: running post IP_EVENT:7 with handler 0x40091d34 and context 0x3ffeaee8 on loop 0x3ffd8fe8
0x40091d34: on_modem_event at D:/Espressif/esp-iot-solution/components/usb/iot_usbh_modem/src/usbh_modem_board.c:329

I (79974) modem_board: IP event! 7
I (79978) modem_board: Modem Disconnect from PPP Server
W (79984) modem_board: Modem Got IP timeout, retry from start!
D (79990) modem_board: Handling bits = 0120, stage = 7, retry = 5
E (79996) modem_board: Modem state STAGE_WAIT_IP, retry 5, timeout !
W (80003) modem_board: Retry From Start !
I (80008) modem_board: Modem state STAGE_SYNC, Start
D (80024) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:AT

W (80024) esp-modem-dte: Not support sending command in ppp mode, please exit ppp mode first
response timeoutem_dce: esp_modem_dce_generic_command(26): Command:AT
I (80049) modem_board: Current timeouts: 1 and errors: 0
W (80049) modem_board: Try to exit ppp mode........
D (80051) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:+++

D (80058) USB_HCDC: cdc write itf: 0, buf = 0x3f00abc4, len = 3
E (90064) esp-modem-dte: esp_modem_dte_send_cmd(272): process command timeout
W (90064) esp_modem_dce: esp_modem_dce_generic_command(26): Command:+++ response timeout
D (90069) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:AT

D (90077) USB_HCDC: cdc write itf: 0, buf = 0x3f00aaf8, len = 3
D (90085) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 6, read = 6
D (90090) esp-modem: debug_data: 0x3ffdad28 0d 0a 4f 4b 0d 0a |..OK..|
D (90099) esp_modem_dce: esp_modem_dce_generic_command(35): Command:AT
succeeded
D (90107) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:ATE0

D (90114) USB_HCDC: cdc write itf: 0, buf = 0x3f00ab04, len = 5
D (90123) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 6, read = 6
D (90127) esp-modem: debug_data: 0x3ffdad28 0d 0a 4f 4b 0d 0a |..OK..|
D (90137) esp_modem_dce: esp_modem_dce_generic_command(35): Command:ATE0
succeeded
I (90145) modem_board: Network Auto reconnecting ...
D (90150) event: running post MODEM_BOARD_EVENT:7 with handler 0x400893a0 and context 0x3ffeaf1c on loop 0x3ffd8fe8
0x400893a0: on_modem_event at E:/Project/eps32/example_usb_cat1/usb_cat1/main/app_main.c:140

W (90161) 4g_main: Modem Board Event: Network disconnected
E (90167) led_indicator: ./managed_components/espressif__led_indicator/src/led_indicator.c:461 (led_indicator_stop):invalid p_handle
I (90180) modem_board: Modem state STAGE_SYNC, Success!
D (90285) modem_board: Handling bits = 0120, stage = 3, retry = 0
I (90285) modem_board: Modem state STAGE_CHECK_SIM, Start
D (90286) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:AT+CPIN?

D (90294) USB_HCDC: cdc write itf: 0, buf = 0x3f00ab58, len = 9
D (90302) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 22, read = 22
D (90307) esp-modem: debug_data: 0x3ffdad28 0d 0a 2b 43 50 49 4e 3a 20 52 45 41 44 59 0d 0a |..+CPIN: READY..|
D (90317) esp-modem: debug_data: 0x3ffdad38 0d 0a 4f 4b 0d 0a |..OK..|
D (90327) esp_modem_dce: esp_modem_dce_generic_command(35): Command:AT+CPIN?
succeeded
I (90335) modem_board: SIM Card Ready
D (90339) event: running post MODEM_BOARD_EVENT:2 with handler 0x400893a0 and context 0x3ffeaf1c on loop 0x3ffd8fe8
0x400893a0: on_modem_event at E:/Project/eps32/example_usb_cat1/usb_cat1/main/app_main.c:140

I (90350) 4g_main: Modem Board Event: SIM Card Connected
I (90356) modem_board: Modem state STAGE_CHECK_SIM, Success!
D (90462) modem_board: Handling bits = 0120, stage = 4, retry = 0
I (90462) modem_board: Modem state STAGE_CHECK_SIGNAL, Start
D (90463) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:AT+CSQ

D (90471) USB_HCDC: cdc write itf: 0, buf = 0x3f00aba0, len = 7
D (90479) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 21, read = 21
D (90484) esp-modem: debug_data: 0x3ffdad28 0d 0a 2b 43 53 51 3a 20 32 34 2c 39 39 0d 0a 0d |..+CSQ: 24,99...|
D (90494) esp-modem: debug_data: 0x3ffdad38 0a 4f 4b 0d 0a |.OK..|
D (90504) esp_modem_dce: esp_modem_dce_generic_command(35): Command:AT+CSQ
succeeded
I (90512) modem_board: Signal quality: rssi=24, ber=99
I (90518) modem_board: Modem state STAGE_CHECK_SIGNAL, Success!
D (90624) modem_board: Handling bits = 0120, stage = 5, retry = 0
I (90624) modem_board: Modem state STAGE_CHECK_REGIST, Start
D (90625) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:AT+COPS?

D (90633) USB_HCDC: cdc write itf: 0, buf = 0x3f00ab30, len = 9
D (90642) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 37, read = 37
D (90646) esp-modem: debug_data: 0x3ffdad28 0d 0a 2b 43 4f 50 53 3a 20 30 2c 30 2c 22 43 48 |..+COPS: 0,0,"CH|
D (90657) esp-modem: debug_data: 0x3ffdad38 49 4e 41 20 4d 4f 42 49 4c 45 22 2c 37 0d 0a 0d |INA MOBILE",7...|
D (90667) esp-modem: debug_data: 0x3ffdad48 0a 4f 4b 0d 0a |.OK..|
D (90677) esp_modem_dce: esp_modem_dce_generic_command(35): Command:AT+COPS?
succeeded
I (90685) modem_board: Network registed, Operator: "CHINA MOBILE"
I (90691) modem_board: Modem state STAGE_CHECK_REGIST, Success!
D (90798) modem_board: Handling bits = 0120, stage = 6, retry = 0
I (90798) modem_board: Modem state STAGE_START_PPP, Start
D (90799) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:AT+CGDCONT=1,"IP","cmnet"

D (90808) USB_HCDC: cdc write itf: 0, buf = 0x3ffed6a0, len = 26
D (90819) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 6, read = 6
D (90821) esp-modem: debug_data: 0x3ffdad28 0d 0a 4f 4b 0d 0a |..OK..|
D (90831) esp_modem_dce: esp_modem_dce_generic_command(35): Command:AT+CGDCONT=1,"IP","cmnet"
succeeded
D (90841) esp_modem_dce: esp_modem_dce_generic_command(20): Sending command:ATD*99#

D (90849) USB_HCDC: cdc write itf: 0, buf = 0x3f00abb0, len = 8
D (90857) USB_HCDC: cdc read itf: 0, buf = 0x3ffdad28, len = 11, read = 11
D (90862) esp-modem: debug_data: 0x3ffdad28 0d 0a 43 4f 4e 4e 45 43 54 0d 0a |..CONNECT..|
D (90872) esp_modem_dce: esp_modem_dce_generic_command(35): Command:ATD*99#
succeeded
I (90880) modem_board: Modem state STAGE_START_PPP, Success!
D (90881) event: running post ESP_MODEM_EVENT:0 with handler 0x400b3208 and context 0x3ffeb370 on loop 0x3ffdba10
0x400b3208: esp_netif_action_start at D:/Espressif/frameworks/esp-idf-v5.1.2/components/esp_netif/esp_netif_handlers.c:24

D (90896) esp_netif_handlers: esp_netif action has started with netif0x3ffeb4a8 from event_id=0
D (90905) esp-netif_lwip-ppp: esp_netif_start_ppp: Starting PPP connection: 0x3ffec418
D (90913) esp-netif_lwip-ppp: Phase Start
D (90917) event: running post NETIF_PPP_STATUS:259 with handler 0x400949e8 and context 0x3ffeae80 on loop 0x3ffd8fe8
0x400949e8: on_ppp_changed at D:/Espressif/esp-iot-solution/components/usb/iot_usbh_modem/src/esp_modem_netif.c:73

D (90928) esp-netif_lwip-ppp: Phase Establish
D (90932) event: running post NETIF_PPP_STATUS:262 with handler 0x400949e8 and context 0x3ffeae80 on loop 0x3ffd8fe8
0x400949e8: on_ppp_changed at D:/Espressif/esp-iot-solution/components/usb/iot_usbh_modem/src/esp_modem_netif.c:73

D (90943) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 47
D (90949) esp_netif_lwip: check: remote, if=0x3ffeb4a8 fn=0x4011ab6c
0x4011ab6c: esp_netif_update_default_netif_lwip at D:/Espressif/frameworks/esp-idf-v5.1.2/components/esp_netif/lwip/esp_netif_lwip.c:327

D (90955) esp_netif_lwip: esp_netif_update_default_netif_lwip 0x3ffeb4a8
D (90962) esp_netif_lwip: call api in lwip: ret=0x0, give sem
D (90968) event: running post ESP_MODEM_EVENT:0 with handler 0x40091d34 and context 0x3ffeaeb4 on loop 0x3ffdba10
0x40091d34: on_modem_event at D:/Espressif/esp-iot-solution/components/usb/iot_usbh_modem/src/usbh_modem_board.c:329

W (90978) modem_board: Modem event! 0
D (90986) modem_board: Handling bits = 0120, stage = 7, retry = 0
I (90989) modem_board: Modem state STAGE_WAIT_IP, Start
D (96949) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 47
D (102949) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 47
D (108949) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 47
D (114949) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 47
D (120949) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 47
D (126949) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 47
D (132949) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 47
D (138949) USB_HCDC: cdc write itf: 0, buf = 0x3ffedc9c, len = 47**

[tyrael-cui]

问题已经解决，所用的CAT1模块不支持PPP协议，但仍然在拨号命令时，返回connect，导致程序等待ip.