Date: Fri, 12 Feb 99 13:09:19 +0100 From: Ben Stuyts <ben@stuyts.nl> To: stable@FreeBSD.ORG Subject: ppp problem Message-ID: <199902121209.NAA03283@giskard.stuyts.nl>
next in thread | raw e-mail | index | archive | help
Hello,
Sometimes I have a problem connecting to my ISP. It looks like an
authentication problem, but I'm not sure. It shouldn't happen really, because
usually the ppp connection comes up fine. Sometimes it keeps on failing
however, and the only solution is to power cycle the modem. (a ZyXEL Elite
2864 ISDN) ATZ is not enough. It is a dial on demand connection, so it is
really annoying that it sometimes won't work.
Here is the ppp log file, and I hope someone can help me understand what is
actually going wrong:
Feb 12 12:45:03 nemesis ppp[2181]: tun0: Phase: bundle: Establish
Feb 12 12:45:03 nemesis ppp[2181]: tun0: Phase: deflink: closed -> opening
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Phase: deflink: Redial timer expired.
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Phase: deflink: Connected!
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Phase: deflink: opening -> dial
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Phase: Phone: xxxxxxxxxx
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Chat: deflink: Dial attempt 1 of 1
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Chat: Send: ATZ^M
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Chat: Expect(5): OK
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Chat: Received: ATZ^M^M
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Chat: Received: OK^M
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Chat: Send: ATE1Q0^M
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Chat: Expect(5): OK
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Chat: Received: ATE1Q0^M^M
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Chat: Received: OK^M
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Chat: Send: ATB50DIxxxxxxxxxx ^M
Feb 12 12:45:05 nemesis ppp[2181]: tun0: Chat: Expect(60): CONNECT
Feb 12 12:45:06 nemesis ppp[2181]: tun0: Chat: Received: ATB50DIxxxxxxxxxx ^M^M
Feb 12 12:45:06 nemesis ppp[2181]: tun0: Chat: Received: CONNECT 115200/PPP
64000/NONE^M
Feb 12 12:45:06 nemesis ppp[2181]: tun0: Chat: Send: ^M
Feb 12 12:45:08 nemesis ppp[2181]: tun0: Phase: deflink: dial -> login
Feb 12 12:45:08 nemesis ppp[2181]: tun0: Phase: deflink: login -> lcp
Feb 12 12:45:08 nemesis ppp[2181]: tun0: LCP: FSM: Using "deflink" as a transport
Feb 12 12:45:08 nemesis ppp[2181]: tun0: LCP: deflink: State change Initial
--> Closed
Feb 12 12:45:08 nemesis ppp[2181]: tun0: LCP: deflink: State change Closed
--> Stopped
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: deflink: LayerStart
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: deflink: SendConfigReq(17)
state = Stopped
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: ACFCOMP[2]
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: PROTOCOMP[2]
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: MRU[4] 1500
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0x57e051b2
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: deflink: State change Stopped
--> Req-Sent
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: deflink: RecvConfigRej(17)
state = Req-Sent
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: PROTOCOMP[2]
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: ACFCOMP[2]
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: deflink: SendConfigReq(18)
state = Req-Sent
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: MRU[4] 1500
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0x57e051b2
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: deflink: RecvConfigReq(1)
state = Req-Sent
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0xc36eb7d9
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: deflink: SendConfigAck(1)
state = Req-Sent
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0xc36eb7d9
Feb 12 12:45:09 nemesis ppp[2181]: tun0: LCP: deflink: State change Req-Sent
--> Ack-Sent
Feb 12 12:45:12 nemesis ppp[2181]: tun0: LCP: deflink: SendConfigReq(18)
state = Ack-Sent
Feb 12 12:45:12 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:12 nemesis ppp[2181]: tun0: LCP: MRU[4] 1500
Feb 12 12:45:12 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0x57e051b2
Feb 12 12:45:12 nemesis ppp[2181]: tun0: LCP: deflink: RecvConfigReq(2)
state = Ack-Sent
Feb 12 12:45:12 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:12 nemesis ppp[2181]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
Feb 12 12:45:12 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0xc36eb7d9
Feb 12 12:45:12 nemesis ppp[2181]: tun0: LCP: deflink: SendConfigAck(2)
state = Ack-Sent
Feb 12 12:45:12 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:12 nemesis ppp[2181]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
Feb 12 12:45:12 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0xc36eb7d9
Feb 12 12:45:15 nemesis ppp[2181]: tun0: LCP: deflink: SendConfigReq(18)
state = Ack-Sent
Feb 12 12:45:15 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:15 nemesis ppp[2181]: tun0: LCP: MRU[4] 1500
Feb 12 12:45:15 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0x57e051b2
Feb 12 12:45:15 nemesis ppp[2181]: tun0: LCP: deflink: RecvConfigReq(3)
state = Ack-Sent
Feb 12 12:45:15 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:15 nemesis ppp[2181]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
Feb 12 12:45:15 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0xc36eb7d9
Feb 12 12:45:15 nemesis ppp[2181]: tun0: LCP: deflink: SendConfigAck(3)
state = Ack-Sent
Feb 12 12:45:15 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:15 nemesis ppp[2181]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
Feb 12 12:45:15 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0xc36eb7d9
Feb 12 12:45:18 nemesis ppp[2181]: tun0: LCP: deflink: SendConfigReq(18)
state = Ack-Sent
Feb 12 12:45:18 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:18 nemesis ppp[2181]: tun0: LCP: MRU[4] 1500
Feb 12 12:45:18 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0x57e051b2
Feb 12 12:45:18 nemesis ppp[2181]: tun0: LCP: deflink: RecvConfigReq(4)
state = Ack-Sent
Feb 12 12:45:18 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:18 nemesis ppp[2181]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
Feb 12 12:45:18 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0xc36eb7d9
Feb 12 12:45:18 nemesis ppp[2181]: tun0: LCP: deflink: SendConfigAck(4)
state = Ack-Sent
Feb 12 12:45:18 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:18 nemesis ppp[2181]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
Feb 12 12:45:18 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0xc36eb7d9
Feb 12 12:45:21 nemesis ppp[2181]: tun0: LCP: deflink: SendConfigReq(18)
state = Ack-Sent
Feb 12 12:45:21 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:21 nemesis ppp[2181]: tun0: LCP: MRU[4] 1500
Feb 12 12:45:21 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0x57e051b2
Feb 12 12:45:21 nemesis ppp[2181]: tun0: LCP: deflink: RecvConfigReq(5)
state = Ack-Sent
Feb 12 12:45:21 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:21 nemesis ppp[2181]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
Feb 12 12:45:21 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0xc36eb7d9
Feb 12 12:45:21 nemesis ppp[2181]: tun0: LCP: deflink: SendConfigAck(5)
state = Ack-Sent
Feb 12 12:45:21 nemesis ppp[2181]: tun0: LCP: ACCMAP[6] 0x00000000
Feb 12 12:45:21 nemesis ppp[2181]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
Feb 12 12:45:21 nemesis ppp[2181]: tun0: LCP: MAGICNUM[6] 0xc36eb7d9
Feb 12 12:45:24 nemesis ppp[2181]: tun0: LCP: deflink: LayerFinish
Feb 12 12:45:24 nemesis ppp[2181]: tun0: LCP: deflink: State change Ack-Sent
--> Stopped
Feb 12 12:45:24 nemesis ppp[2181]: tun0: LCP: deflink: State change Stopped
--> Closed
Feb 12 12:45:24 nemesis ppp[2181]: tun0: LCP: deflink: State change Closed
--> Initial
Feb 12 12:45:24 nemesis ppp[2181]: tun0: Phase: deflink: Disconnected!
Feb 12 12:45:24 nemesis ppp[2181]: tun0: Phase: deflink: lcp -> hangup
Feb 12 12:45:24 nemesis ppp[2181]: tun0: Phase: deflink: Connect time: 19
secs: 237 octets in, 487 octets out
Feb 12 12:45:24 nemesis ppp[2181]: tun0: Phase: total 38 bytes/sec, peak 67
bytes/sec on Fri Feb 12 12:45:24 1999
Feb 12 12:45:24 nemesis ppp[2181]: tun0: Phase: deflink: hangup -> closed
Feb 12 12:45:24 nemesis ppp[2181]: tun0: Phase: bundle: Dead
Here is my ppp.conf file. I'm using the xs4all-demand entry.
default:
set log Phase Chat LCP IPCP CCP tun command
set device /dev/cuaa1
set speed 115200
disable lqr
deny lqr
set dial "ABORT BUSY ABORT NO\\sCARRIER ABORT NO\\sANSWER TIMEOUT 5
\"\" ATZ OK ATE1Q0 OK-AT-OK ATB50DI\\T TIMEOUT 30 CONNECT \\d"
set redial 3
set filter alive 0 deny icmp
set filter alive 1 deny udp src eq 53
set filter alive 2 deny udp dst eq 53
set filter alive 3 deny udp src eq 520
set filter alive 4 deny udp dst eq 520
set filter alive 5 deny udp src eq 123
set filter alive 6 deny udp dst eq 123
set filter alive 7 permit 0/0 0/0
set filter dial 0 deny icmp
set filter dial 1 deny udp src eq 123
set filter dial 2 deny udp dst eq 123
set filter dial 3 deny udp src eq 137
set filter dial 4 deny udp dst eq 137
set filter dial 5 permit 0/0 0/0
set timeout 300
xs4all-demand:
allow user xxxx
set phone xxxxxxx
accept pap
set authname "xxxx"
set authkey "xxxx"
set ifaddr 194.109.57.80/0 194.109.6.1/0
add default HISADDR
set openmode active
alias enable yes
set server /var/tmp/internet "" 0177
set timeout 300
Thanks,
Ben
To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-stable" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?199902121209.NAA03283>