Re: Diald Problems....
Kevin Traas wrote:
>
> IT WORKS!!! IT WORKS!!! IT WORKS!!! IT WORKS!!! IT WORKS!!!
> IT WORKS!!! IT WORKS!!!
>
> Sorry.... Got carried away.... <grin>
>
> Thanks for all of your help, Philippe.
>
> At first, the login failed, but once I increased the timeout for the login
> sequence from 5 seconds to 15, everything fell into place.
>
> Although things work, I do have some strange messages appearing in
> /var/log/messages. Although things seem to be working okay, I think
> they're probably something that should be looked into. Can you "shed any
> light" on this?
>
> I've inserted comments below:
>
> <I ran /etc/init.d/diald start>
> Jan 13 09:22:11 sally diald[9849]: Using fifo /var/lib/diald/diald.fifo
> Jan 13 09:22:11 sally diald[9849]: Starting diald version 0.14
> Jan 13 09:22:11 sally diald[9849]: Proxy device established on interface
> sl0
> Jan 13 09:22:19 sally diald[9849]: Setting pointopoint route for sl0
> Jan 13 09:22:20 sally diald[9849]: Establishing routes for sl0
> Jan 13 09:22:20 sally diald[9849]: Changed snoop device to sl0
> Jan 13 09:22:20 sally diald[9849]: Diald initial setup completed.
> <I ran ping www.microsoft.com>
> Jan 13 09:22:34 sally diald[9849]: filter accepted rule 32 proto 1 len 84
> packet 127.0.0.2,0 => 207.68.137.65,0
> Jan 13 09:22:35 sally diald[9849]: Running connect (pid = 9945).
> Jan 13 09:22:35 sally diald[9849]: filter accepted rule 32 proto 1 len 84
> packet 127.0.0.2,0 => 207.68.137.65,0
> Jan 13 09:22:35 sally diald-up: Initializing Modem...
> Jan 13 09:22:36 sally diald[9849]: filter accepted rule 32 proto 1 len 84
> packet 127.0.0.2,0 => 207.68.137.65,0
> Jan 13 09:22:36 sally diald-up: Dialing system 1...
> Jan 13 09:22:37 sally diald[9849]: filter accepted rule 32 proto 1 len 84
> packet 127.0.0.2,0 => 207.68.137.65,0
> Jan 13 09:22:50 sally last message repeated 11 times
> Jan 13 09:22:54 sally diald-up: Connected
> Jan 13 09:22:54 sally diald-up: Logging in now...
> Jan 13 09:22:55 sally diald[9849]: filter accepted rule 32 proto 1 len 84
> packet 127.0.0.2,0 => 207.68.137.65,0
> Jan 13 09:23:04 sally last message repeated 6 times
> Jan 13 09:23:04 sally diald-up: PPP startup successful
> <This is the end of the connect script (I've changed the messages a bit and
> renamed it to diald-up.>
> Jan 13 09:23:04 sally diald[9849]: Running pppd (pid = 9956).
> Jan 13 09:23:05 sally pppd[9956]: pppd 2.2.0 started by root, uid 0
> Jan 13 09:23:05 sally pppd[9956]: Using interface ppp0
> Jan 13 09:23:05 sally pppd[9956]: Connect: ppp0 <--> /dev/ttyS0
> Jan 13 09:23:05 sally diald[9849]: filter accepted rule 32 proto 1 len 84
> packet 127.0.0.2,0 => 207.68.137.65,0
> Jan 13 09:23:08 sally last message repeated 3 times
> Jan 13 09:23:08 sally pppd[9956]: local IP address 204.244.143.173
> Jan 13 09:23:08 sally pppd[9956]: remote IP address 204.244.143.6
> Jan 13 09:23:08 sally diald[9849]: New addresses: local 204.244.143.173,
> remote 204.244.143.6.
> Jan 13 09:23:14 sally diald[9849]: Setting pointopoint route for ppp0
> Jan 13 09:23:15 sally diald[9849]: Establishing routes for ppp0
> Jan 13 09:23:15 sally diald[9849]: Changed snoop device to ppp0
> <Everything above here, I'm quite happy with... but then comes the
> following....>
> Jan 13 09:23:15 sally diald[9849]: PPP network layer died, but link did
> not. Probable configuration error.
> Jan 13 09:23:22 sally diald[9849]: Setting pointopoint route for sl0
> Jan 13 09:23:22 sally diald[9849]: Establishing routes for sl0
> Jan 13 09:23:22 sally diald[9849]: Removing routes for ppp0
> Jan 13 09:23:22 sally diald[9849]: Changed snoop device to sl0
> Jan 13 09:23:22 sally diald[9849]: New addresses: local 204.244.143.173,
> remote 204.244.143.6.
> Jan 13 09:23:31 sally diald[9849]: Setting pointopoint route for ppp0
> Jan 13 09:23:31 sally diald[9849]: Establishing routes for ppp0
> Jan 13 09:23:32 sally diald[9849]: Changed snoop device to ppp0
> <Notice how this sequence of lines keeps repeating.... However, by this
> point, my ping is successfully receiving responses from
> www.microsoft.com....>
> Jan 13 09:23:32 sally diald[9849]: PPP network layer died, but link did
> not. Probable configuration error.
> Jan 13 09:23:38 sally diald[9849]: Setting pointopoint route for sl0
> Jan 13 09:23:38 sally diald[9849]: Establishing routes for sl0
> Jan 13 09:23:39 sally diald[9849]: Removing routes for ppp0
> Jan 13 09:23:39 sally diald[9849]: Changed snoop device to sl0
> Jan 13 09:23:39 sally diald[9849]: New addresses: local 204.244.143.173,
> remote 204.244.143.6.
> Jan 13 09:23:45 sally diald[9849]: Setting pointopoint route for ppp0
> Jan 13 09:23:45 sally diald[9849]: Establishing routes for ppp0
> Jan 13 09:23:45 sally diald[9849]: Changed snoop device to ppp0
> Jan 13 09:23:45 sally diald[9849]: PPP network layer died, but link did
> not. Probable configuration error.
> Jan 13 09:23:55 sally diald[9849]: Setting pointopoint route for sl0
> Jan 13 09:23:55 sally diald[9849]: Establishing routes for sl0
> Jan 13 09:23:56 sally diald[9849]: Removing routes for ppp0
> Jan 13 09:23:56 sally diald[9849]: Changed snoop device to sl0
> Jan 13 09:23:56 sally diald[9849]: New addresses: local 204.244.143.173,
> remote 204.244.143.6.
> Jan 13 09:24:03 sally diald[9849]: Setting pointopoint route for ppp0
> Jan 13 09:24:03 sally diald[9849]: Establishing routes for ppp0
> Jan 13 09:24:04 sally diald[9849]: Changed snoop device to ppp0
> Jan 13 09:24:04 sally diald[9849]: filter accepted rule 32 proto 1 len 84
> packet 204.244.143.173,0 => 207.68.137.65,0
> <Many "filter accepted" messages snipped>
> Jan 13 09:24:18 sally diald[9849]: filter accepted rule 32 proto 1 len 84
> packet 207.68.156.61,0 => 204.244.143.173,0
> <This is the last "accepted" message because I cancelled the ping. Thus, I
> got the following sequence of messages as expected....>
> Jan 13 09:24:48 sally diald[9849]: Closing down idle link.
> Jan 13 09:24:55 sally diald[9849]: Setting pointopoint route for sl0
> Jan 13 09:24:55 sally diald[9849]: Establishing routes for sl0
> Jan 13 09:24:55 sally diald[9849]: Removing routes for ppp0
> Jan 13 09:24:55 sally diald[9849]: Changed snoop device to sl0
> Jan 13 09:24:56 sally pppd[9956]: Terminating on signal 2.
> Jan 13 09:25:02 sally pppd[9956]: Connection terminated.
> Jan 13 09:25:02 sally pppd[9956]: Exit.
> Jan 13 09:25:02 sally diald[9849]: Setting pointopoint route for sl0
> Jan 13 09:25:02 sally diald[9849]: Establishing routes for sl0
> Jan 13 09:25:02 sally diald[9849]: Closing modem line.
> Jan 13 09:25:03 sally diald[9849]: Delaying 2 seconds before clear to dial.
>
> So, I'm quite happy in that things are working; however, I feel I should be
> concerned about the "PPP network layer died, but link did not. Probable
> configuration error." messages and how the sequence repeats 3 or 4
> times....
>
> Any ideas?
>
> TIA,
>
> Kevin Traas
> Systems Analyst
> Edmondson Roper Chartered Accountants
> http://users.uniserve.com/~erca
> Chilliwack, B.C.
> Pager: (604) 918-2054
> Office: (604) 792-1915
A possible cause for this is that the peer gave up the connection (hung
up
the modem) without sending the LCP terminate request and waiting for a
reply. PPP implementations are supposed to do this to gracefully take
down
a link, but maybe the implementation on your peer isn't behaving
properly.
I can't really tell because you cut out the messages immediately
preceding
the nasty one you're complaining of so it's hard to tell. Maybe on the
other
hand your pppd sent a terminate request but never received the terminate
ACK
before the other end dropped the line. As long as the connection goes
down
when you want it to I wouldn't worry about it.
--
Jens B. Jorgensen
jjorgens@bdsinc.com
--
TO UNSUBSCRIBE FROM THIS MAILING LIST: e-mail the word "unsubscribe" to
debian-user-REQUEST@lists.debian.org . Trouble? e-mail to Bruce@Pixar.com
Reply to: