Re: PAP/CHAT --> no route; LCP-Config Requests reflected (solved)
Hello, just want to mention how it got solved.
AFAICS it was a combination of not loadnig the drivers correct ( serial_cs.o
AND serial.o both seem to be needed ) and a bad resetted modem.
After i compiled the serial.o again, and tried AT&F instead of ATZ, it worked.
Many thanks Bill, for your suggestion !
--
micha
-------------------------------------------------------------------------------------------------------
> Thank you, Bill, for replying.
>
> Now here's the chat from my desktop, which is working well:
Actually you do not have debug set up for pppd on the desktop. But there
is enough here .
Another possibility is that your modem is improperly set up not to be
using 8 bit protocol for some reason (or perhaps it is defective). The
far end is sending back 7 bit stuff. That is all I can read from teh
logs.
> And in comparison here's the chat from my laptop again
> ( i disabled the lpc stuff as you suggested, and it did no harm :) but
seems
> to be not hitting the problem ):
OK. I can see nothing here which could be causing the problem. I am
suspicious of your modem or modem setup. It must be set up for 8 bit no
parity. I think pppd is supposed to do that, but perhaps not.
> Feb 3 00:50:07 piro chat[2142]: OK^M
> Feb 3 00:50:07 piro chat[2142]: ATZ^M^M
Actually I like AT&F better. ATZ can do strange things to a modem.
> Feb 3 00:51:12 piro pppd[2141]: Receive serial link is not 8-bit clean:
> Feb 3 00:51:12 piro pppd[2141]: Problem: all had bit 7 set to 0
> > What are you running on your laptop?
> Debian woody 3.0 on a Dell Inspiron with a gericom v.90 pcmcia-card.
> It's all quite new for me, and i asked the same task on debian-laptop;
> however i thought it's more a question for ppp-experts...but, if you think,
> the hotplug-stuff is interfering, i'll try to disable it.
I do not know. Something seems to be improperly setting up your modem.
----------------------------------------------------------------------------------------------------------
It's a gericom v.90 modem card on a dell inspiron 5000, and it seems to
work.
I dial into an ISP with standard pap / chat login procedure and dynamic DNS.
The same peers and ppp-options on my desktop works fine, but not the
laptop.
On the laptop, I can dial and log in with minicom.
It looks like:
atz at &f
OK
atz
OK
atdt7671796
CONNECT 1200 V42bis
Wilkommen bei Breisnet Online Plus (Flatrate) (0761 7671 798)
login: its_me
Password: no_matter
PPP session from (195.2.163.146) to 213.218.5.147 beginning....~}#@!}!}!}
}4}"}&} } } } }%}&|E}'}"}(}"r}8~~}#@!}!}"} }4}"}&} } } }
}%}&|E}'}"}(}"8%~
OK
But i don't see any route and cannot ping anywhere !
Also with wvdial and pon there was no route and no ping possible after the
ppp has started up.
I wonder if it's something specific to the modem card.
IBut the 'sound' seems ok...it's just not entering TCP/IP.
Now here's the chat from my desktop, which is working well:
DESKTOP:
Feb 2 23:11:16 woody pppd[1023]: pppd 2.4.1 started by root, uid 0
Feb 2 23:11:17 woody chat[1024]: abort on (BUSY)
Feb 2 23:11:17 woody chat[1024]: abort on (NO CARRIER)
Feb 2 23:11:17 woody chat[1024]: abort on (VOICE)
Feb 2 23:11:17 woody chat[1024]: abort on (NO DIALTONE)
Feb 2 23:11:17 woody chat[1024]: abort on (NO DIAL TONE)
Feb 2 23:11:17 woody chat[1024]: abort on (NO ANSWER)
Feb 2 23:11:17 woody chat[1024]: abort on (DELAYED)
Feb 2 23:11:17 woody chat[1024]: send (ATZ^M)
Feb 2 23:11:17 woody chat[1024]: send (ATM1L1 ATQ0 V1 E1 S0=0 &C1 &D2 S11=55
+FCLASS=0^M)
Feb 2 23:11:18 woody chat[1024]: expect (OK)
Feb 2 23:11:18 woody chat[1024]: ATZ^M^M
Feb 2 23:11:18 woody chat[1024]: OK
Feb 2 23:11:18 woody chat[1024]: -- got it
Feb 2 23:11:18 woody chat[1024]: send (ATDT7671796^M)
Feb 2 23:11:18 woody chat[1024]: expect (CONNECT)
Feb 2 23:11:18 woody chat[1024]: ^M
Feb 2 23:11:18 woody chat[1024]: ATQ0 V1 E1 S0=0 &C1 &D2 S11=55 +FCLASS=0^M^M
Feb 2 23:11:18 woody chat[1024]: OK^M
Feb 2 23:11:47 woody chat[1024]: ATDT7671796^M^M
Feb 2 23:11:47 woody chat[1024]: CONNECT
Feb 2 23:11:47 woody chat[1024]: -- got it
Feb 2 23:11:47 woody chat[1024]: send (\d)
Feb 2 23:11:48 woody pppd[1023]: Serial connection established.
Feb 2 23:11:48 woody pppd[1023]: Using interface ppp0
Feb 2 23:11:48 woody pppd[1023]: Connect: ppp0 <--> /dev/ttyS0
Feb 2 23:11:52 woody pppd[1023]: Remote message: Login Succeeded
Feb 2 23:11:52 woody pppd[1023]: local IP address 213.218.5.143
Feb 2 23:11:52 woody pppd[1023]: remote IP address 195.2.163.146
Feb 2 23:11:52 woody pppd[1023]: primary DNS address 212.126.200.62
Feb 2 23:12:07 woody pppd[1023]: Terminating on signal 15.
Feb 2 23:12:07 woody pppd[1023]: Connection terminated.
Feb 2 23:12:07 woody pppd[1023]: Connect time 0.4 minutes.
Feb 2 23:12:07 woody pppd[1023]: Sent 532 bytes, received 815 bytes.
Feb 2 23:12:07 woody pppd[1023]: Hangup (SIGHUP)
Feb 2 23:12:07 woody pppd[1023]: Exit.
And in comparison here's the chat from my laptop:
LAPTOP:
Feb 3 00:50:04 piro pppd[2141]: pppd 2.4.1 started by root, uid 0
Feb 3 00:50:05 piro chat[2142]: abort on (BUSY)
Feb 3 00:50:05 piro chat[2142]: abort on (NO CARRIER)
Feb 3 00:50:05 piro chat[2142]: abort on (VOICE)
Feb 3 00:50:05 piro chat[2142]: abort on (NO DIALTONE)
Feb 3 00:50:05 piro chat[2142]: abort on (NO DIAL TONE)
Feb 3 00:50:05 piro chat[2142]: abort on (NO ANSWER)
Feb 3 00:50:05 piro chat[2142]: abort on (DELAYED)
Feb 3 00:50:05 piro chat[2142]: send (ATZ^M)
Feb 3 00:50:05 piro chat[2142]: send (ATM1L2^M)
Feb 3 00:50:05 piro chat[2142]: send (ATQ0 V1 E1 S0=0 &C1 &D2 S11=55
+FCLASS=0^M)
Feb 3 00:50:06 piro chat[2142]: send (ATDT7671796^M)
Feb 3 00:50:07 piro chat[2142]: expect (CONNECT)
Feb 3 00:50:07 piro chat[2142]:
Feb 3 00:50:07 piro chat[2142]: OK^M
Feb 3 00:50:07 piro chat[2142]: ATZ^M^M
Feb 3 00:50:07 piro chat[2142]: OK^M
Feb 3 00:50:07 piro chat[2142]: ATM1L2^M^M
Feb 3 00:50:07 piro chat[2142]: OK^M
Feb 3 00:50:07 piro chat[2142]: ATQ0 V1 E1 S0=0 &C1 &D2 S11=55 +FCLASS=0^M^M
Feb 3 00:50:07 piro chat[2142]: OK^M
Feb 3 00:50:37 piro chat[2142]: ATDT7671796^M^M
Feb 3 00:50:37 piro chat[2142]: CONNECT
Feb 3 00:50:37 piro chat[2142]: -- got it
Feb 3 00:50:37 piro chat[2142]: send (\d)
Feb 3 00:50:38 piro pppd[2141]: Serial connection established.
Feb 3 00:50:38 piro pppd[2141]: using channel 15
Feb 3 00:50:38 piro pppd[2141]: Using interface ppp0
Feb 3 00:50:38 piro pppd[2141]: Connect: ppp0 <--> /dev/modem
Feb 3 00:50:39 piro pppd[2141]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0x90bd97c3> <pcomp> <accomp>]
Feb 3 00:51:03 piro last message repeated 8 times
Feb 3 00:51:06 piro pppd[2141]: Terminating on signal 15.
Feb 3 00:51:06 piro pppd[2141]: sent [LCP TermReq id=0x2 "User request"]
Feb 3 00:51:09 piro pppd[2141]: sent [LCP TermReq id=0x3 "User request"]
Feb 3 00:51:12 piro pppd[2141]: Connection terminated.
Feb 3 00:51:12 piro pppd[2141]: Receive serial link is not 8-bit clean:
Feb 3 00:51:12 piro pppd[2141]: Problem: all had bit 7 set to 0
Feb 3 00:51:13 piro pppd[2141]: Exit.
------------------------------------------------------------------------------------------------------
Here are the global ppp - options; they are exactly the same as for my
desktop, which connects to the same ISP with the same setting alright, using
an external modem.
==== /etc/ppp/options:
asyncmap 0
crtscts
lock
modem
proxyarp
lcp-echo-interval 30
lcp-echo-failure 4
noipx
-- for debugging:
noauth
show-password
==== /etc/ppp/peers/provider:
debug
show-password
noauth
connect "/usr/sbin/chat -v -f /etc/chatscripts/provider"
/dev/modem
115200
defaultroute
noipdefault
user breisnet
remotename breisnet
ipparam breisnet
usepeerdns
==== /etc/chatscripts/provider:
# This chatfile was generated by pppconfig 2.0.10.
# Please do not delete any of the comments. Pppconfig needs them.
#
# ispauth PAP
# abortstring
ABORT BUSY ABORT 'NO CARRIER' ABORT VOICE ABORT 'NO DIALTONE' ABORT 'NO DIAL
TONE' ABORT 'NO ANSWER' ABORT DELAYED
# modeminit
'' ATZ
'' 'ATM1L1 ATQ0 V1 E1 S0=0 &C1 &D2 S11=55 +FCLASS=0'
# ispnumber
OK-AT-OK ATDT7671796
# ispconnect
CONNECT \d\c
# prelogin
# ispname
# isppassword
# postlogin
# end of pppconfig stuff
---------------------------------------------------------------------------------------------------
Debugging Log for Desktop:
Feb 2 18:35:18 woody pppd[2246]: using channel 11
Feb 2 18:35:19 woody pppd[2246]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0x469383fd> <pcomp> <accomp>]
XXX please look at the last 'auth pap'...missing on the laptop
Feb 2 18:35:19 woody pppd[2246]: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0xde9b57ed> <pcomp> <accomp> <auth pap>]
Feb 2 18:35:19 woody pppd[2246]: sent [LCP ConfAck id=0x1 <asyncmap 0x0>
<magic 0xde9b57ed> <pcomp> <accomp> <auth pap>]
XXX
Feb 2 18:35:22 woody pppd[2246]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0x469383fd> <pcomp> <accomp>]
Feb 2 18:35:22 woody pppd[2246]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0>
<magic 0x469383fd> <pcomp> <accomp>]
Feb 2 18:35:22 woody pppd[2246]: sent [LCP EchoReq id=0x0 magic=0x469383fd]
Feb 2 18:35:22 woody pppd[2246]: sent [PAP AuthReq id=0x1 user="breisnet"
password="linux"]
Feb 2 18:35:22 woody pppd[2246]: rcvd [LCP EchoRep id=0x0 magic=0xde9b57ed]
Feb 2 18:35:22 woody pppd[2246]: rcvd [PAP AuthAck id=0x1 "Login Succeeded"]
Feb 2 18:35:22 woody pppd[2246]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>
<compress VJ 0f 01> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Feb 2 18:35:22 woody pppd[2246]: sent [CCP ConfReq id=0x1 <deflate 15>
<deflate(old#) 15> <bsd v1 15>]
Feb 2 18:35:22 woody pppd[2246]: rcvd [IPCP ConfReq id=0x1 <compress VJ 0f
00> <addr 195.2.163.146>]
Feb 2 18:35:22 woody pppd[2246]: sent [IPCP ConfAck id=0x1 <compress VJ 0f
00> <addr 195.2.163.146>]
Feb 2 18:35:22 woody pppd[2246]: rcvd [IPCP ConfRej id=0x1 <ms-dns3 0.0.0.0>]
Feb 2 18:35:22 woody pppd[2246]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0>
<compress VJ 0f 01> <ms-dns1 0.0.0.0>]
Feb 2 18:35:22 woody pppd[2246]: rcvd [LCP ProtRej id=0x2 80 fd 01 01 00 0f
1a 04 78 00 18 04 78 00 15 03 2f]
Feb 2 18:35:22 woody pppd[2246]: rcvd [IPCP ConfNak id=0x2 <addr
213.218.5.132> <ms-dns1 212.126.200.62>]
Feb 2 18:35:22 woody pppd[2246]: sent [IPCP ConfReq id=0x3 <addr
213.218.5.132> <compress VJ 0f 01> <ms-dns1 212.126.200.62>]
Feb 2 18:35:22 woody pppd[2246]: rcvd [IPCP ConfAck id=0x3 <addr
213.218.5.132> <compress VJ 0f 01> <ms-dns1 212.126.200.62>]
Feb 2 18:35:22 woody pppd[2246]: Script /etc/ppp/ip-up started (pid 2262)
Feb 2 18:35:23 woody pppd[2246]: Script /etc/ppp/ip-up finished (pid 2262),
status = 0x0
Feb 2 18:35:31 woody pppd[2246]: Script /etc/ppp/ip-down started (pid 2296)
Feb 2 18:35:31 woody pppd[2246]: sent [LCP TermReq id=0x2 "User request"]
Feb 2 18:35:32 woody pppd[2246]: Script /etc/ppp/ip-down finished (pid
2296), status = 0x0
Feb 2 18:35:34 woody pppd[2246]: sent [LCP TermReq id=0x3 "User request"]
---------------------------------------------------------------------------------------------------
Debugging the Laptop ( it's not recent but unchanged ):
[...]
Jan 7 01:25:24 piro chat[1439]: ATDT7671796^M^M
Jan 7 01:25:24 piro chat[1439]: CONNECT
Jan 7 01:25:24 piro chat[1439]: -- got it
Jan 7 01:25:24 piro chat[1439]: send (\d)
Jan 7 01:25:25 piro pppd[1438]: Serial connection established.
Jan 7 01:25:25 piro pppd[1438]: using channel 1
Jan 7 01:25:26 piro pppd[1438]: Using interface ppp0
Jan 7 01:25:26 piro pppd[1438]: Connect: ppp0 <--> /dev/modem
XX the following i try to explain below
Jan 7 01:25:26 piro /etc/hotplug/net.agent: assuming ppp0 is already up
Jan 7 01:25:26 piro ifd[282]: executing: '/usr/share/laptop-net/link-change
ppp0 unwatched unknown down,stopped,unknown'
XXX here no 'auth pap'...:
Jan 7 01:25:27 piro pppd[1438]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic0xce5755b4> <pcomp> <accomp>]
Jan 7 01:25:54 piro last message repeated 9 times
Jan 7 01:25:57 piro pppd[1438]: LCP: timeout sending Config-Requests
Jan 7 01:25:57 piro pppd[1438]: Connection terminated.
XXX
Jan 7 01:25:57 piro pppd[1438]: Receive serial link is not 8-bit clean:
Jan 7 01:25:57 piro pppd[1438]: Problem: all had bit 7 set to 0
Jan 7 01:25:57 piro /etc/hotplug/net.agent: invoke ifdown ppp0
Jan 7 01:25:58 piro ifd[282]: executing: '/usr/share/laptop-net/link-change
ppp0 unwatched down,stopped,unknown unknown'
Jan 7 01:25:58 piro pppd[1438]: Exit.
------------------------------------------------------------------------------------------------------------------
Reply to: