GPRS initialization problem

[suruaZ]

I set IrDA - PPP between sl-5500 and Alcatel OT 715. Mainly GPRS works fine but sometimes (mainly in most undesireable circumstances ) it fails. It goes right till \"Aithenticating\" step and breaks. At the same time from the phone and other devices GPRS works fine.

Exploring the problem closer I found:
1. If I have uninitialized GPRS connection ( no GPRS mark on the phone screen ) Zaurus is fails to start it (some authentication problems).
2. After it was initialized ( with my old friend - Psion ) and GPRS mark is there - Zaurus connects fine.

Is this known issue?

I made two tcpdumps. First dump - Zaurus rejected by provider. Then successful conection with Psion ( through the same IrDA-Phone ). Second dump - Zaurus connected.
Comparing this dumps I found no big differences between. The only difference - in the first dump after sending of username Zaurus got back ERROR packet from provider. In the second dump after sending exactly the same username connection was continued.
What is interesting that in both cases password was not used after the username at all.

Any ideas?

suruaZ

[suruaZ]

No response yet
Well. May be this could be helpful.
First log - Zaurus unsuccessfully tried to connect. Phone was just switched on . No gprs sign on the phone screen (it was not signed to the GRPS yet).

May 29 12:54:19 localhost pppd[435]: pppd 2.4.0 started by root, uid 0
May 29 12:54:20 localhost chat[437]: abort on (NO CARRIER)
May 29 12:54:20 localhost chat[437]: abort on (NO DIALTONE)
May 29 12:54:20 localhost chat[437]: abort on (BUSY)
May 29 12:54:20 localhost chat[437]: send (ATZ^M)
May 29 12:54:20 localhost chat[437]: expect (OK)
May 29 12:54:21 localhost chat[437]: ATZ^M^M
May 29 12:54:21 localhost chat[437]: OK
May 29 12:54:21 localhost chat[437]:  -- got it
May 29 12:54:21 localhost chat[437]: send (ATD*99***1#^M)
May 29 12:54:21 localhost chat[437]: expect (CONNECT)
May 29 12:54:21 localhost chat[437]: ^M
May 29 12:54:21 localhost chat[437]: ATD*99***1#^M^M
May 29 12:54:21 localhost chat[437]: CONNECT
May 29 12:54:21 localhost chat[437]:  -- got it
May 29 12:54:21 localhost pppd[435]: Serial connection established.
May 29 12:54:21 localhost pppd[435]: using channel 2
May 29 12:54:21 localhost pppd[435]: Using interface ppp0
May 29 12:54:21 localhost pppd[435]: Connect: ppp0 <--> /dev/ircomm
May 29 12:54:25 localhost pppd[435]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xf0e013c6> <pcomp> <accomp>]
May 29 12:54:25 localhost pppd[435]: Timeout 0x20084bc:0x203b440 in 3 seconds.
May 29 12:54:25 localhost pppd[435]: rcvd [LCP ConfReq id=0x3 <asyncmap 0xa0000> <pcomp> <accomp> <magic 0x7287cac3> <auth chap MD5>]
May 29 12:54:25 localhost pppd[435]: lcp_reqci: returning CONFACK.
May 29 12:54:25 localhost pppd[435]: sent [LCP ConfAck id=0x3 <asyncmap 0xa0000> <pcomp> <accomp> <magic 0x7287cac3> <auth chap MD5>]
May 29 12:54:25 localhost pppd[435]: rcvd [LCP ConfNak id=0x1 <asyncmap 0xa0000>]
May 29 12:54:25 localhost pppd[435]: Untimeout 0x20084bc:0x203b440.
May 29 12:54:25 localhost pppd[435]: sent [LCP ConfReq id=0x2 <asyncmap 0xa0000> <magic 0xf0e013c6> <pcomp> <accomp>]
May 29 12:54:25 localhost pppd[435]: Timeout 0x20084bc:0x203b440 in 3 seconds.
May 29 12:54:25 localhost pppd[435]: rcvd [LCP ConfAck id=0x2 <asyncmap 0xa0000> <magic 0xf0e013c6> <pcomp> <accomp>]
May 29 12:54:25 localhost pppd[435]: Untimeout 0x20084bc:0x203b440.
May 29 12:54:25 localhost pppd[435]: rcvd [CHAP Challenge id=0x1 <f2ae29c4ebd1269658fd>, name = \"\"]
May 29 12:54:25 localhost pppd[435]: ChapReceiveChallenge: using \'IrDAPPP1074151617\' as remote name
May 29 12:54:25 localhost pppd[435]: sent [CHAP Response id=0x1 <cd65c25099b6bfdc924a9aa6968eeca3>, name = \"username\"]
May 29 12:54:25 localhost pppd[435]: Timeout 0x200f698:0x203b710 in 3 seconds.
May 29 12:54:25 localhost pppd[435]: rcvd [CHAP Success id=0x1 \"\"]
May 29 12:54:25 localhost pppd[435]: Untimeout 0x200f698:0x203b710.
May 29 12:54:25 localhost pppd[435]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
May 29 12:54:25 localhost pppd[435]: Timeout 0x20084bc:0x203b6a0 in 3 seconds.
May 29 12:54:25 localhost pppd[435]: sent [CCP ConfReq id=0x1 <bsd v1 15>]
May 29 12:54:25 localhost pppd[435]: Timeout 0x20084bc:0x203b7a0 in 3 seconds.
May 29 12:54:25 localhost pppd[435]: rcvd [LCP ProtRej id=0x4 80 fd 01 01 00 07 15 03 2f]
May 29 12:54:25 localhost pppd[435]: Untimeout 0x20084bc:0x203b7a0.
May 29 12:54:28 localhost pppd[435]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
May 29 12:54:28 localhost pppd[435]: Timeout 0x20084bc:0x203b6a0 in 3 seconds.
May 29 12:54:29 localhost pppd[435]: rcvd [LCP TermReq id=0x5]
May 29 12:54:29 localhost pppd[435]: LCP terminated by peer

Next I connected/disconneceted with Psion. gprs sign appeared on the phone screen. After this I was able successfully connect with Zaurus again:

May 29 12:58:49 localhost pppd[518]: pppd 2.4.0 started by root, uid 0
May 29 12:58:50 localhost chat[520]: abort on (NO CARRIER)
May 29 12:58:50 localhost chat[520]: abort on (NO DIALTONE)
May 29 12:58:50 localhost chat[520]: abort on (BUSY)
May 29 12:58:50 localhost chat[520]: send (ATZ^M)
May 29 12:58:50 localhost chat[520]: expect (OK)
May 29 12:58:50 localhost chat[520]: ATZ^M^M
May 29 12:58:50 localhost chat[520]: OK
May 29 12:58:50 localhost chat[520]:  -- got it
May 29 12:58:50 localhost chat[520]: send (ATD*99***1#^M)
May 29 12:58:51 localhost chat[520]: expect (CONNECT)
May 29 12:58:51 localhost chat[520]: ^M
May 29 12:58:51 localhost chat[520]: ATD*99***1#^M^M
May 29 12:58:51 localhost chat[520]: CONNECT
May 29 12:58:51 localhost chat[520]:  -- got it
May 29 12:58:51 localhost pppd[518]: Serial connection established.
May 29 12:58:51 localhost pppd[518]: using channel 3
May 29 12:58:51 localhost pppd[518]: Using interface ppp0
May 29 12:58:51 localhost pppd[518]: Connect: ppp0 <--> /dev/ircomm
May 29 12:58:54 localhost pppd[518]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xfb3528c> <pcomp> <accomp>]
May 29 12:58:54 localhost pppd[518]: Timeout 0x20084bc:0x203b440 in 3 seconds.
May 29 12:58:54 localhost pppd[518]: rcvd [LCP ConfReq id=0x3 <asyncmap 0xa0000> <pcomp> <accomp> <magic 0x9970b2b> <auth chap MD5>]
May 29 12:58:54 localhost pppd[518]: lcp_reqci: returning CONFACK.
May 29 12:58:54 localhost pppd[518]: sent [LCP ConfAck id=0x3 <asyncmap 0xa0000> <pcomp> <accomp> <magic 0x9970b2b> <auth chap MD5>]
May 29 12:58:55 localhost pppd[518]: rcvd [LCP ConfNak id=0x1 <asyncmap 0xa0000>]
May 29 12:58:55 localhost pppd[518]: Untimeout 0x20084bc:0x203b440.
May 29 12:58:55 localhost pppd[518]: sent [LCP ConfReq id=0x2 <asyncmap 0xa0000> <magic 0xfb3528c> <pcomp> <accomp>]
May 29 12:58:55 localhost pppd[518]: Timeout 0x20084bc:0x203b440 in 3 seconds.
May 29 12:58:55 localhost pppd[518]: rcvd [LCP ConfAck id=0x2 <asyncmap 0xa0000> <magic 0xfb3528c> <pcomp> <accomp>]
May 29 12:58:55 localhost pppd[518]: Untimeout 0x20084bc:0x203b440.
May 29 12:58:55 localhost pppd[518]: rcvd [CHAP Challenge id=0x1 <f7d0b79b558167230a28>, name = \"\"]
May 29 12:58:55 localhost pppd[518]: ChapReceiveChallenge: using \'IrDAPPP1074151617\' as remote name
May 29 12:58:55 localhost pppd[518]: sent [CHAP Response id=0x1 <3d2a67ccaebe4a3d1a938f14b6706dfc>, name = \"username\"]
May 29 12:58:55 localhost pppd[518]: Timeout 0x200f698:0x203b710 in 3 seconds.
May 29 12:58:55 localhost pppd[518]: rcvd [CHAP Success id=0x1 \"\"]
May 29 12:58:55 localhost pppd[518]: Untimeout 0x200f698:0x203b710.
May 29 12:58:55 localhost pppd[518]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
May 29 12:58:55 localhost pppd[518]: Timeout 0x20084bc:0x203b6a0 in 3 seconds.
May 29 12:58:55 localhost pppd[518]: sent [CCP ConfReq id=0x1 <bsd v1 15>]
May 29 12:58:55 localhost pppd[518]: Timeout 0x20084bc:0x203b7a0 in 3 seconds.
May 29 12:58:55 localhost pppd[518]: rcvd [LCP ProtRej id=0x4 80 fd 01 01 00 07 15 03 2f]
May 29 12:58:55 localhost pppd[518]: Untimeout 0x20084bc:0x203b7a0.
May 29 12:58:56 localhost pppd[518]: rcvd [IPCP ConfReq id=0x1 <addr 93.141.69.1>]
May 29 12:58:56 localhost pppd[518]: ipcp: returning Configure-ACK
May 29 12:58:56 localhost pppd[518]: sent [IPCP ConfAck id=0x1 <addr 93.141.69.1>]
May 29 12:58:58 localhost pppd[518]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
May 29 12:58:58 localhost pppd[518]: Timeout 0x20084bc:0x203b6a0 in 3 seconds.
May 29 12:58:58 localhost pppd[518]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
May 29 12:58:58 localhost pppd[518]: Untimeout 0x20084bc:0x203b6a0.
May 29 12:58:58 localhost pppd[518]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0>]
May 29 12:58:58 localhost pppd[518]: Timeout 0x20084bc:0x203b6a0 in 3 seconds.
May 29 12:58:58 localhost pppd[518]: rcvd [IPCP ConfNak id=0x2 <addr 93.141.69.6>]
May 29 12:58:58 localhost pppd[518]: Untimeout 0x20084bc:0x203b6a0.
May 29 12:58:58 localhost pppd[518]: sent [IPCP ConfReq id=0x3 <addr 93.141.69.6>]
May 29 12:58:58 localhost pppd[518]: Timeout 0x20084bc:0x203b6a0 in 3 seconds.
May 29 12:58:58 localhost pppd[518]: rcvd [IPCP ConfAck id=0x3 <addr 93.141.69.6>]
May 29 12:58:58 localhost pppd[518]: Untimeout 0x20084bc:0x203b6a0.
May 29 12:58:58 localhost pppd[518]: ipcp: up
...

They differ after \"sent IPCP\". In the first case it got back \"LCP terminated by peer\" instead of \"rcvd IPCP\" in the second try.

Any ideas?

Thanks
suruaZ

[suruaZ]

Well, here is the end of story.
Network Setup applet did\'t filed chap-secrets with user-password information. It\'s empty there alhough appears in the network settings dialog.
A few clicks in vim and everything works as expected now  

suruaZ