[lwip-users] R: Re: R: Re: Unable to connect to HTTP server using PPP

Hi Sylvain, thanks again for your help.

I changed some parts of my code and the fcs error disappeared, but I'm still having the same problem (unable to close the TCP connection).

I'm sharing the functions that I use for communicating with the SIM5320A, the debug output when trying to connect with the http server and trying to ping the server.

Regards,

Renato

----lwip.c (Output callback function)-----

u32_t output_cb(ppp_pcb *pcb, u8_t *data, u32_t len, void *ctx){
if (HAL_UART_Transmit(&huart2, data, len, HAL_MAX_DELAY) == HAL_OK){
return len;
}else{
return 0;
}
}

----On my main function-------

while (1){
if(send){
send = 0;
pppos_input(ppp, recBuffer, recIndex);

recIndex = 0;
memset(recBuffer, 0, sizeof(recBuffer));
}
sys_check_timeouts();
}

/*
* Receive complete callback function
*/

void HAL_UART_RxCpltCallback(UART_HandleTypeDef *UartHandle)
{
if (UartHandle->Instance == USART2)
{
//callback reset
HAL_UART_Receive_IT(&huart2, &recData, 1);

//receive buffer overflow check
if(recIndex >= RX_SIZE) recIndex = 0;

//This variables are used to control if it's ok to send data to pppos_input()
dataReceived = 1;
sysTickCounter = 0;
send = 0;

recBuffer[recIndex++] = recData;
}
}

/* Callback function used to send the received bytes to pppos_input()
*
* If 10ms have passed after the last received byte, send = 1 and we send recBuffer to pppos_input()
*/
void HAL_SYSTICK_Callback(void)
{
if(dataReceived){
sysTickCounter++;
if (sysTickCounter >= 10){
send = 1;
dataReceived = 0;
sysTickCounter = 0;
}
}
}

-----lwipopts.h------------

#define DEBUG_RH 1
#define DEBUG_SP 0
#define SEMIHOSTING 0

#define LWIP_DEBUG 1

#define NETIF_DEBUG LWIP_DBG_ON
#define HTTPD_DEBUG LWIP_DBG_ON
#define TCP_DEBUG LWIP_DBG_ON
#define PPP_DEBUG LWIP_DBG_ON
#define PRINTPKT_SUPPORT 1
#define PPP_PROTOCOLNAME 1

/*PPP*/
#define PPP_USE_PBUF_RAM 0

#define PPP_NUM_TIMEOUTS_PER_PCB (1 + PPP_IPV4_SUPPORT + PPP_IPV6_SUPPORT + CCP_SUPPORT)

#define VJ_SUPPORT 0
#define CCP_SUPPORT 0
#define EAP_SUPPORT 0

#define PPP_MRU 1500
#define PPP_DEFMRU 1500
#define PPP_MAXMRU 1500
#define PPP_MINMRU 128

/*ICMP*/
#define IP_DEFAULT_TTL 255

#define MEMP_NUM_PBUF 120
#define MEMP_NUM_TCP_PCB 40

/*TCP*/
#define LWIP_TCP_SACK_OUT 1
#define TCP_TTL 255
#define TCP_MAXRTX 12
#define TCP_SYNMAXRTX 6
#define TCP_MSS 1460
#define TCP_WND (4 * TCP_MSS)
#define TCP_SND_BUF (4 * TCP_MSS)

//#define TCP_SND_QUEUELEN ((4 * (TCP_SND_BUF) + (TCP_MSS - 1))/(TCP_MSS))
//#define MEMP_NUM_TCP_SEG 16

/*HTTP*/
//#define LWIP_HTTPD_SUPPORT_REQUESTLIST 1
//#define LWIP_HTTPD_SUPPORT_11_KEEPALIVE 1
#define HTTPD_MAX_RETRIES 15
#define HTTPD_POLL_INTERVAL 6
#define HTTPD_TCP_PRIO TCP_PRIO_MIN
//#define LWIP_HTTPD_REQ_QUEUELEN 15
//#define LWIP_HTTPD_MAX_REQ_LENGTH LWIP_MIN(1023, (LWIP_HTTPD_REQ_QUEUELEN * PBUF_POOL_BUFSIZE))
//#define HTTPD_MAX_WRITE_LEN(pcb) ((u16_t)(3 * altcp_mss(pcb)))

-----Trying to ping the server------

------Trying to connect to the server---------

Sending AT+CGATT=0
SIM5320A: OK

Sending ATD*99***1#
SIM5320A: CONNECT 115200

