Powerfail/reboot, now inbound ppp not working

stan stanb at panix.com
Sun Feb 5 12:58:55 PST 2006


I've got a ppp over ssh tunnel from work, that's initiated from the remote end
by a cron task.

Friday night we lost power at our house. Most of the machines are on UPS's, so I'm
failry certain I got them shutdown normally (it was the middle of the night
so I might have messed up).

In any case, the tunnel is not working now. I see that the remote end is atempting 
to connect. Here's a snippet from the ppp.log file:


Feb  5 15:39:03 black ppp[424]: Phase: Using interface: tun0
Feb  5 15:39:03 black ppp[424]: Phase: deflink: Created in closed state
Feb  5 15:39:03 black ppp[424]: tun0: Command: default: ident user-ppp VERSION (built COMPILATIONDATE)
Feb  5 15:39:03 black ppp[424]: tun0: Command: default: set server +3000 ********
Feb  5 15:39:03 black ppp[424]: tun0: Phase: Listening at port 3000.
Feb  5 15:39:03 black ppp[424]: tun0: Command: default: set device /dev/cuaa1
Feb  5 15:39:03 black ppp[424]: tun0: Command: default: set redial 1
Feb  5 15:39:03 black ppp[424]: tun0: Command: default: set speed 115200
Feb  5 15:39:03 black ppp[424]: tun0: Command: default: set dial ABORT BUSY ABORT NO\sCARRIER TIMEOUT 5            "" AT OK-AT-OK AT+FCLASS=0 OK ATE1Q0L2M1 OK \dATDT\T TIMEOUT 40 CONNECT
Feb  5 15:39:03 black ppp[424]: tun0: Command: default: set timeout 180
Feb  5 15:39:03 black ppp[424]: tun0: Command: default: enable dns
Feb  5 15:39:03 black ppp[424]: tun0: Command: wvpn: set timeout 0
Feb  5 15:39:03 black ppp[424]: tun0: Command: wvpn: set ifaddr 192.168.3.1 192.168.4.1
Feb  5 15:39:03 black ppp[424]: tun0: Command: wvpn: add XXX.85.0.0 255.255.0.0 HISADDR
Feb  5 15:39:03 black ppp[424]: tun0: Phase: PPP Started (direct mode).
Feb  5 15:39:03 black ppp[424]: tun0: Phase: bundle: Establish
Feb  5 15:39:03 black ppp[424]: tun0: Phase: deflink: closed -> opening
Feb  5 15:39:03 black ppp[424]: tun0: Phase: deflink: Connected!
Feb  5 15:39:03 black ppp[424]: tun0: Phase: deflink: opening -> carrier
Feb  5 15:39:03 black ppp[424]: tun0: Phase: deflink: carrier -> lcp
Feb  5 15:39:03 black ppp[424]: tun0: LCP: FSM: Using "deflink" as a transport
Feb  5 15:39:03 black ppp[424]: tun0: LCP: deflink: State change Initial --> Closed
Feb  5 15:39:03 black ppp[424]: tun0: LCP: deflink: State change Closed --> Stopped
Feb  5 15:39:03 black ppp[424]: tun0: LCP: deflink: RecvConfigReq(207) state = Stopped
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  ACFCOMP[2]
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  PROTOCOMP[2]
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  ACCMAP[6] 0x00000000
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  MRU[4] 1500
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  MAGICNUM[6] 0xd3f1ae61
Feb  5 15:39:03 black ppp[424]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  ACFCOMP[2]
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  PROTOCOMP[2]
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  ACCMAP[6] 0x00000000
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  MRU[4] 1500
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  MAGICNUM[6] 0x0f111e73
Feb  5 15:39:03 black ppp[424]: tun0: LCP: deflink: SendConfigAck(207) state = Stopped
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  ACFCOMP[2]
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  PROTOCOMP[2]
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  ACCMAP[6] 0x00000000
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  MRU[4] 1500
Feb  5 15:39:03 black ppp[424]: tun0: LCP:  MAGICNUM[6] 0xd3f1ae61
Feb  5 15:39:03 black ppp[424]: tun0: LCP: deflink: LayerStart
Feb  5 15:39:03 black ppp[424]: tun0: LCP: deflink: State change Stopped --> Ack-Sent
Feb  5 15:39:04 black ppp[424]: tun0: LCP: deflink: RecvConfigAck(1) state = Ack-Sent
Feb  5 15:39:04 black ppp[424]: tun0: LCP:  ACFCOMP[2]
Feb  5 15:39:04 black ppp[424]: tun0: LCP:  PROTOCOMP[2]
Feb  5 15:39:04 black ppp[424]: tun0: LCP:  ACCMAP[6] 0x00000000
Feb  5 15:39:04 black ppp[424]: tun0: LCP:  MRU[4] 1500
Feb  5 15:39:04 black ppp[424]: tun0: LCP:  MAGICNUM[6] 0x0f111e73
Feb  5 15:39:04 black ppp[424]: tun0: LCP: deflink: State change Ack-Sent --> Opened
Feb  5 15:39:04 black ppp[424]: tun0: LCP: deflink: LayerUp
Feb  5 15:39:04 black ppp[424]: tun0: LCP: deflink: SendIdent(0) state = Opened
Feb  5 15:39:04 black ppp[424]: tun0: LCP:  MAGICNUM 0f111e73
Feb  5 15:39:04 black ppp[424]: tun0: LCP:  TEXT user-ppp 3.2 (built Nov  6 2005)
Feb  5 15:39:04 black ppp[424]: tun0: CCP: FSM: Using "deflink" as a transport
Feb  5 15:39:04 black ppp[424]: tun0: CCP: deflink: State change Initial --> Closed
Feb  5 15:39:04 black ppp[424]: tun0: CCP: deflink: LayerStart.
Feb  5 15:39:04 black ppp[424]: tun0: CCP: MPPE: Not usable without CHAP81
Feb  5 15:39:04 black ppp[424]: tun0: CCP: deflink: SendConfigReq(1) state = Closed
Feb  5 15:39:04 black ppp[424]: tun0: CCP:  DEFLATE[4] win 15
Feb  5 15:39:04 black ppp[424]: tun0: CCP:  PRED1[2] 
Feb  5 15:39:04 black ppp[424]: tun0: CCP: deflink: State change Closed --> Req-Sent
Feb  5 15:39:04 black ppp[424]: tun0: Phase: deflink: lcp -> open
Feb  5 15:39:04 black ppp[424]: tun0: Phase: bundle: Network
Feb  5 15:39:04 black ppp[424]: tun0: IPCP: FSM: Using "deflink" as a transport
Feb  5 15:39:04 black ppp[424]: tun0: IPCP: deflink: State change Initial --> Closed
Feb  5 15:39:04 black ppp[424]: tun0: IPCP: deflink: LayerStart.
Feb  5 15:39:04 black ppp[424]: tun0: IPCP: deflink: SendConfigReq(1) state = Closed
Feb  5 15:39:04 black ppp[424]: tun0: IPCP:  IPADDR[6] 192.168.3.1
Feb  5 15:39:04 black ppp[424]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Feb  5 15:39:04 black ppp[424]: tun0: IPCP:  PRIDNS[6] 205.159.77.234
Feb  5 15:39:04 black ppp[424]: tun0: IPCP:  SECDNS[6] 255.255.255.255
Feb  5 15:39:04 black ppp[424]: tun0: IPCP: deflink: State change Closed --> Req-Sent
Feb  5 15:39:04 black ppp[424]: tun0: LCP: deflink: RecvProtocolRej(208) state = Opened
Feb  5 15:39:04 black ppp[424]: tun0: LCP: deflink: -- Protocol 0x80fd (Compression Control Protocol) was rejected!
Feb  5 15:39:04 black ppp[424]: tun0: CCP: deflink: State change Req-Sent --> Stopped
Feb  5 15:39:07 black ppp[424]: tun0: IPCP: deflink: SendConfigReq(1) state = Req-Sent
Feb  5 15:39:07 black ppp[424]: tun0: IPCP:  IPADDR[6] 192.168.3.1
Feb  5 15:39:07 black ppp[424]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Feb  5 15:39:07 black ppp[424]: tun0: IPCP:  PRIDNS[6] 205.159.77.234
Feb  5 15:39:07 black ppp[424]: tun0: IPCP:  SECDNS[6] 255.255.255.255
Feb  5 15:39:10 black ppp[424]: tun0: IPCP: deflink: SendConfigReq(1) state = Req-Sent
Feb  5 15:39:10 black ppp[424]: tun0: IPCP:  IPADDR[6] 192.168.3.1
Feb  5 15:39:10 black ppp[424]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Feb  5 15:39:10 black ppp[424]: tun0: IPCP:  PRIDNS[6] 205.159.77.234
Feb  5 15:39:10 black ppp[424]: tun0: IPCP:  SECDNS[6] 255.255.255.255
Feb  5 15:39:13 black ppp[424]: tun0: IPCP: deflink: SendConfigReq(1) state = Req-Sent
Feb  5 15:39:13 black ppp[424]: tun0: IPCP:  IPADDR[6] 192.168.3.1
Feb  5 15:39:13 black ppp[424]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Feb  5 15:39:13 black ppp[424]: tun0: IPCP:  PRIDNS[6] 205.159.77.234
Feb  5 15:39:13 black ppp[424]: tun0: IPCP:  SECDNS[6] 255.255.255.255
Feb  5 15:39:16 black ppp[424]: tun0: IPCP: deflink: SendConfigReq(1) state = Req-Sent
Feb  5 15:39:16 black ppp[424]: tun0: IPCP:  IPADDR[6] 192.168.3.1
Feb  5 15:39:16 black ppp[424]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Feb  5 15:39:16 black ppp[424]: tun0: IPCP:  PRIDNS[6] 205.159.77.234
Feb  5 15:39:16 black ppp[424]: tun0: IPCP:  SECDNS[6] 255.255.255.255
Feb  5 15:39:19 black ppp[424]: tun0: IPCP: deflink: LayerFinish.
Feb  5 15:39:19 black ppp[424]: tun0: IPCP: Connect time: 15 secs: 0 octets in, 0 octets out
Feb  5 15:39:19 black ppp[424]: tun0: IPCP: 0 packets in, 0 packets out
Feb  5 15:39:19 black ppp[424]: tun0: IPCP:  total 0 bytes/sec, peak 0 bytes/sec on Sun Feb  5 15:39:04 2006
Feb  5 15:39:19 black ppp[424]: tun0: IPCP: deflink: State change Req-Sent --> Stopped

It's been a _long_ time since I set this up, and even longer since I used ppp
on a regular bassis.

Can anyone see what's causing this to fail?


The 205.159.77.xxx is my home network address, and the 192.168.x.x are the
2 ends of the tunnel.
-- 
U.S. Encouraged by Vietnam Vote - Officials Cite 83% Turnout Despite Vietcong Terror 
- New York Times 9/3/1967


More information about the freebsd-questions mailing list