Date: Mon, 24 Sep 2001 14:50:23 +0400 From: "Igor B. Bykhalo" <goshik@binep.ac.ru> To: "FreeBSD Questions" <freebsd-questions@FreeBSD.org> Subject: incoming user-ppp problems: help, please Message-ID: <007101c144e6$b846d060$ea2ce9c1@binep.ac.ru>
next in thread | raw e-mail | index | archive | help
What can be the problems with the following setup for incoming connections? Below are config files logs of 3 sessions, second was probably successful, but user didn't try to use the web browser :( TIA Igor. ---> /etc/ppp/ppp.conf: default: set log Phase Chat LCP IPCP CCP LQM TUN +Command +Connect #set dial "ABORT BUSY ABORT NO\\sCARRIER TIMEOUT 5 \"\" AT \ # OK-AT-OK ATE1Q0 OK \\dATDT\\T TIMEOUT 40 CONNECT" # set server /var/tmp/internet MySecretPassword 0177 # set server 6670 MySecretpassword ttyd3: set device /dev/ttyd3 set speed 57600 set cd 10 enable chap chap80 chap81 pap passwdauth enable proxy set ifaddr a.b.c.214 a.b.c.215 255.255.255.224 enable dns allow user1 user2 ---> /etc/ppp/ppp.secret: user1 * user2 * >>>Session#1 from user1: Sep 24 13:01:54 ns0 ppp[260]: Phase: Using interface: tun0 Sep 24 13:01:54 ns0 ppp[260]: Phase: deflink: Created in closed state Sep 24 13:01:54 ns0 ppp[260]: tun0: Command: ttyd3: set device /dev/ttyd3 Sep 24 13:01:54 ns0 ppp[260]: tun0: Command: ttyd3: set speed 57600 Sep 24 13:01:54 ns0 ppp[260]: tun0: Command: ttyd3: set cd 10 Sep 24 13:01:54 ns0 ppp[260]: tun0: Command: ttyd3: enable chap Sep 24 13:01:54 ns0 ppp[260]: tun0: Command: ttyd3: enable pap Sep 24 13:01:54 ns0 ppp[260]: tun0: Command: ttyd3: enable passwdauth Sep 24 13:01:54 ns0 ppp[260]: tun0: Command: ttyd3: enable dns Sep 24 13:01:54 ns0 ppp[260]: tun0: Command: ttyd3: enable proxy Sep 24 13:01:54 ns0 ppp[260]: tun0: Command: ttyd3: set ifaddr a.b.c.214 a.b.c.215 255.255.255.224 Sep 24 13:01:54 ns0 ppp[260]: tun0: Phase: PPP Started (direct mode). Sep 24 13:01:55 ns0 ppp[260]: tun0: Phase: bundle: Establish Sep 24 13:01:55 ns0 ppp[260]: tun0: Phase: deflink: closed -opening Sep 24 13:01:55 ns0 ppp[260]: tun0: Phase: deflink: Connected! Sep 24 13:01:55 ns0 ppp[260]: tun0: Phase: deflink: opening -carrier Sep 24 13:01:56 ns0 ppp[260]: tun0: Phase: deflink: /dev/ttyd3: CD detected Sep 24 13:01:56 ns0 ppp[260]: tun0: Phase: deflink: carrier -lcp Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: FSM: Using "deflink" as a transport Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: deflink: State change Initial --Closed Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: deflink: State change Closed --Stopped Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: deflink: RecvConfigReq(2) state = Stopped Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: ACCMAP[6] 0x000a0000 Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: MAGICNUM[6] 0x0001ff74 Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: PROTOCOMP[2] Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: ACFCOMP[2] Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: CALLBACK[3] CBCP Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: ACFCOMP[2] Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: PROTOCOMP[2] Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: ACCMAP[6] 0x00000000 Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: MRU[4] 1500 Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: MAGICNUM[6] 0xe99f5dcd Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05) Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: deflink: SendConfigRej(2) state = Stopped Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: CALLBACK[3] CBCP Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: deflink: LayerStart Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: deflink: State change Stopped --Req-Sent Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: deflink: RecvConfigReq(3) state = Req-Sent Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: ACCMAP[6] 0x000a0000 Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: MAGICNUM[6] 0x0001ff74 Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: PROTOCOMP[2] Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: ACFCOMP[2] Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: deflink: SendConfigAck(3) state = Req-Sent Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: ACCMAP[6] 0x000a0000 Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: MAGICNUM[6] 0x0001ff74 Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: PROTOCOMP[2] Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: ACFCOMP[2] Sep 24 13:01:56 ns0 ppp[260]: tun0: LCP: deflink: State change Req-Sent --Ack-Sent Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: deflink: SendConfigReq(1) state = Ack-Sent Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: ACFCOMP[2] Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: PROTOCOMP[2] Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: ACCMAP[6] 0x00000000 Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: MRU[4] 1500 Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: MAGICNUM[6] 0xe99f5dcd Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05) Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: deflink: RecvConfigAck(1) state = Ack-Sent Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: deflink: State change Ack-Sent --Opened Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: deflink: LayerUp Sep 24 13:01:59 ns0 ppp[260]: tun0: LQM: deflink: LQR/ECHO LQR not negotiated Sep 24 13:01:59 ns0 ppp[260]: tun0: Phase: bundle: Authenticate Sep 24 13:01:59 ns0 ppp[260]: tun0: Phase: deflink: his = none, mine = CHAP 0x05 Sep 24 13:01:59 ns0 ppp[260]: tun0: Phase: Chap Output: CHALLENGE Sep 24 13:01:59 ns0 ppp[260]: tun0: Phase: Chap Input: RESPONSE (16 bytes from user1) Sep 24 13:01:59 ns0 ppp[260]: tun0: Phase: Chap Output: FAILURE Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: deflink: LayerDown Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: deflink: SendTerminateReq(2) state = Opened Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: deflink: State change Opened --Closing Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: deflink: RecvTerminateAck(2) state = Closing Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: deflink: LayerFinish Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: deflink: State change Closing --Closed Sep 24 13:01:59 ns0 ppp[260]: tun0: LCP: deflink: State change Closed --Initial Sep 24 13:01:59 ns0 ppp[260]: tun0: Phase: deflink: Disconnected! Sep 24 13:01:59 ns0 ppp[260]: tun0: Phase: deflink: Connect time: 4 secs: 260 octets in, 254 octets out Sep 24 13:01:59 ns0 ppp[260]: tun0: Phase: deflink: : 8 packets in, 7 packets out Sep 24 13:01:59 ns0 ppp[260]: tun0: Phase: total 128 bytes/sec, peak 92 bytes/sec on Mon Sep 24 13:01:59 2001 Sep 24 13:01:59 ns0 ppp[321]: tun0: Phase: deflink: lcp -closed Sep 24 13:01:59 ns0 ppp[321]: tun0: Phase: bundle: Dead Sep 24 13:01:59 ns0 ppp[321]: tun0: Phase: PPP Terminated (normal). Sep 24 13:05:33 ns0 ppp[322]: Phase: Using interface: tun0 Sep 24 13:05:34 ns0 ppp[322]: Phase: deflink: Created in closed state Sep 24 13:05:34 ns0 ppp[322]: tun0: Command: ttyd3: set device /dev/ttyd3 Sep 24 13:05:34 ns0 ppp[322]: tun0: Command: ttyd3: set speed 57600 Sep 24 13:05:34 ns0 ppp[322]: tun0: Command: ttyd3: set cd 10 Sep 24 13:05:34 ns0 ppp[322]: tun0: Command: ttyd3: enable chap Sep 24 13:05:34 ns0 ppp[322]: tun0: Command: ttyd3: enable pap Sep 24 13:05:34 ns0 ppp[322]: tun0: Command: ttyd3: enable passwdauth Sep 24 13:05:34 ns0 ppp[322]: tun0: Command: ttyd3: enable dns Sep 24 13:05:34 ns0 ppp[322]: tun0: Command: ttyd3: enable proxy Sep 24 13:05:34 ns0 ppp[322]: tun0: Command: ttyd3: set ifaddr a.b.c.214 a.b.c.215 255.255.255.224 Sep 24 13:05:34 ns0 ppp[322]: tun0: Phase: PPP Started (direct mode). Sep 24 13:05:34 ns0 ppp[322]: tun0: Phase: bundle: Establish Sep 24 13:05:34 ns0 ppp[322]: tun0: Phase: deflink: closed -opening Sep 24 13:05:34 ns0 ppp[322]: tun0: Phase: deflink: Connected! Sep 24 13:05:34 ns0 ppp[322]: tun0: Phase: deflink: opening -carrier Sep 24 13:05:35 ns0 ppp[322]: tun0: Phase: deflink: /dev/ttyd3: CD detected Sep 24 13:05:35 ns0 ppp[322]: tun0: Phase: deflink: carrier -lcp Sep 24 13:05:35 ns0 ppp[322]: tun0: LCP: FSM: Using "deflink" as a transport Sep 24 13:05:35 ns0 ppp[322]: tun0: LCP: deflink: State change Initial --Closed Sep 24 13:05:35 ns0 ppp[322]: tun0: LCP: deflink: State change Closed --Stopped Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: deflink: LayerStart Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: ACFCOMP[2] Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: PROTOCOMP[2] Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: ACCMAP[6] 0x00000000 Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: MRU[4] 1500 Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: MAGICNUM[6] 0x5612f872 Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05) Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: deflink: State change Stopped --Req-Sent Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: deflink: RecvConfigReq(2) state = Req-Sent Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: ACCMAP[6] 0x000a0000 Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: MAGICNUM[6] 0x00055e33 Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: PROTOCOMP[2] Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: ACFCOMP[2] Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: CALLBACK[3] CBCP Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: deflink: SendConfigRej(2) state = Req-Sent Sep 24 13:05:36 ns0 ppp[322]: tun0: LCP: CALLBACK[3] CBCP Sep 24 13:05:37 ns0 ppp[322]: tun0: LCP: deflink: RecvConfigReq(3) state = Req-Sent Sep 24 13:05:37 ns0 ppp[322]: tun0: LCP: ACCMAP[6] 0x000a0000 Sep 24 13:05:37 ns0 ppp[322]: tun0: LCP: MAGICNUM[6] 0x00055e33 Sep 24 13:05:37 ns0 ppp[322]: tun0: LCP: PROTOCOMP[2] Sep 24 13:05:37 ns0 ppp[322]: tun0: LCP: ACFCOMP[2] Sep 24 13:05:37 ns0 ppp[322]: tun0: LCP: deflink: SendConfigAck(3) state = Req-Sent Sep 24 13:05:37 ns0 ppp[322]: tun0: LCP: ACCMAP[6] 0x000a0000 Sep 24 13:05:37 ns0 ppp[322]: tun0: LCP: MAGICNUM[6] 0x00055e33 Sep 24 13:05:37 ns0 ppp[322]: tun0: LCP: PROTOCOMP[2] Sep 24 13:05:37 ns0 ppp[322]: tun0: LCP: ACFCOMP[2] Sep 24 13:05:37 ns0 ppp[322]: tun0: LCP: deflink: State change Req-Sent --Ack-Sent Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: deflink: SendConfigReq(1) state = Ack-Sent Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: ACFCOMP[2] Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: PROTOCOMP[2] Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: ACCMAP[6] 0x00000000 Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: MRU[4] 1500 Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: MAGICNUM[6] 0x5612f872 Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05) Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: deflink: RecvConfigAck(1) state = Ack-Sent Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: deflink: State change Ack-Sent --Opened Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: deflink: LayerUp Sep 24 13:05:39 ns0 ppp[322]: tun0: LQM: deflink: LQR/ECHO LQR not negotiated Sep 24 13:05:39 ns0 ppp[322]: tun0: Phase: bundle: Authenticate Sep 24 13:05:39 ns0 ppp[322]: tun0: Phase: deflink: his = none, mine = CHAP 0x05 Sep 24 13:05:39 ns0 ppp[322]: tun0: Phase: Chap Output: CHALLENGE Sep 24 13:05:39 ns0 ppp[322]: tun0: Phase: Chap Input: RESPONSE (16 bytes from filatov) Sep 24 13:05:39 ns0 ppp[322]: tun0: Phase: Chap Output: FAILURE Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: deflink: LayerDown Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: deflink: SendTerminateReq(2) state = Opened Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: deflink: State change Opened --Closing Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: deflink: RecvTerminateAck(2) state = Closing Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: deflink: LayerFinish Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: deflink: State change Closing --Closed Sep 24 13:05:39 ns0 ppp[322]: tun0: LCP: deflink: State change Closed --Initial Sep 24 13:05:39 ns0 ppp[322]: tun0: Phase: deflink: Disconnected! Sep 24 13:05:39 ns0 ppp[322]: tun0: Phase: deflink: Connect time: 5 secs: 261 octets in, 279 octets out Sep 24 13:05:39 ns0 ppp[322]: tun0: Phase: deflink: : 9 packets in, 7 packets out Sep 24 13:05:39 ns0 ppp[322]: tun0: Phase: total 108 bytes/sec, peak 69 bytes/sec on Mon Sep 24 13:05:39 2001 Sep 24 13:05:40 ns0 ppp[353]: tun0: Phase: deflink: lcp -closed Sep 24 13:05:40 ns0 ppp[353]: tun0: Phase: bundle: Dead Sep 24 13:05:40 ns0 ppp[353]: tun0: Phase: PPP Terminated (normal). >>>Session#2 from user2: Sep 24 13:23:21 ns0 ppp[397]: Phase: Using interface: tun0 Sep 24 13:23:21 ns0 ppp[397]: Phase: deflink: Created in closed state Sep 24 13:23:21 ns0 ppp[397]: tun0: Command: ttyd3: set device /dev/ttyd3 Sep 24 13:23:21 ns0 ppp[397]: tun0: Command: ttyd3: set speed 57600 Sep 24 13:23:21 ns0 ppp[397]: tun0: Command: ttyd3: set cd 10 Sep 24 13:23:21 ns0 ppp[397]: tun0: Command: ttyd3: enable pap passwdauth Sep 24 13:23:21 ns0 ppp[397]: tun0: Command: ttyd3: enable dns Sep 24 13:23:21 ns0 ppp[397]: tun0: Command: ttyd3: enable proxy Sep 24 13:23:21 ns0 ppp[397]: tun0: Command: ttyd3: set ifaddr a.b.c.214 a.b.c.215 255.255.255.224 Sep 24 13:23:21 ns0 ppp[397]: tun0: Phase: PPP Started (direct mode). Sep 24 13:23:21 ns0 ppp[397]: tun0: Phase: bundle: Establish Sep 24 13:23:21 ns0 ppp[397]: tun0: Phase: deflink: closed -opening Sep 24 13:23:21 ns0 ppp[397]: tun0: Phase: deflink: Connected! Sep 24 13:23:21 ns0 ppp[397]: tun0: Phase: deflink: opening -carrier Sep 24 13:23:22 ns0 ppp[397]: tun0: Phase: deflink: /dev/ttyd3: CD detected Sep 24 13:23:22 ns0 ppp[397]: tun0: Phase: deflink: carrier -lcp Sep 24 13:23:22 ns0 ppp[397]: tun0: LCP: FSM: Using "deflink" as a transport Sep 24 13:23:22 ns0 ppp[397]: tun0: LCP: deflink: State change Initial --Closed Sep 24 13:23:22 ns0 ppp[397]: tun0: LCP: deflink: State change Closed --Stopped Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: deflink: LayerStart Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: ACFCOMP[2] Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: PROTOCOMP[2] Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: ACCMAP[6] 0x00000000 Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: MRU[4] 1500 Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: MAGICNUM[6] 0x349e6334 Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP) Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: deflink: State change Stopped --Req-Sent Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: deflink: RecvConfigAck(1) state = Req-Sent Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: deflink: State change Req-Sent --Ack-Rcvd Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: deflink: RecvConfigReq(2) state = Ack-Rcvd Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: ACCMAP[6] 0x000a0000 Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: MAGICNUM[6] 0x0031447b Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: PROTOCOMP[2] Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: ACFCOMP[2] Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: CALLBACK[3] CBCP Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: deflink: SendConfigRej(2) state = Ack-Rcvd Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: CALLBACK[3] CBCP Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: deflink: RecvConfigReq(3) state = Ack-Rcvd Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: ACCMAP[6] 0x000a0000 Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: MAGICNUM[6] 0x0031447b Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: PROTOCOMP[2] Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: ACFCOMP[2] Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: deflink: SendConfigAck(3) state = Ack-Rcvd Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: ACCMAP[6] 0x000a0000 Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: MAGICNUM[6] 0x0031447b Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: PROTOCOMP[2] Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: ACFCOMP[2] Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: deflink: State change Ack-Rcvd --Opened Sep 24 13:23:23 ns0 ppp[397]: tun0: LCP: deflink: LayerUp Sep 24 13:23:23 ns0 ppp[397]: tun0: LQM: deflink: LQR/ECHO LQR not negotiated Sep 24 13:23:23 ns0 ppp[397]: tun0: Phase: bundle: Authenticate Sep 24 13:23:23 ns0 ppp[397]: tun0: Phase: deflink: his = none, mine = PAP Sep 24 13:23:24 ns0 ppp[397]: tun0: Phase: Pap Input: REQUEST (user2) Sep 24 13:23:24 ns0 ppp[397]: tun0: Phase: Pap Output: SUCCESS Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: FSM: Using "deflink" as a transport Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: deflink: State change Initial --Closed Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: deflink: LayerStart. Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: MPPE: Not usable without CHAP81 Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: deflink: SendConfigReq(1) state = Closed Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: DEFLATE[4] win 15 Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: PRED1[2] Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: deflink: State change Closed --Req-Sent Sep 24 13:23:24 ns0 ppp[397]: tun0: Phase: deflink: lcp -open Sep 24 13:23:24 ns0 ppp[397]: tun0: Phase: bundle: Network Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: FSM: Using "deflink" as a transport Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: deflink: State change Initial --Closed Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: deflink: LayerStart. Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: deflink: SendConfigReq(1) state = Closed Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: IPADDR[6] a.b.c.214 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: PRIDNS[6] 193.233.46.3 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: SECDNS[6] 193.233.32.4 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: deflink: State change Closed --Req-Sent Sep 24 13:23:24 ns0 ppp[397]: tun0: Phase: Unknown protocol 0x802b (Novell IPX Control Protocol) Sep 24 13:23:24 ns0 ppp[397]: tun0: LCP: deflink: SendProtocolRej(2) state = Opened Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: deflink: RecvConfigReq(1) state = Req-Sent Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: IPADDR[6] 0.0.0.0 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: PRIDNS[6] 0.0.0.0 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: PRINBNS[6] 0.0.0.0 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: NBNS REQ - rejected - nbns not set Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: SECDNS[6] 0.0.0.0 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: SECNBNS[6] 0.0.0.0 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: NBNS REQ - rejected - nbns not set Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: deflink: SendConfigRej(1) state = Req-Sent Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: PRIDNS[6] 0.0.0.0 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: PRINBNS[6] 0.0.0.0 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: SECDNS[6] 0.0.0.0 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: SECNBNS[6] 0.0.0.0 Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: deflink: RecvConfigReq(1) state = Req-Sent Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: MPPE[6] value 0x00000001 ( bits, stateful, compressed) Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: MPPE: Not usable without CHAP81 Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: STAC[5] Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: deflink: SendConfigRej(1) state = Req-Sent Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: MPPE[6] value 0x00000001 ( bits, stateful, compressed) Sep 24 13:23:24 ns0 ppp[397]: tun0: CCP: STAC[5] Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: deflink: RecvConfigReq(2) state = Req-Sent Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: IPADDR[6] 0.0.0.0 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: deflink: SendConfigNak(2) state = Req-Sent Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: IPADDR[6] a.b.c.215 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: deflink: RecvConfigReq(3) state = Req-Sent Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: IPADDR[6] a.b.c.215 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: deflink: SendConfigAck(3) state = Req-Sent Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: IPADDR[6] a.b.c.215 Sep 24 13:23:24 ns0 ppp[397]: tun0: IPCP: deflink: State change Req-Sent --Ack-Sent Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: MPPE: Not usable without CHAP81 Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: SendConfigReq(1) state = Req-Sent Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: DEFLATE[4] win 15 Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: PRED1[2] Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: deflink: SendConfigReq(1) state = Ack-Sent Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: IPADDR[6] a.b.c.214 Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: PRIDNS[6] 193.233.46.3 Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: SECDNS[6] 193.233.32.4 Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: RecvConfigReq(2) state = Req-Sent Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: [EMPTY] Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: SendConfigAck(2) state = Req-Sent Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: [EMPTY] Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: State change Req-Sent --Ack-Sent Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: RecvConfigRej(1) state = Ack-Sent Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: DEFLATE[4] win 15 Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: PRED1[2] Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: MPPE: Not usable without CHAP81 Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: SendConfigReq(2) state = Ack-Sent Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: [EMPTY] Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: deflink: RecvConfigRej(1) state = Ack-Sent Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: PRIDNS[6] 193.233.46.3 Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: SECDNS[6] 193.233.32.4 Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: deflink: SendConfigReq(2) state = Ack-Sent Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: IPADDR[6] a.b.c.214 Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: RecvConfigAck(2) state = Ack-Sent Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: State change Ack-Sent --Opened Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: LayerUp. Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: Out = <0xffffffff>[-1], In = <0xffffffff>[-1] Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: RecvTerminateReq(3) state = Opened Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: LayerDown. Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: SendTerminateAck(3) state = Opened Sep 24 13:23:27 ns0 ppp[397]: tun0: CCP: deflink: State change Opened --Stopping Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: deflink: RecvConfigAck(2) state = Ack-Sent Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: deflink: State change Ack-Sent --Opened Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: deflink: LayerUp. Sep 24 13:23:27 ns0 ppp[397]: tun0: IPCP: myaddr a.b.c.214 hisaddr = a.b.c.215 Sep 24 13:23:27 ns0 ppp[397]: tun0: Phase: Found interface ep1 for a.b.c.215 Sep 24 13:23:30 ns0 ppp[397]: tun0: CCP: deflink: LayerFinish. Sep 24 13:23:30 ns0 ppp[397]: tun0: CCP: deflink: State change Stopping --Stopped Sep 24 13:24:25 ns0 ppp[397]: tun0: Phase: deflink: HDLC errors -FCS: 0, ADDR: 0, COMD: 0, PROTO: 1 Sep 24 13:24:53 ns0 ppp[397]: tun0: LCP: deflink: RecvTerminateReq(4) state = Opened Sep 24 13:24:53 ns0 ppp[397]: tun0: LCP: deflink: LayerDown Sep 24 13:24:53 ns0 ppp[397]: tun0: LCP: deflink: SendTerminateAck(4) state = Opened Sep 24 13:24:53 ns0 ppp[397]: tun0: LCP: deflink: State change Opened --Stopping Sep 24 13:24:53 ns0 ppp[397]: tun0: CCP: deflink: State change Stopped --Closed Sep 24 13:24:53 ns0 ppp[397]: tun0: CCP: deflink: State change Closed --Initial Sep 24 13:24:53 ns0 ppp[397]: tun0: Phase: deflink: open -lcp Sep 24 13:24:53 ns0 ppp[397]: tun0: IPCP: deflink: LayerDown: a.b.c.214 Sep 24 13:24:53 ns0 ppp[397]: tun0: IPCP: deflink: State change Opened --Starting Sep 24 13:24:53 ns0 ppp[397]: tun0: IPCP: deflink: LayerFinish. Sep 24 13:24:53 ns0 ppp[397]: tun0: IPCP: Connect time: 89 secs: 0 octets in, 0 octets out Sep 24 13:24:53 ns0 ppp[397]: tun0: IPCP: : 0 packets in, 0 packets out Sep 24 13:24:53 ns0 ppp[397]: tun0: IPCP: total 0 bytes/sec, peak 0 bytes/sec on Mon Sep 24 13:24:53 2001 Sep 24 13:24:53 ns0 ppp[397]: tun0: IPCP: deflink: State change Starting --Initial Sep 24 13:24:53 ns0 ppp[397]: tun0: Phase: bundle: Terminate Sep 24 13:24:55 ns0 ppp[397]: tun0: Phase: deflink: Carrier lost Sep 24 13:24:55 ns0 ppp[397]: tun0: LCP: deflink: State change Stopping --Starting Sep 24 13:24:55 ns0 ppp[397]: tun0: LCP: deflink: LayerFinish Sep 24 13:24:55 ns0 ppp[397]: tun0: LCP: deflink: State change Starting --Initial Sep 24 13:24:55 ns0 ppp[397]: tun0: Phase: deflink: Disconnected! Sep 24 13:24:55 ns0 ppp[397]: tun0: Phase: deflink: Connect time: 94 secs: 483 octets in, 485 octets out Sep 24 13:24:55 ns0 ppp[397]: tun0: Phase: deflink: : 20 packets in, 18 packets out Sep 24 13:24:55 ns0 ppp[397]: tun0: Phase: total 10 bytes/sec, peak 176 bytes/sec on Mon Sep 24 13:24:55 2001 Sep 24 13:24:55 ns0 ppp[425]: tun0: Phase: deflink: lcp -closed Sep 24 13:24:55 ns0 ppp[425]: tun0: Phase: bundle: Dead Sep 24 13:24:55 ns0 ppp[425]: tun0: Phase: PPP Terminated (normal). Sep 24 13:24:55 ns0 ppp[425]: tun0: Phase: Found interface ep1 for a.b.c.215 >>>Session#3 from user2: Sep 24 14:29:45 ns0 ppp[726]: Phase: Using interface: tun0 Sep 24 14:29:45 ns0 ppp[726]: Phase: deflink: Created in closed state Sep 24 14:29:45 ns0 ppp[726]: tun0: Command: ttyd3: set device /dev/ttyd3 Sep 24 14:29:45 ns0 ppp[726]: tun0: Command: ttyd3: set speed 57600 Sep 24 14:29:45 ns0 ppp[726]: tun0: Command: ttyd3: set cd 10 Sep 24 14:29:45 ns0 ppp[726]: tun0: Command: ttyd3: enable chap chap80 chap81 pap passwdauth Sep 24 14:29:45 ns0 ppp[726]: tun0: Warning: enable chap80: Ambiguous command Sep 24 14:29:45 ns0 ppp[726]: tun0: Warning: enable chap80: Failed 1 Sep 24 14:29:45 ns0 ppp[726]: tun0: Command: ttyd3: enable proxy Sep 24 14:29:45 ns0 ppp[726]: tun0: Command: ttyd3: set ifaddr a.b.c.214 a.b.c.215 255.255.255.224 Sep 24 14:29:45 ns0 ppp[726]: tun0: Command: ttyd3: enable dns Sep 24 14:29:45 ns0 ppp[726]: tun0: Phase: PPP Started (direct mode). Sep 24 14:29:45 ns0 ppp[726]: tun0: Phase: bundle: Establish Sep 24 14:29:45 ns0 ppp[726]: tun0: Phase: deflink: closed -opening Sep 24 14:29:45 ns0 ppp[726]: tun0: Phase: deflink: Connected! Sep 24 14:29:45 ns0 ppp[726]: tun0: Phase: deflink: opening -carrier Sep 24 14:29:46 ns0 ppp[726]: tun0: Phase: deflink: /dev/ttyd3: CD detected Sep 24 14:29:46 ns0 ppp[726]: tun0: Phase: deflink: carrier -lcp Sep 24 14:29:46 ns0 ppp[726]: tun0: LCP: FSM: Using "deflink" as a transport Sep 24 14:29:46 ns0 ppp[726]: tun0: LCP: deflink: State change Initial --Closed Sep 24 14:29:46 ns0 ppp[726]: tun0: LCP: deflink: State change Closed --Stopped Sep 24 14:29:47 ns0 ppp[726]: tun0: LCP: deflink: LayerStart Sep 24 14:29:47 ns0 ppp[726]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped Sep 24 14:29:47 ns0 ppp[726]: tun0: LCP: ACFCOMP[2] Sep 24 14:29:47 ns0 ppp[726]: tun0: LCP: PROTOCOMP[2] Sep 24 14:29:47 ns0 ppp[726]: tun0: LCP: ACCMAP[6] 0x00000000 Sep 24 14:29:47 ns0 ppp[726]: tun0: LCP: MRU[4] 1500 Sep 24 14:29:47 ns0 ppp[726]: tun0: LCP: MAGICNUM[6] 0x81951986 Sep 24 14:29:47 ns0 ppp[726]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05) Sep 24 14:29:47 ns0 ppp[726]: tun0: LCP: deflink: State change Stopped --Req-Sent Sep 24 14:29:47 ns0 ppp[726]: tun0: LCP: deflink: RecvConfigAck(1) state = Req-Sent Sep 24 14:29:47 ns0 ppp[726]: tun0: LCP: deflink: State change Req-Sent --Ack-Rcvd Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: deflink: RecvConfigReq(2) state = Ack-Rcvd Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: ACCMAP[6] 0x000a0000 Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: MAGICNUM[6] 0x006e1027 Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: PROTOCOMP[2] Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: ACFCOMP[2] Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: CALLBACK[3] CBCP Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: deflink: SendConfigRej(2) state = Ack-Rcvd Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: CALLBACK[3] CBCP Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: deflink: RecvConfigReq(3) state = Ack-Rcvd Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: ACCMAP[6] 0x000a0000 Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: MAGICNUM[6] 0x006e1027 Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: PROTOCOMP[2] Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: ACFCOMP[2] Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: deflink: SendConfigAck(3) state = Ack-Rcvd Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: ACCMAP[6] 0x000a0000 Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: MAGICNUM[6] 0x006e1027 Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: PROTOCOMP[2] Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: ACFCOMP[2] Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: deflink: State change Ack-Rcvd --Opened Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: deflink: LayerUp Sep 24 14:29:48 ns0 ppp[726]: tun0: LQM: deflink: LQR/ECHO LQR not negotiated Sep 24 14:29:48 ns0 ppp[726]: tun0: Phase: bundle: Authenticate Sep 24 14:29:48 ns0 ppp[726]: tun0: Phase: deflink: his = none, mine = CHAP 0x05 Sep 24 14:29:48 ns0 ppp[726]: tun0: Phase: Chap Output: CHALLENGE Sep 24 14:29:48 ns0 ppp[726]: tun0: Phase: Chap Input: RESPONSE (16 bytes from user2) Sep 24 14:29:48 ns0 ppp[726]: tun0: Phase: Chap Output: FAILURE Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: deflink: LayerDown Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: deflink: SendTerminateReq(2) state = Opened Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: deflink: State change Opened --Closing Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: deflink: RecvTerminateAck(2) state = Closing Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: deflink: LayerFinish Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: deflink: State change Closing --Closed Sep 24 14:29:48 ns0 ppp[726]: tun0: LCP: deflink: State change Closed --Initial Sep 24 14:29:48 ns0 ppp[726]: tun0: Phase: deflink: Disconnected! Sep 24 14:29:48 ns0 ppp[726]: tun0: Phase: deflink: Connect time: 3 secs: 260 octets in, 207 octets out Sep 24 14:29:48 ns0 ppp[726]: tun0: Phase: deflink: : 10 packets in, 6 packets out Sep 24 14:29:48 ns0 ppp[726]: tun0: Phase: total 155 bytes/sec, peak 16 bytes/sec on Mon Sep 24 14:29:48 2001 Sep 24 14:29:48 ns0 ppp[737]: tun0: Phase: deflink: lcp -closed Sep 24 14:29:48 ns0 ppp[737]: tun0: Phase: bundle: Dead Sep 24 14:29:48 ns0 ppp[737]: tun0: Phase: PPP Terminated (normal). To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-questions" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?007101c144e6$b846d060$ea2ce9c1>