[lwip-users] ppp modem/gprs debug

[Felix Nielsen]

Hi All,

After some help from Michael Broughton, I finally got started with some 
lwip/PPP testing :)

I have enabled debug info, but no connect seems to be successful, below 
is a dump of the debug session, if anyone have a hint or two I would  
appreciate it.

Felix

AIM target
Begin
isp_test
netif_set_ipaddr: netif address being changed
netif: IP address of interface  set to 127.0.0.1
netif: netmask of interface  set to 255.0.0.0
netif: GW address of interface  set to 127.0.0.1
netif: added interface lo IP addr 127.0.0.1 netmask 255.0.0.0 gw 127.0.0.1
lcp_init: xmit_accm=0 0 0 0
serial:/dev/ser1
dialing...13
done
lcp_init: xmit_accm=0 0 0 0
pbuf_alloc(length=1504)
pbuf_alloc(length=1504) == 0x0006e970
pppMain(new): unit 0: Connecting
tcpip_thread: CALLBACK 0x000701c4
pppStartCB: unit 0
ppp_set_xaccm[0]: outACCM=0 0 0 0
ppp_send_config[0]: outACCM=FF FF FF FF
ppp_recv_config[0]: inACCM=0 0 0 0
lcp_lowerup: asyncmap=0 0 0 0
LCP: lowerup state 0 -> 2
auth_reset: 0
lcp_addci: L opt=2 0
lcp_addci: L opt=5 44101852
lcp_addci: opt=7
lcp_addci: opt=8
pbuf_alloc(length=0)
pbuf_alloc: allocated pbuf 0x000702a0
pbuf_alloc(length=0) == 0x000702a0
pppWrite[0]: len=47
pbuf_free(0x000702a0)
pbuf_free: deallocating 0x000702a0
fsm_sdata(LCP): Sent code 1,1,20.
LCP: sending Configure-Request, id 1
LCP: open state 2 -> 6
pppInProc[0]: got 72 bytes
pbuf_alloc(length=0)
pbuf_alloc: allocated pbuf 0x000702a0
pbuf_alloc(length=0) == 0x000702a0
tcpip_thread: CALLBACK 0x000701c4
pbuf_header: old 0x000702b0 new 0x000702b8 (-8)
pppInput[0]: LCP len=8
fsm_input(LCP):4,1,8
fsm_rconfnakrej(LCP): Rcvd id 1 state=6
lcp_rejci: void opt 7 rejected
lcp_rejci: void opt 8 rejected
lcp_addci: L opt=2 0
lcp_addci: L opt=5 44101852
pbuf_alloc(length=0)
pbuf_alloc: allocated pbuf 0x000706b0
pbuf_alloc(length=0) == 0x000706b0
pppWrite[0]: len=38
pbuf_free(0x000706b0)
pbuf_free: deallocating 0x000706b0
fsm_sdata(LCP): Sent code 1,2,16.
LCP: sending Configure-Request, id 2
pbuf_free(0x000702a0)
pbuf_free: deallocating 0x000702a0
pbuf_alloc(length=0)
pbuf_alloc: allocated pbuf 0x000702a0
pbuf_alloc(length=0) == 0x000702a0
pppInProc[0]: got 65 bytes
tcpip_thread: CALLBACK 0x000701c4
pbuf_header: old 0x000702b0 new 0x000702b8 (-8)
pppInput[0]: LCP len=20
fsm_input(LCP):1,1,20
fsm_rconfreq(LCP): Rcvd id 1 state=6
lcp_reqci: rcvd ASYNCMAP=A0000 PAP (C023) MAGICNUMBER (10270000)
lcp_reqci: returning CONFACK.
pbuf_alloc(length=0)
pbuf_alloc: allocated pbuf 0x000706b0
pbuf_alloc(length=0) == 0x000706b0
pppWrite[0]: len=45
pbuf_free(0x000706b0)
pbuf_free: deallocating 0x000706b0
fsm_sdata(LCP): Sent code 2,1,20.
pbuf_free(0x000702a0)
pbuf_free: deallocating 0x000702a0
pbuf_alloc(length=0)
pbuf_alloc: allocated pbuf 0x000702a0
pbuf_alloc(length=0) == 0x000702a0
tcpip_thread: CALLBACK 0x000701c4
pbuf_header: old 0x000702b0 new 0x000702b8 (-8)
pppInput[0]: LCP len=16
fsm_input(LCP):2,2,16
fsm_rconfack(LCP): Rcvd id 2 state=8
lcp_acki: Ack
ppp_send_config[0]: outACCM=0 0 A 0
ppp_recv_config[0]: inACCM=0 0 0 0
link_established: 0
IPCP: lowerup state 0 -> 2
pbuf_alloc(length=0)
pbuf_alloc: allocated pbuf 0x000706b0
pbuf_alloc(length=0) == 0x000706b0
pppWrite[0]: len=29
pbuf_free(0x000706b0)
pbuf_free: deallocating 0x000706b0
fsm_sdata(IPCP): Sent code 1,1,22.
IPCP: sending Configure-Request, id 1
IPCP: open state 2 -> 6
pbuf_free(0x000702a0)
pbuf_free: deallocating 0x000702a0
pppMainWakeup: unit 0 sio_read len=1504 returned 0
pppMainWakeup: unit 0 sio_read len=1504 returned 0
pppMainWakeup: unit 0 sio_read len=1504 returned 0
pppMainWakeup: unit 0 sio_read len=1504 returned 0
pppMainWakeup: unit 0 sio_read len=1504 returned 0