netif: netmask of interface netif: setting default interface pp
ppp_connect[0]: holdoff=3
ppp phase changed[0]: phase=2
httpd_init
tcp_bind: bind to port 80
ppp phase changed[0]: phase=3
pppos_connect: unit 0: connecting
ppp_start[0]
ppp phase changed[0]: phase=6
pppos_send_config[0]: out_accm=FF FF FF FF
ppp_send_config[0]
pppos_recv_config[0]: in_accm=FF FF FF FF
ppp_recv_config[0]
ppp: auth protocols: CHAP=1 CHAP_MD5=1
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xf7edb889> <pcomp> accomp>]
pppos_write[0]: len=24
ppp_start[0]: finished
pppos_input[0]: got 101 bytes
rcvd [LCP ConfReq id=0x0 <asyncmap 0x0> <auth chap MD5> <magic 0x12c1b02> <pcomp> <accomp>]
sent [LCP ConfAck id=0x0 <asyncmap 0x0> <auth chap MD5> <magic 0x12c1b02> <pcomp> <accomp>]
pppos_write[0]: len=29
rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xf7edb889> <pcomp> <accomp>]
netif_set_mtu[0]: mtu=1500
pppos_send_config[0]: out_accm=0 0 0 0
ppp_send_config[0]
pppos_recv_config[0]: in_accm=0 0 0 0
ppp_recv_config[0]
ppp phase changed[0]: phase=7
pppos_input[0]: got 67 bytes
rcvd [LCP DiscReq id=0x1 magic=0x12c1b02]
rcvd [CHAP Challenge id=0x1 <c4a6b4b48aea30c0986c76cb82d7d3cb>, name = "UMTS_CHAP_SRVR"]
No CHAP secret found for authenticating us to UMTS_CHAP_SRVR
sent [CHAP Response id=0x1 <3724df7149bafb35d11c9348e92fdb88>, name = ""]
pppos_write[0]: len=25
pppos_input[0]: got 10 bytes
rcvd [CHAP Success id=0x1 ""]
CHAP authentication succeeded
CHAP authentication succeeded
ppp phase changed[0]: phase=9
sent [IPCP CnfReq id=0x1 <addr 0.0.0.0>]
pppos_write[0]: len=14
pppos_input[0]: got 34 bytes
rcvd [IPCP ConfNak id=0x1 < 81 06 0a 0b 0c 0d> < 83 06 0a 0b 0c 0e> < 82 06 0a 0b 0c 0d> < 84 06 0a 0b 0c 0e>]
sent [IPCP ConfReq id=0x2 <addr 0.0.0.0>]
pppos_write[0]: len=14
pppos_input[0]: got 34 bytes
rcvd [IPCP ConfNak id=0x2 < 81 06 0a 0b 0c 0d> < 83 06 0a 0b 0c 0e> < 82 06 0a 0b 0c 0d> < 84 06 0a 0b 0c 0e>]
sent [IPCP ConfReq id=0x3 <addr 0.0.0.0>]
pppos_write[0]: len=14
pppos_input[0]: got 26 bytes
rcvd [IPCP ConfReq id=0x0]
sent [IPCP ConfNak id=0x0 <addr 0.0.0.0>]
pppos_write[0]: len=14
rcvd [IPCP ConfNak id=0x3 <addr 10.2.237.55>]
sent [IPCP ConfReq id=0x4 <addr 10.2.237.55>]
pppos_write[0]: len=14
pppos_input[0]: got 26 bytes
rcvd [IPCP ConfReq id=0x1]
sent [IPCP ConfAck id=0x1]
pppos_write[0]: len=8
rcvd [IPCP ConfAck id=0x4 <addr 10.2.237.55>]
Could not determine remote IP address: defaulting to 10.64.64.64
netif: GW address of interface pp set to 10.64.64.64
netif_set_iaddr: netif address being changed
sifup[0]: err_code=0
status_cb: Connected
our_ipaddr = 10.2.237.55
gatewayaddr = 10.64.64.64
netmask = 255.255.255.255
local IP address 10.2.237.55
remote IP address 10.64.64.64
ppp phase changed[0]: phase=10
pppos_input[0]: got 174 bytes
ppp_input[0]: ip in pbuf len=52
TCP connection request 54133 -> 8.
pppos_netif_output[0]: proto=0x21, len = 48
ppp_input[0]: ip in pbuf len=52
TCP connection request 54134 -> 80.
pppos_netif_output[0]: proto=0x21, len = 48
ppp_input[0]: ip in pbuf len=52
TCP connection request 54135 -> 80.
pppos_netif_output[0]: proto=0x21, len = 48
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
pppos_input[0]: got 46 bytes
ppp_input[0]: ip in pbuf len=40
TCP connection established 54133 -> 80.
http_accept 0x2000a8a8 / 0
pppos_input[0]: got 46 bytes
ppp_input[0]: ip in pbuf len=40
TCP connection established 54134 -> 80.
http_accept 0x2000a7ec / 0
pppos_input[0]: got 46 bytes
ppp_input[0]: ip in pbuf len=40
TCP connection established 54135 -> 80.
http_accept 0x2000a730 / 0
pppos_input[0]: got 92 bytes
ppp_input[0]: ip in pbuf len=40
ppp_input[0]: ip in pbuf len=40
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing a t ive pcb
tcp_slowtmr: processing active pcb
pppos_input[0]: got 687 bytes
ppp_input[0]: ip in pbuf len=497
http_recv: pcb=0x2000a8a8 pbuf=0x20007b08 err=Ok.
tcp_recved: received 457 bytes, wnd 5840 (0).
Received 457 bytes
First pbuf
CRLF received, parsing request
Received GET request"
Received "GET" request for URI: "/"
Looking for /index.shtml...
Looking for /index.ssi...
Looking for /index.shtm...
Looking for /index.html...
Opened.
http_recv: data 0x80217a8 len 695
http_send: pcb=0x2000a8a8 hs=0x20001abc left=695
Trying go send 695 bytes
Sent 695 bytes
End of file.
Closing connection 0x2000a8a8
tcp_close: closing in State: ESTABLISHED
pppos_netif_output[0]: proto=0x21, len = 735
ppp_input[0]: ip in pbuf len=40
ppp_input[0]: ip in pbuf len=40
ppp_input[0]: ip in pbuf len=40
ppp_input[0]: ip in pbuf len=40
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: p ocessing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
http_poll: pcb=0x2000a730 hs=0x20001c24 pcb_state=ESTABLISHED
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
http_poll: pcb=0x2000a7ec hs=0x20001b70 pcb_state=ESTABLISHED
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
pppos_netif_output[0]: proto=0x21, len = 735
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pc
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
http_poll: pcb=0x2000a730 hs=0x20001c24 pcb_state=ESTABLISHED
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
http_poll: pcb=0x2000a7ec hs=0x20001b70 pcb_state=ESTABLISHED
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processingactive pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
http_poll: pcb=0x2000a730 hs=0x20001c24 pcb_state=ESTABLISHED
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
http_poll: pcb=0x2000a7ec hs=0x20001b70 pcb_state=ESTABLISHED
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
pppos_netif_output[0]: proto=0x21, len = 735
tcp_slotmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
http_poll: pcb=0x2000a730 hs=0x20001c24 pcb_state=ESTABLISHED
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
http_poll: pcb=0x2000a7ec hs=0x20001b70 pcb_state=ESTABLISHED
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active p b
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
pppos_input[0]: got 46 bytes
ppp_input[0]: ip in pbuf len=40
http_recv: pcb=0x2000a7ec pbuf=0 err=Ok.
Closing connection 0x2000a7ec
tcp_close: closing in State: CLOSE_WAIT
pppos_netif_output[0]: proto=0x21, len = 40
pppos_input[0]: got 46 bytes
ppp_input[0]: ip in pbuf len=40
http_recv: pcb=0x2000a730 pbuf=0 err=Ok.
Closing connection 0x2000a730
tcp_close: closing in State: CLOSE_WAIT
pppos_netif_output[0]: proto=0x21, len = 40
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slwtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pc
pppos_netif_output[0]: proto=0x21, len = 40
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
pppos_netif_output[0]: proto=0x21, len = 40
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: poll g application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
pppos_input[0]: got 92 bytes
ppp_input[0]: ip in pbuf len=40
pppos_netif_output[0]: proto=0x21, len = 40
ppp_input[0]: ip in pbuf len=40
pppos_netif_output[0]: proto=0x21, len = 40
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowtmr: polling application
tcp_slowtmr: processing active pcb
tcp_slowmr: polling application

On Mon, Feb 10, 2020 at 11:55 PM <address@hidden> wrote:

Hi Renato,

On Fri, Feb 07, 2020 at 10:00:00AM -0300, Renato Barresi wrote:
> [...]
> pppos_input[0]: Dropping bad fcs 0x85ae proto=0x21
> [...]
> pppos_input[0]: Dropping bad fcs 0xb7ce proto=0x4

You serial low level driver does not seem to work properly. Probably
both Tx and Rx are affected by data corruption.

Sylvain

From:	Renato Barresi
Subject:	[lwip-users] R: Re: R: Re: Unable to connect to HTTP server using PPP
Date:	Sat, 15 Feb 2020 11:09:43 -0300