From owner-freebsd-questions Wed May 17 14:28:19 2000 Delivered-To: freebsd-questions@freebsd.org Received: from helix.nih.gov (helix.nih.gov [128.231.2.3]) by hub.freebsd.org (Postfix) with ESMTP id 9D98D37BCA1 for ; Wed, 17 May 2000 14:28:09 -0700 (PDT) (envelope-from crtb@helix.nih.gov) Received: (from crtb@localhost) by helix.nih.gov (8.9.3/8.9.3) id RAA6781825; Wed, 17 May 2000 17:28:00 -0400 (EDT) Date: Wed, 17 May 2000 17:28:00 -0400 (EDT) From: Chuck Bacon Message-Id: <200005172128.RAA6781825@helix.nih.gov> To: fug-washdc@sytex.net, questions@freebsd.org Subject: pppd problem Sender: owner-freebsd-questions@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.ORG Problem with my ISP and PPP. FreeBSD 3.2R. Last Jan., I used a modem with kernel-mode pppd to access my ISP, CapeInternet. The appended logfile shows successful setup at that time. A week ago, lightning hit my ISDN box, so I'm back to the modem. This time, the connection never could get through. pppd's LCP rejected the same config request, but this time, the ISP kept resending it. Last Jan., that same config request was not repeated once it was rejected. The May 11 entries below show a connection featuring a loop around the rejected option until it timed out. The request appears to be of type 19, not listed as one of the options in the source. The six hex bytes <13 09 03 00 c0 7b 80 16 7f> in the rcvd-ConfReq and sent-ConfRej messages apparently stand for protocol 19 (0x13), 9 bytes overall, followed by 7 bytes of unknown meaning. Can someone identify what's happening? The ISP's tech support has been surprisingly knowledgeable, but couldn't help with this. He claimed that since Jan., no software changes have been made to their dialup server. Likewise, I'm still running the same FreeBSD 3.2R and same kernel. FWIW, my wife's Win98 gets through every time on its internal modem. Too bad it can't route; if it could, the FreeBSD system could go that way! Chuck Bacon - crtb@capecod.net PS: I'm currently running from helix, via Kermit. I can receive mail at either address, but would prefer . Thank you. Jan 29 10:18:14 capecod pppd[28235]: pppd 2.3.5 started by crtb, uid 1152 Jan 29 10:18:15 capecod chat[28240]: abort on (BUSY) Jan 29 10:18:15 capecod chat[28240]: send (ATZ^M) Jan 29 10:18:15 capecod chat[28240]: expect (OK) Jan 29 10:18:15 capecod chat[28240]: ATZ^M^M Jan 29 10:18:15 capecod chat[28240]: OK Jan 29 10:18:15 capecod chat[28240]: -- got it Jan 29 10:18:15 capecod chat[28240]: send (ATD403-1111^M) Jan 29 10:18:16 capecod chat[28240]: expect (CONNECT) Jan 29 10:18:16 capecod chat[28240]: ^M Jan 29 10:18:41 capecod chat[28240]: ATD403-1111^M^M Jan 29 10:18:41 capecod chat[28240]: CONNECT Jan 29 10:18:41 capecod chat[28240]: -- got it Jan 29 10:18:41 capecod chat[28240]: send (^M) Jan 29 10:18:41 capecod chat[28240]: expect (ogin:) Jan 29 10:18:41 capecod chat[28240]: 49333/ARQ/V90/LAPM/V42BIS^M Jan 29 10:18:42 capecod chat[28240]: ^M Jan 29 10:18:42 capecod chat[28240]: Jan 29 10:18:42 capecod last message repeated 23 times Jan 29 10:18:42 capecod chat[28240]: Level 3 Comm nas14.bos1.Level3.net UQKT2^M Jan 29 10:18:42 capecod chat[28240]: ^M Jan 29 10:18:42 capecod chat[28240]: ^M Jan 29 10:18:42 capecod chat[28240]: login: Jan 29 10:18:42 capecod chat[28240]: -- got it Jan 29 10:18:42 capecod chat[28240]: send (cbacon^M) Jan 29 10:18:42 capecod chat[28240]: expect (sword:) Jan 29 10:18:42 capecod chat[28240]: cbacon^M Jan 29 10:18:42 capecod chat[28240]: Password: Jan 29 10:18:42 capecod chat[28240]: -- got it Jan 29 10:18:42 capecod chat[28240]: send (mypassword^M) Jan 29 10:18:42 capecod pppd[28235]: Serial connection established. Jan 29 10:18:43 capecod pppd[28235]: Using interface ppp0 Jan 29 10:18:43 capecod pppd[28235]: Connect: ppp0 <--> /dev/cuaa1 Jan 29 10:18:43 capecod pppd[28235]: sent [LCP ConfReq id=0x1 ] Jan 29 10:18:43 capecod pppd[28235]: rcvd [LCP ConfAck id=0x1 ] Jan 29 10:18:46 capecod pppd[28235]: sent [LCP ConfReq id=0x1 ] Jan 29 10:18:46 capecod pppd[28235]: rcvd [LCP ConfAck id=0x1 ] Jan 29 10:18:47 capecod pppd[28235]: rcvd [LCP ConfReq id=0x1 < 11 04 05 dc> < 13 09 03 00 c0 7b 7a 97 f3>] Jan 29 10:18:47 capecod pppd[28235]: sent [LCP ConfRej id=0x1 < 11 04 05 dc> < 13 09 03 00 c0 7b 7a 97 f3>] Jan 29 10:18:47 capecod pppd[28235]: rcvd [LCP ConfReq id=0x2 ] Jan 29 10:18:47 capecod pppd[28235]: sent [LCP ConfAck id=0x2 ] Jan 29 10:18:47 capecod pppd[28235]: sent [IPCP ConfReq id=0x1 ] Jan 29 10:18:47 capecod pppd[28235]: rcvd [IPCP ConfReq id=0x1 ] Jan 29 10:18:47 capecod pppd[28235]: sent [IPCP ConfAck id=0x1 ] Jan 29 10:18:47 capecod pppd[28235]: rcvd [IPCP ConfNak id=0x1 ] Jan 29 10:18:47 capecod pppd[28235]: sent [IPCP ConfReq id=0x2 ] Jan 29 10:18:47 capecod pppd[28235]: rcvd [IPCP ConfAck id=0x2 ] Jan 29 10:18:47 capecod pppd[28235]: Couldn't add default route: File exists Jan 29 10:18:47 capecod pppd[28235]: local IP address 63.211.185.23 Jan 29 10:18:47 capecod pppd[28235]: remote IP address 209.244.43.206 Jan 29 11:05:36 capecod pppd[28235]: Terminating on signal 15. Jan 29 11:05:36 capecod pppd[28235]: sent [LCP TermReq id=0x2 "User request"] Jan 29 11:05:36 capecod pppd[28235]: rcvd [LCP TermAck id=0x2] Jan 29 11:05:36 capecod pppd[28235]: Connection terminated, connected for 47 minutes Jan 29 11:05:36 capecod pppd[28235]: Exit. ================= May 11 11:24:20 capecod pppd[1151]: pppd 2.3.5 started by crtb, uid 0 May 11 11:24:21 capecod chat[1155]: abort on (BUSY) May 11 11:24:22 capecod chat[1155]: send (ATZ^M) May 11 11:24:22 capecod chat[1155]: expect (OK) May 11 11:24:22 capecod chat[1155]: ATZ^M^M May 11 11:24:22 capecod chat[1155]: OK May 11 11:24:22 capecod chat[1155]: -- got it May 11 11:24:22 capecod chat[1155]: send (ATD403-1111^M) May 11 11:24:22 capecod chat[1155]: expect (CONNECT) May 11 11:24:22 capecod chat[1155]: ^M May 11 11:24:48 capecod chat[1155]: ATD403-1111^M^M May 11 11:24:48 capecod chat[1155]: CONNECT May 11 11:24:48 capecod chat[1155]: -- got it May 11 11:24:48 capecod chat[1155]: send (^M) May 11 11:24:48 capecod chat[1155]: expect (ogin:) May 11 11:24:48 capecod chat[1155]: 40000/ARQ/V90/LAPM/V42BIS^M May 11 11:24:48 capecod chat[1155]: ^M May 11 11:24:48 capecod chat[1155]: May 11 11:24:48 capecod last message repeated 23 times May 11 11:24:48 capecod chat[1155]: Level 3 Comm nas13.bos1 UQKT2^M May 11 11:24:48 capecod chat[1155]: ^M May 11 11:24:48 capecod chat[1155]: ^M May 11 11:24:48 capecod chat[1155]: login: May 11 11:24:48 capecod chat[1155]: -- got it May 11 11:24:48 capecod chat[1155]: send (cbacon^M) May 11 11:24:49 capecod chat[1155]: expect (sword:) May 11 11:24:49 capecod chat[1155]: cbacon^M May 11 11:24:49 capecod chat[1155]: Password: May 11 11:24:49 capecod chat[1155]: -- got it May 11 11:24:49 capecod chat[1155]: send (mypassword^M) May 11 11:24:49 capecod pppd[1151]: Serial connection established. May 11 11:24:50 capecod pppd[1151]: Using interface ppp0 May 11 11:24:50 capecod pppd[1151]: Connect: ppp0 <--> /dev/cuaa1 May 11 11:24:50 capecod pppd[1151]: sent [LCP ConfReq id=0x1 ] May 11 11:24:53 capecod pppd[1151]: sent [LCP ConfReq id=0x1 ] May 11 11:24:53 capecod pppd[1151]: rcvd [LCP ConfReq id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:24:53 capecod pppd[1151]: sent [LCP ConfRej id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:24:56 capecod pppd[1151]: sent [LCP ConfReq id=0x1 ] May 11 11:24:57 capecod pppd[1151]: rcvd [LCP ConfReq id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:24:57 capecod pppd[1151]: sent [LCP ConfRej id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:24:59 capecod pppd[1151]: sent [LCP ConfReq id=0x1 ] May 11 11:25:01 capecod pppd[1151]: rcvd [LCP ConfReq id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:01 capecod pppd[1151]: sent [LCP ConfRej id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:02 capecod pppd[1151]: sent [LCP ConfReq id=0x1 ] May 11 11:25:05 capecod pppd[1151]: sent [LCP ConfReq id=0x1 ] May 11 11:25:05 capecod pppd[1151]: rcvd [LCP ConfReq id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:05 capecod pppd[1151]: sent [LCP ConfRej id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:08 capecod pppd[1151]: sent [LCP ConfReq id=0x1 ] May 11 11:25:09 capecod pppd[1151]: rcvd [LCP ConfReq id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:09 capecod pppd[1151]: sent [LCP ConfRej id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:11 capecod pppd[1151]: sent [LCP ConfReq id=0x1 ] May 11 11:25:13 capecod pppd[1151]: rcvd [LCP ConfReq id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:13 capecod pppd[1151]: sent [LCP ConfRej id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:14 capecod pppd[1151]: sent [LCP ConfReq id=0x1 ] May 11 11:25:17 capecod pppd[1151]: sent [LCP ConfReq id=0x1 ] May 11 11:25:17 capecod pppd[1151]: rcvd [LCP ConfReq id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:17 capecod pppd[1151]: sent [LCP ConfRej id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:20 capecod pppd[1151]: LCP: timeout sending Config-Requests May 11 11:25:21 capecod pppd[1151]: rcvd [LCP ConfReq id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:21 capecod pppd[1151]: sent [LCP ConfReq id=0x2 ] May 11 11:25:21 capecod pppd[1151]: sent [LCP ConfRej id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:24 capecod pppd[1151]: sent [LCP ConfReq id=0x2 ] May 11 11:25:25 capecod pppd[1151]: rcvd [LCP ConfReq id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:25 capecod pppd[1151]: sent [LCP ConfRej id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:27 capecod pppd[1151]: sent [LCP ConfReq id=0x2 ] May 11 11:25:29 capecod pppd[1151]: rcvd [LCP ConfReq id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:29 capecod pppd[1151]: sent [LCP ConfRej id=0x1 < 13 09 03 00 c0 7b 80 3f 10>] May 11 11:25:30 capecod pppd[1151]: sent [LCP ConfReq id=0x2 ] May 11 11:25:48 capecod last message repeated 6 times May 11 11:25:51 capecod pppd[1151]: LCP: timeout sending Config-Requests May 11 11:25:54 capecod pppd[1151]: Modem hangup, connected for 1 minutes May 11 11:25:54 capecod pppd[1151]: Connection terminated, connected for 1 minutes May 11 11:25:55 capecod pppd[1151]: Exit. To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-questions" in the body of the message