[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]
[MiNT] pppd error messages
Hi!
I've tried a few things out about the pppd on the Milan. I've tried
three versions of pppd: v2.1.2, v2.2.0 and v2.2.0 modified by me (put in
a lot of debug messages)
First I've tried with HSModem, rsvx.prg (2x) and hsminst (well, I
haven´t tried with the _real_ new one yet, of yourse).
That does not work at all. chat dials, connects and that's it. No more,
no
less. In the ppp-log you'd find:
Jul 26 23:56:20 milan pppd[95]: pppd 2.2.0 started by root, uid 0
Jul 26 23:56:20 milan pppd[95]: Connecting with </usr/etc/chat -f
/etc/ppp/chatscript.wb>
Jul 26 23:56:48 milan pppd[95]: Serial connection established.
Jul 26 23:56:52 milan pppd[95]: now if_link (my debug version)
Jul 26 23:57:10 milan pppd[95]: Hangup (SIGHUP)
Jul 26 23:57:20 milan pppd[95]: Fatal signal 8
Jul 26 23:57:22 milan pppd[95]: Exit.
As you can see, the whole thing stops in the if_link routine of the
pppd. That routine uses a call in sockdev.xdd. sockdev at one point
uses a fcntl which never returns. All debug messages stop there until I
kill pppd. As this fcntl is (as I guess) passed on to HSModem, there
_might_ be a bug in HSModem, but of course that´s just a guess.
The second setup is HSModem without RSVX and thus without hsminst,
too.
Then things look like this:
Jul 26 23:47:22 milan pppd[137]: pppd 2.1.2 started by root, uid 0
Jul 26 23:47:22 milan pppd[138]: Connecting with </usr/etc/chat -f
/etc/ppp/chatscript.wb>
Jul 26 23:47:50 milan pppd[138]: Connected...
Jul 26 23:48:24 milan pppd[138]: Hangup (SIGHUP)
Jul 26 23:48:24 milan pppd[138]: LCP: Down event in state 0!
Jul 26 23:48:26 milan pppd[138]: Exit.
Well, that's just as bad.
The MODEM TD LED does not flash, like above.
Finally I've tried without HSModem at all. That ends up like this:
...
Jul 27 01:02:44 milan pppd[155]: Connect: ppp0 <--> /dev/ttyd
Jul 27 01:02:44 milan pppd[155]: fsm_sdata(LCP): Sent code 1, id 1.
Jul 27 01:02:44 milan ppp<151>Jul 27 01:02:44 pppd[155]: IO signal
received
Jul 27 01:02:44 milan pppd[155]: fsm_rconfreq(LCP): Rcvd id 1.
Jul 27 01:02:44 milan pppd[155]: lcp_reqci: rcvd unknown option 0
Jul 27 01:02:44 milan pppd[155]: (REJ)
Jul 27 01:02:44 milan pppd[155]: lcp_reqci: rcvd MRU
Jul 27 01:02:44 milan pppd[155]: (1524)
Jul 27 01:02:44 milan pppd[155]: (ACK)
Jul 27 01:02:44 milan pppd[155]: lcp_reqci: rcvd ASYNCMAP
Jul 27 01:02:44 milan pppd[155]: (a0000)
Jul 27 01:02:44 milan pppd[155]: (ACK)
Jul 27 01:02:44 milan pppd[155]: lcp_reqci: rcvd AUTHTYPE
Jul 27 01:02:44 milan pppd[155]: (c023)
Jul 27 01:02:44 milan pppd[155]: (ACK)
Jul 27 01:02:44 milan pppd[155]: lcp_reqci: rcvd PCOMPRESSION
Jul 27 01:02:44 milan pppd[155]: (ACK)
Jul 27 01:02:44 milan Jul 27 01:02: rcvd ACCOMPRESSION
Jul 27 01:02:44 milan pppd[155]: (ACK)
Jul 27 01:02:44 milan pppd[155]: lcp_reqci: rcvd unknown option 17
Jul 27 01:02:44 milan pppd[155]: (REJ)
Jul 27 01:02:44 milan pppd[155]: lcp_reqci: rcvd unknown option 19
Jul 27 01:02:44 milan pppd[155]: (REJ)
Jul 27 01:02:44 milan pppd[155]: lcp_reqci: returning CONFREJ.
Jul 27 01:02:44 milan pppd[155]: fsm_sdata(LCP): Se<151>Jul 27 01:02:48
pppd[155]: Alarm
Jul 27 01:02:48 milan pppd[155]: fsm_sdata(LCP): Sent code 1, id 1.
Jul 27 01:02:48 milan Jul 27 01:02:4<151>Jul 27 01:02:48 pppd[155]: IO
signal received
Jul 27 01:02:48 milan pppd[155]: fsm_rconfreq(LCP): Rcvd id 1.
Jul 27 01:02:48 milan pppd[155]: lcp_reqci: rcvd unknown option 0
Jul 27 01:02:48 milan pppd[155]: (REJ)
Jul 27 01:02:48 milan pppd[155]: lcp_reqci: rcvd MRU
Jul 27 01:02:48 milan pppd[155]: (1524)
Jul 27 01:02:48 milan pppd[155]: (ACK)
Jul 27 01:02:48 milan pppd[155]: lcp_reqci: rcvd ASYNCMAP
Jul 27 01:02:48 milan pppd[155]: (a0000)
Jul 27 01:02:48 milan pppd[155]: (ACK)
Jul 27 01:02:48 milan pppd[155]: lcp_reqci: rcvd AUTHTYPE
Jul 27 01:02:48 milan pppd[155]: (c023)
Jul 27 01:02:48 milan pppd[155]: (ACK)
Jul 27 01:02:48 milan pppd[155]: lcp_reqci: rcvd PCOMPRESSION
Jul 27 01:02:48 milan pppd[155]: (ACK)
Jul 27 01:02:48 milan pppd[1d ACCOMPRESSION
Jul 27 01:02:48 milan pppd[155]: (ACK)
Jul 27 01:02:48 milan pppd[155]: lcp_reqci: rcvd unknown option 17
Jul 27 01:02:48 milan pppd[155]: (REJ)
Jul 27 01:02:48 milan pppd[155]: lcp_reqci: rcvd unknown option 19
Jul 27 01:02:48 milan pppd[155]: (REJ)
Jul 27 01:02:48 milan pppd[155]: lcp_reqci: returning CONFREJ.
Jul 27 01:02:48 milan pppd[155]: fsm_sdat<151>Jul 27 01:02:50 pppd[155]:
Alarm
Jul 27 01:02:50 milan pppd[155]: fsm_sdata(LCP): Sent code 1, id 1.
Jul 27 01:02:50 milan pppd[155<151>Jul 27 01:02:52 pppd[155]: IO signal
received
Jul 27 01:02:52 milan pppd[155]: fsm_rconfreq(LCP): Rcvd id 1.
Jul 27 01:02:52 milan pppd[155]: lcp_reqci: rcvd unknown option 0
...now it repeats for about 5 times...
Jul 27 01:03:14 milan pppd[155]: lcp_reqci: returning CONFREJ.
Jul 27 01:03:14 milan pppd[155]<151>Jul 27 01:03:14 pppd[155]: Alarm
Jul 27 01:03:14 milan pppd[155]: LCP: timeout sending Config-Requests
Sorry, it's a bit long.
Well, the Transmit Data LED of my modem never even flickers. (Not
even at a fierce look of mine ;-))
But note these funny interruptions. What does this <151> means? It
always appears, each time, so this is not a process ID. But what could
it be?
And what does the Alarm mean? What happens there?
Anyway, obviously pppd seems to have troubles sending data to the modem.
I've tried all sort auf speeds (19200, 38400, 115200).
All my scripts and options files should work alright as someone else
has tested it on his computer and sent me all the files. So _that_
shouldn't be the problem (pppd doesn't even get that far that it
would need any option files like resolv.conf etc., does it?)
Also the new mintnet version doesn´t help this problem.
Cheers, Ingo =;->