Date: Fri, 21 Nov 2003 02:34:42 -0000 From: "Daniel O'Connor" <doconnor@gsoft.com.au> To: freebsd-stable@freebsd.org Cc: brian@Awfulhak.org Subject: Strange intermittent PPP problem Message-ID: <200311211304.11267.doconnor@gsoft.com.au>
next in thread | raw e-mail | index | archive | help
Hi, We have a radar system sited at Rarotonga and it has a modem connection to a (the?) local ISP (Oyster). It used to work fine, but in the last 2 weeks or so it has been having problems dialling up at it's usual time (1am UT). The ISP claims no changes have been made at that time. If I ring the system and tell it to call back it seems to connect fine. Here is a log extract from a dud session -> Nov 16 01:00:35 rarotonga ppp[67972]: tun0: LCP: FSM: Using "deflink" as a transport Nov 16 01:00:35 rarotonga ppp[67972]: tun0: LCP: deflink: State change Initial --> Closed Nov 16 01:00:35 rarotonga ppp[67972]: tun0: LCP: deflink: State change Closed --> Stopped Nov 16 01:00:36 rarotonga ppp[67972]: tun0: LCP: deflink: LayerStart Nov 16 01:00:36 rarotonga ppp[67972]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped Nov 16 01:00:36 rarotonga ppp[67972]: tun0: LCP: ACFCOMP[2] Nov 16 01:00:36 rarotonga ppp[67972]: tun0: LCP: PROTOCOMP[2] Nov 16 01:00:36 rarotonga ppp[67972]: tun0: LCP: ACCMAP[6] 0x00000000 Nov 16 01:00:36 rarotonga ppp[67972]: tun0: LCP: MRU[4] 1500 Nov 16 01:00:36 rarotonga ppp[67972]: tun0: LCP: MAGICNUM[6] 0x24cb74f5 Nov 16 01:00:36 rarotonga ppp[67972]: tun0: LCP: deflink: State change Stopped --> Req-Sent Nov 16 01:00:39 rarotonga ppp[67972]: tun0: LCP: deflink: SendConfigReq(1) state = Req-Sent Nov 16 01:00:39 rarotonga ppp[67972]: tun0: LCP: ACFCOMP[2] Nov 16 01:00:39 rarotonga ppp[67972]: tun0: LCP: PROTOCOMP[2] Nov 16 01:00:39 rarotonga ppp[67972]: tun0: LCP: ACCMAP[6] 0x00000000 Nov 16 01:00:39 rarotonga ppp[67972]: tun0: LCP: MRU[4] 1500 Nov 16 01:00:39 rarotonga ppp[67972]: tun0: LCP: MAGICNUM[6] 0x24cb74f5 Nov 16 01:00:42 rarotonga ppp[67972]: tun0: LCP: deflink: SendConfigReq(1) state = Req-Sent Nov 16 01:00:42 rarotonga ppp[67972]: tun0: LCP: ACFCOMP[2] Nov 16 01:00:42 rarotonga ppp[67972]: tun0: LCP: PROTOCOMP[2] Nov 16 01:00:42 rarotonga ppp[67972]: tun0: LCP: ACCMAP[6] 0x00000000 Nov 16 01:00:42 rarotonga ppp[67972]: tun0: LCP: MRU[4] 1500 Nov 16 01:00:42 rarotonga ppp[67972]: tun0: LCP: MAGICNUM[6] 0x24cb74f5 Nov 16 01:00:45 rarotonga ppp[67972]: tun0: LCP: deflink: SendConfigReq(1) state = Req-Sent Nov 16 01:00:45 rarotonga ppp[67972]: tun0: LCP: ACFCOMP[2] Nov 16 01:00:45 rarotonga ppp[67972]: tun0: LCP: PROTOCOMP[2] Nov 16 01:00:45 rarotonga ppp[67972]: tun0: LCP: ACCMAP[6] 0x00000000 Nov 16 01:00:45 rarotonga ppp[67972]: tun0: LCP: MRU[4] 1500 Nov 16 01:00:45 rarotonga ppp[67972]: tun0: LCP: MAGICNUM[6] 0x24cb74f5 Nov 16 01:00:48 rarotonga ppp[67972]: tun0: LCP: deflink: SendConfigReq(1) state = Req-Sent Nov 16 01:00:48 rarotonga ppp[67972]: tun0: LCP: ACFCOMP[2] Nov 16 01:00:48 rarotonga ppp[67972]: tun0: LCP: PROTOCOMP[2] Nov 16 01:00:48 rarotonga ppp[67972]: tun0: LCP: ACCMAP[6] 0x00000000 Nov 16 01:00:48 rarotonga ppp[67972]: tun0: LCP: MRU[4] 1500 Nov 16 01:00:48 rarotonga ppp[67972]: tun0: LCP: MAGICNUM[6] 0x24cb74f5 Nov 16 01:00:48 rarotonga ppp[67972]: tun0: Warning: Packet too large (4102), discarding. Nov 16 01:00:51 rarotonga ppp[67972]: tun0: LCP: deflink: LayerFinish Nov 16 01:00:51 rarotonga ppp[67972]: tun0: LCP: deflink: State change Req-Sent --> Stopped Nov 16 01:00:51 rarotonga ppp[67972]: tun0: LCP: deflink: State change Stopped --> Closed Nov 16 01:00:51 rarotonga ppp[67972]: tun0: LCP: deflink: State change Closed --> Initial Nov 16 01:00:52 rarotonga ppp[67972]: tun0: Phase: deflink: Disconnected! And from a working session -> Nov 18 23:09:34 rarotonga ppp[64554]: tun0: LCP: FSM: Using "deflink" as a transport Nov 18 23:09:34 rarotonga ppp[64554]: tun0: LCP: deflink: State change Initial --> Closed Nov 18 23:09:34 rarotonga ppp[64554]: tun0: LCP: deflink: State change Closed --> Stopped Nov 18 23:09:35 rarotonga ppp[64554]: tun0: LCP: deflink: LayerStart Nov 18 23:09:35 rarotonga ppp[64554]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped Nov 18 23:09:35 rarotonga ppp[64554]: tun0: LCP: ACFCOMP[2] Nov 18 23:09:35 rarotonga ppp[64554]: tun0: LCP: PROTOCOMP[2] Nov 18 23:09:35 rarotonga ppp[64554]: tun0: LCP: ACCMAP[6] 0x00000000 Nov 18 23:09:35 rarotonga ppp[64554]: tun0: LCP: MRU[4] 1500 Nov 18 23:09:35 rarotonga ppp[64554]: tun0: LCP: MAGICNUM[6] 0x15a16783 Nov 18 23:09:35 rarotonga ppp[64554]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP) Nov 18 23:09:35 rarotonga ppp[64554]: tun0: LCP: deflink: State change Stopped --> Req-Sent Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: deflink: RecvConfigReq(1) state = Req-Sent Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: ACFCOMP[2] Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: PROTOCOMP[2] Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: ACCMAP[6] 0x00000000 Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: MRU[4] 1500 Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: MAGICNUM[6] 0xac742603 Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: deflink: SendConfigAck(1) state = Req-Sent Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: ACFCOMP[2] Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: PROTOCOMP[2] Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: ACCMAP[6] 0x00000000 Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: MRU[4] 1500 Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: MAGICNUM[6] 0xac742603 Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: deflink: State change Req-Sent --> Ack-Sent Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: deflink: RecvConfigAck(1) state = Ack-Sent Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: ACFCOMP[2] Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: PROTOCOMP[2] Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: ACCMAP[6] 0x00000000 Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: MRU[4] 1500 Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: MAGICNUM[6] 0x15a16783 Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP) Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: deflink: State change Ack-Sent --> Opened Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: deflink: LayerUp Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: deflink: SendIdent(0) state = Opened Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: MAGICNUM 15a16783 Nov 18 23:09:36 rarotonga ppp[64554]: tun0: LCP: TEXT user-ppp 3.1 (built Jan 9 2003) It seems like one end (dunno which end is which for these messages) isn't responding properly..? Also, the size it complains about is right on the edge of what the code checks for - perhaps it's an off by one error? The PPP is a little old, but I am somewhat reluctant to upgrade it given it's the sole means of communication :) Anyone got any hints? -- Daniel O'Connor software and network engineer for Genesis Software - http://www.gsoft.com.au "The nice thing about standards is that there are so many of them to choose from." -- Andrew Tanenbaum GPG Fingerprint - 9A8C 569F 685A D928 5140 AE4B 319B 41F4 5D17 FDD5
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?200311211304.11267.doconnor>