Re: PAP/CHAT --> no route; LCP-Config Requests reflected (solved)
Thanks for letting us know how it turned out. Yes, ATZ can leave the
modem is a really weird state.
I am a bit surprised by the serial driver stuff.
Usually that is loaded into the kernel and not as modules.
Also you are communicating with it.
Anyway glad it works.
> 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.
>
> ------------------------------------------------------------------------------------------------------------------
>
>
--
William G. Unruh Canadian Institute for Tel: +1(604)822-3273
Physics&Astronomy Advanced Research Fax: +1(604)822-5324
UBC, Vancouver,BC Program in Cosmology unruh@physics.ubc.ca
Canada V6T 1Z1 and Gravity www.theory.physics.ubc.ca/
For step by step instructions about setting up ppp under Linux, see
http://www.theory.physics.ubc.ca/ppp-linux.html
Reply to: