Project

General

Profile

Actions
Copy link

Bug #3624

closed

"ppp: OpenConfFile: Can't open file '/var/etc/mpd_wan.conf': No such file or directory"

Added by Vladimir Suhhanov about 11 years ago. Updated about 9 years ago.

Status:
Closed
Priority:
Normal
Assignee:
-
Category:
-
Target version:
-
Start date:
04/23/2014
Due date:
% Done:

0%

Estimated time:
Plus Target Version:
Release Notes:
Affected Version:
Affected Architecture:
i386

Description

I've got this error "ppp: OpenConfFile: Can't open file '/var/etc/mpd_wan.conf': No such file or directory". No connection, manually reconnected (via WebConfig).
This is similar behavior as described here - https://forum.pfsense.org/index.php?topic=74172.0
So it looks like the problem is not "solved".
And as you can see in log, pf successfully reconnected before this error (several times).

Apr 22 05:55:16     ppp: [wan] IFACE: Rename interface ng0 to pppoe0
Apr 22 05:55:16     ppp: [wan] IFACE: Up event
Apr 22 05:55:14     ppp: [wan] xx.xx.xx.xx -> xx.xx.xx.xx
Apr 22 05:55:14     ppp: [wan] IPCP: LayerUp
Apr 22 05:55:14     ppp: [wan] IPCP: state change Ack-Sent --> Opened
Apr 22 05:55:14     ppp: [wan] SECDNS xx.xx.xx.34
Apr 22 05:55:14     ppp: [wan] PRIDNS xx.xx.0.33
Apr 22 05:55:14     ppp: [wan] IPADDR xx.xx.xx.xx
Apr 22 05:55:14     ppp: [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Apr 22 05:55:14     ppp: [wan] SECDNS xx.xx.xx.34
Apr 22 05:55:14     ppp: [wan] PRIDNS xx.xx.0.33
Apr 22 05:55:14     ppp: [wan] IPADDR xx.xx.xx.xx
Apr 22 05:55:14     ppp: [wan] IPCP: SendConfigReq #3
Apr 22 05:55:14     ppp: [wan] SECDNS xx.xx.xx.34
Apr 22 05:55:14     ppp: [wan] PRIDNS xx.xx.0.33
Apr 22 05:55:14     ppp: [wan] xx.xx.xx.xx is OK
Apr 22 05:55:14     ppp: [wan] IPADDR xx.xx.xx.xx
Apr 22 05:55:14     ppp: [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Apr 22 05:55:14     ppp: [wan] IPV6CP: LayerFinish
Apr 22 05:55:14     ppp: [wan] IPV6CP: state change Req-Sent --> Stopped
Apr 22 05:55:14     ppp: [wan] IPV6CP: protocol was rejected by peer
Apr 22 05:55:14     ppp: [wan_link0] LCP: protocol IPV6CP was rejected
Apr 22 05:55:14     ppp: [wan_link0] LCP: rec'd Protocol Reject #2 (Opened)
Apr 22 05:55:14     ppp: [wan] SECDNS 0.0.0.0
Apr 22 05:55:14     ppp: [wan] PRIDNS 0.0.0.0
Apr 22 05:55:14     ppp: [wan] IPADDR 0.0.0.0
Apr 22 05:55:14     ppp: [wan] IPCP: SendConfigReq #2
Apr 22 05:55:14     ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Apr 22 05:55:14     ppp: [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Apr 22 05:55:14     ppp: [wan] IPCP: state change Req-Sent --> Ack-Sent
Apr 22 05:55:14     ppp: [wan] IPADDR xx.xx.xx.xx
Apr 22 05:55:14     ppp: [wan] IPCP: SendConfigAck #1
Apr 22 05:55:14     ppp: [wan] xx.xx.xx.xx is OK
Apr 22 05:55:14     ppp: [wan] IPADDR xx.xx.xx.xx
Apr 22 05:55:14     ppp: [wan] IPCP: rec'd Configure Request #1 (Req-Sent)
Apr 22 05:55:14     ppp: [wan] IPV6CP: SendConfigReq #1
Apr 22 05:55:14     ppp: [wan] IPV6CP: state change Starting --> Req-Sent
Apr 22 05:55:14     ppp: [wan] IPV6CP: Up event
Apr 22 05:55:14     ppp: [wan] SECDNS 0.0.0.0
Apr 22 05:55:14     ppp: [wan] PRIDNS 0.0.0.0
Apr 22 05:55:14     ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Apr 22 05:55:14     ppp: [wan] IPADDR 0.0.0.0
Apr 22 05:55:14     ppp: [wan] IPCP: SendConfigReq #1
Apr 22 05:55:14     ppp: [wan] IPCP: state change Starting --> Req-Sent
Apr 22 05:55:14     ppp: [wan] IPCP: Up event
Apr 22 05:55:14     ppp: [wan] IPV6CP: LayerStart
Apr 22 05:55:14     ppp: [wan] IPV6CP: state change Initial --> Starting
Apr 22 05:55:14     ppp: [wan] IPV6CP: Open event
Apr 22 05:55:14     ppp: [wan] IPCP: LayerStart
Apr 22 05:55:14     ppp: [wan] IPCP: state change Initial --> Starting
Apr 22 05:55:14     ppp: [wan] IPCP: Open event
Apr 22 05:55:14     ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Apr 22 05:55:14     ppp: [wan_link0] Link: Join bundle "wan" 
Apr 22 05:55:14     ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Apr 22 05:55:14     ppp: [wan_link0] LCP: authorization successful
Apr 22 05:55:14     ppp: [wan_link0] CHAP: rec'd SUCCESS #1 len: 4
Apr 22 05:55:14     ppp: [wan_link0] CHAP: sending RESPONSE #1 len: 33
Apr 22 05:55:14     ppp: [wan_link0] CHAP: Using authname "someISPusername" 
Apr 22 05:55:14     ppp: [wan_link0] Name: "k18-29-236" 
Apr 22 05:55:14     ppp: [wan_link0] CHAP: rec'd CHALLENGE #1 len: 31
Apr 22 05:55:14     ppp: [wan_link0] LCP: LayerUp
Apr 22 05:55:14     ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Apr 22 05:55:14     ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
Apr 22 05:55:14     ppp: [wan_link0] MAGICNUM f28ea0a0
Apr 22 05:55:14     ppp: [wan_link0] MRU 1492
Apr 22 05:55:14     ppp: [wan_link0] PROTOCOMP
Apr 22 05:55:14     ppp: [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)
Apr 22 05:55:14     ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Apr 22 05:55:14     ppp: [wan_link0] MAGICNUM fc1a5b29
Apr 22 05:55:14     ppp: [wan_link0] AUTHPROTO CHAP MD5
Apr 22 05:55:14     ppp: [wan_link0] MRU 1492
Apr 22 05:55:14     ppp: [wan_link0] LCP: SendConfigAck #1
Apr 22 05:55:14     ppp: [wan_link0] MAGICNUM fc1a5b29
Apr 22 05:55:14     ppp: [wan_link0] AUTHPROTO CHAP MD5
Apr 22 05:55:14     ppp: [wan_link0] MRU 1492
Apr 22 05:55:14     ppp: [wan_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Apr 22 05:55:14     ppp: [wan_link0] MAGICNUM f28ea0a0
Apr 22 05:55:14     ppp: [wan_link0] MRU 1492
Apr 22 05:55:14     ppp: [wan_link0] PROTOCOMP
Apr 22 05:55:14     ppp: [wan_link0] LCP: SendConfigReq #1
Apr 22 05:55:14     ppp: [wan_link0] LCP: state change Starting --> Req-Sent
Apr 22 05:55:14     ppp: [wan_link0] LCP: Up event
Apr 22 05:55:14     ppp: [wan_link0] Link: UP event
Apr 22 05:55:14     ppp: [wan_link0] PPPoE: connection successful
Apr 22 05:55:14     ppp: PPPoE: rec'd ACNAME "k18-29-236" 
Apr 22 05:55:14     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 22 05:55:14     ppp: [wan_link0] LCP: LayerStart
Apr 22 05:55:14     ppp: [wan_link0] LCP: state change Initial --> Starting
Apr 22 05:55:14     ppp: [wan_link0] LCP: Open event
Apr 22 05:55:14     ppp: [wan_link0] Link: OPEN event
Apr 22 05:55:14     ppp: [wan] Bundle: Interface ng0 created
Apr 22 05:55:14     ppp: web: web is not running
Apr 22 05:55:14     ppp: process 7776 started, version 5.7 (root@snapshots-8_3-i386.builders.pfsense.org 19:47 27-Mar-2014)
Apr 22 05:55:14     ppp:
Apr 22 05:55:14     ppp: Multi-link PPP daemon for FreeBSD
Apr 22 04:37:50     ppp: process 2130 terminated
Apr 22 04:37:50     ppp: can't read configuration for "pppoeclient" 
Apr 22 04:37:50     ppp: OpenConfFile: Can't open file '/var/etc/mpd_wan.conf': No such file or directory
Apr 22 04:37:50     ppp: OpenConfFile: Can't open file '/var/etc/mpd_wan.conf': No such file or directory
Apr 22 04:37:49     ppp: process 84093 terminated
Apr 22 04:37:49     ppp: [wan_link0] Link: Shutdown
Apr 22 04:37:49     ppp: [wan] Bundle: Shutdown
Apr 22 04:37:49     ppp: waiting for process 84093 to die...
Apr 22 04:37:49     ppp: process 2130 started, version 5.7 (root@snapshots-8_3-i386.builders.pfsense.org 19:47 27-Mar-2014)
Apr 22 04:37:49     ppp:
Apr 22 04:37:49     ppp: Multi-link PPP daemon for FreeBSD
Apr 22 04:37:48     ppp: [wan_link0] LCP: LayerFinish
Apr 22 04:37:48     ppp: [wan_link0] LCP: state change Starting --> Initial
Apr 22 04:37:48     ppp: [wan_link0] LCP: Close event
Apr 22 04:37:47     ppp: [wan] IPV6CP: Close event
Apr 22 04:37:47     ppp: [wan] IPCP: Close event
Apr 22 04:37:47     ppp: [wan] IFACE: Close event
Apr 22 04:37:47     ppp: caught fatal signal term
Apr 22 04:37:45     ppp: [wan_link0] Link: reconnection attempt 1 in 3 seconds
Apr 22 04:37:45     ppp: [wan_link0] LCP: Down event
Apr 22 04:37:45     ppp: [wan_link0] Link: DOWN event
Apr 22 04:37:45     ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Apr 22 04:37:36     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 22 04:37:36     ppp: [wan_link0] LCP: LayerStart
Apr 22 04:37:36     ppp: [wan_link0] LCP: state change Initial --> Starting
Apr 22 04:37:36     ppp: [wan_link0] LCP: Open event
Apr 22 04:37:36     ppp: [wan_link0] Link: OPEN event
Apr 22 04:37:36     ppp: [wan] Bundle: Interface ng0 created
Apr 22 04:37:36     ppp: web: web is not running
Apr 22 04:37:36     ppp: process 84093 started, version 5.7 (root@snapshots-8_3-i386.builders.pfsense.org 19:47 27-Mar-2014)
Apr 22 04:37:36     ppp:
Apr 22 04:37:36     ppp: Multi-link PPP daemon for FreeBSD
Apr 22 04:37:36     ppp: process 19923 terminated
Apr 22 04:37:36     ppp: [wan_link0] Link: Shutdown
Apr 22 04:37:36     ppp: [wan] Bundle: Shutdown
Apr 22 04:37:36     ppp: [wan] IPCP: SendTerminateReq #9
Apr 22 04:37:34     ppp: [wan] IPV6CP: state change Stopped --> Closed
Apr 22 04:37:34     ppp: [wan] IPV6CP: Close event
Apr 22 04:37:34     ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0
Apr 22 04:37:34     ppp: [wan] IFACE: Down event
Apr 22 04:37:34     ppp: [wan] IPCP: LayerDown
Apr 22 04:37:34     ppp: [wan] IPCP: SendTerminateReq #8
Apr 22 04:37:34     ppp: [wan] IPCP: state change Opened --> Closing
Apr 22 04:37:34     ppp: [wan] IPCP: Close event
Apr 22 04:37:34     ppp: [wan] IFACE: Close event
Apr 22 04:37:34     ppp: caught fatal signal term
Apr 21 16:47:04     ppp: [wan] IFACE: Rename interface ng0 to pppoe0
Apr 21 16:47:04     ppp: [wan] IFACE: Up event
Apr 21 16:47:03     ppp: [wan] xx.xx.xx.xx -> xx.xx.xx.xx
Apr 21 16:47:03     ppp: [wan] IPCP: LayerUp
Apr 21 16:47:03     ppp: [wan] IPCP: state change Ack-Sent --> Opened
Apr 21 16:47:03     ppp: [wan] SECDNS xx.xx.xx.34
Apr 21 16:47:03     ppp: [wan] PRIDNS xx.xx.0.33
Apr 21 16:47:03     ppp: [wan] IPADDR xx.xx.xx.xx
Apr 21 16:47:03     ppp: [wan] IPCP: rec'd Configure Ack #7 (Ack-Sent)
Apr 21 16:47:03     ppp: [wan] SECDNS xx.xx.xx.34
Apr 21 16:47:03     ppp: [wan] PRIDNS xx.xx.0.33
Apr 21 16:47:03     ppp: [wan] IPADDR xx.xx.xx.xx
Apr 21 16:47:03     ppp: [wan] IPCP: SendConfigReq #7
Apr 21 16:47:03     ppp: [wan] SECDNS xx.xx.xx.34
Apr 21 16:47:03     ppp: [wan] PRIDNS xx.xx.0.33
Apr 21 16:47:03     ppp: [wan] xx.xx.xx.xx is OK
Apr 21 16:47:03     ppp: [wan] IPADDR xx.xx.xx.xx
Apr 21 16:47:03     ppp: [wan] IPCP: rec'd Configure Nak #6 (Ack-Sent)
Apr 21 16:47:03     ppp: [wan] IPV6CP: LayerFinish
Apr 21 16:47:03     ppp: [wan] IPV6CP: state change Req-Sent --> Stopped
Apr 21 16:47:03     ppp: [wan] IPV6CP: protocol was rejected by peer
Apr 21 16:47:03     ppp: [wan_link0] LCP: protocol IPV6CP was rejected
Apr 21 16:47:03     ppp: [wan_link0] LCP: rec'd Protocol Reject #2 (Opened)
Apr 21 16:47:03     ppp: [wan] SECDNS 0.0.0.0
Apr 21 16:47:03     ppp: [wan] PRIDNS 0.0.0.0
Apr 21 16:47:03     ppp: [wan] IPADDR 0.0.0.0
Apr 21 16:47:03     ppp: [wan] IPCP: SendConfigReq #6
Apr 21 16:47:03     ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Apr 21 16:47:03     ppp: [wan] IPCP: rec'd Configure Reject #5 (Ack-Sent)
Apr 21 16:47:03     ppp: [wan] IPCP: state change Req-Sent --> Ack-Sent
Apr 21 16:47:03     ppp: [wan] IPADDR xx.xx.xx.xx
Apr 21 16:47:03     ppp: [wan] IPCP: SendConfigAck #1
Apr 21 16:47:03     ppp: [wan] xx.xx.xx.xx is OK
Apr 21 16:47:03     ppp: [wan] IPADDR xx.xx.xx.xx
Apr 21 16:47:03     ppp: [wan] IPCP: rec'd Configure Request #1 (Req-Sent)
Apr 21 16:47:03     ppp: [wan] IPV6CP: SendConfigReq #2
Apr 21 16:47:03     ppp: [wan] IPV6CP: state change Starting --> Req-Sent
Apr 21 16:47:03     ppp: [wan] IPV6CP: Up event
Apr 21 16:47:03     ppp: [wan] SECDNS 0.0.0.0
Apr 21 16:47:03     ppp: [wan] PRIDNS 0.0.0.0
Apr 21 16:47:03     ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Apr 21 16:47:03     ppp: [wan] IPADDR 0.0.0.0
Apr 21 16:47:03     ppp: [wan] IPCP: SendConfigReq #5
Apr 21 16:47:03     ppp: [wan] IPCP: state change Starting --> Req-Sent
Apr 21 16:47:03     ppp: [wan] IPCP: Up event
Apr 21 16:47:03     ppp: [wan] IPV6CP: LayerStart
Apr 21 16:47:03     ppp: [wan] IPV6CP: state change Initial --> Starting
Apr 21 16:47:03     ppp: [wan] IPV6CP: Open event
Apr 21 16:47:03     ppp: [wan] IPCP: LayerStart
Apr 21 16:47:03     ppp: [wan] IPCP: state change Initial --> Starting
Apr 21 16:47:03     ppp: [wan] IPCP: Open event
Apr 21 16:47:03     ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Apr 21 16:47:03     ppp: [wan_link0] Link: Join bundle "wan" 
Apr 21 16:47:03     ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Apr 21 16:47:03     ppp: [wan_link0] LCP: authorization successful
Apr 21 16:47:03     ppp: [wan_link0] CHAP: rec'd SUCCESS #1 len: 4
Apr 21 16:47:03     ppp: [wan_link0] CHAP: sending RESPONSE #1 len: 33
Apr 21 16:47:03     ppp: [wan_link0] CHAP: Using authname "someISPusername" 
Apr 21 16:47:03     ppp: [wan_link0] Name: "k18-29-236" 
Apr 21 16:47:03     ppp: [wan_link0] CHAP: rec'd CHALLENGE #1 len: 31
Apr 21 16:47:03     ppp: [wan_link0] LCP: LayerUp
Apr 21 16:47:03     ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Apr 21 16:47:03     ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
Apr 21 16:47:03     ppp: [wan_link0] MAGICNUM b10ca6df
Apr 21 16:47:03     ppp: [wan_link0] MRU 1492
Apr 21 16:47:03     ppp: [wan_link0] PROTOCOMP
Apr 21 16:47:03     ppp: [wan_link0] LCP: rec'd Configure Ack #4 (Ack-Sent)
Apr 21 16:47:03     ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Apr 21 16:47:03     ppp: [wan_link0] MAGICNUM f9477729
Apr 21 16:47:03     ppp: [wan_link0] AUTHPROTO CHAP MD5
Apr 21 16:47:03     ppp: [wan_link0] MRU 1492
Apr 21 16:47:03     ppp: [wan_link0] LCP: SendConfigAck #1
Apr 21 16:47:03     ppp: [wan_link0] MAGICNUM f9477729
Apr 21 16:47:03     ppp: [wan_link0] AUTHPROTO CHAP MD5
Apr 21 16:47:03     ppp: [wan_link0] MRU 1492
Apr 21 16:47:03     ppp: [wan_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Apr 21 16:47:03     ppp: [wan_link0] MAGICNUM b10ca6df
Apr 21 16:47:03     ppp: [wan_link0] MRU 1492
Apr 21 16:47:03     ppp: [wan_link0] PROTOCOMP
Apr 21 16:47:03     ppp: [wan_link0] LCP: SendConfigReq #4
Apr 21 16:47:03     ppp: [wan_link0] LCP: state change Starting --> Req-Sent
Apr 21 16:47:03     ppp: [wan_link0] LCP: Up event
Apr 21 16:47:03     ppp: [wan_link0] Link: UP event
Apr 21 16:47:03     ppp: [wan_link0] PPPoE: connection successful
Apr 21 16:47:03     ppp: PPPoE: rec'd ACNAME "k18-29-236" 
Apr 21 16:47:03     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 21 16:47:03     ppp: [wan_link0] Link: reconnection attempt 174
Apr 21 16:46:59     ppp: [wan_link0] Link: reconnection attempt 174 in 4 seconds
Apr 21 16:46:59     ppp: [wan_link0] LCP: Down event
Apr 21 16:46:59     ppp: [wan_link0] Link: DOWN event
Apr 21 16:46:59     ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Apr 21 16:46:50     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 21 16:46:50     ppp: [wan_link0] Link: reconnection attempt 173
Apr 21 16:46:47     ppp: [wan_link0] Link: reconnection attempt 173 in 3 seconds
Apr 21 16:46:47     ppp: [wan_link0] LCP: Down event
Apr 21 16:46:47     ppp: [wan_link0] Link: DOWN event
Apr 21 16:46:47     ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Apr 21 16:46:38     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 21 16:46:38     ppp: [wan_link0] Link: reconnection attempt 172
Apr 21 16:46:36     ppp: [wan_link0] Link: reconnection attempt 172 in 2 seconds
Apr 21 16:46:36     ppp: [wan_link0] LCP: Down event
Apr 21 16:46:36     ppp: [wan_link0] Link: DOWN event
Apr 21 16:46:36     ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Apr 21 16:46:27     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 21 16:46:27     ppp: [wan_link0] Link: reconnection attempt 171
Apr 21 16:46:24     ppp: [wan_link0] Link: reconnection attempt 171 in 3 seconds
Apr 21 16:46:24     ppp: [wan_link0] LCP: Down event
Apr 21 16:46:24     ppp: [wan_link0] Link: DOWN event
Apr 21 16:46:24     ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Apr 21 16:46:15     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 21 16:46:15     ppp: [wan_link0] Link: reconnection attempt 170
Apr 21 16:46:12     ppp: [wan_link0] Link: reconnection attempt 170 in 3 seconds
Apr 21 16:46:12     ppp: [wan_link0] LCP: Down event
Apr 21 16:46:12     ppp: [wan_link0] Link: DOWN event
Apr 21 16:46:12     ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Apr 21 16:46:03     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 21 16:46:03     ppp: [wan_link0] Link: reconnection attempt 169
Apr 21 16:45:59     ppp: [wan_link0] Link: reconnection attempt 169 in 4 seconds
Apr 21 16:45:59     ppp: [wan_link0] LCP: Down event
Apr 21 16:45:59     ppp: [wan_link0] Link: DOWN event
Apr 21 16:45:59     ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Apr 21 16:45:50     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 21 16:45:50     ppp: [wan_link0] Link: reconnection attempt 168
Apr 21 16:45:47     ppp: [wan_link0] Link: reconnection attempt 168 in 3 seconds
Apr 21 16:45:47     ppp: [wan_link0] LCP: Down event
Apr 21 16:45:47     ppp: [wan_link0] Link: DOWN event
Apr 21 16:45:47     ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Apr 21 16:45:38     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 21 16:45:38     ppp: [wan_link0] Link: reconnection attempt 167
Apr 21 16:45:34     ppp: [wan_link0] Link: reconnection attempt 167 in 4 seconds
Apr 21 16:45:34     ppp: [wan_link0] LCP: Down event
Apr 21 16:45:34     ppp: [wan_link0] Link: DOWN event
Apr 21 16:45:34     ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Apr 21 16:45:25     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 21 16:45:25     ppp: [wan_link0] Link: reconnection attempt 166
Apr 21 16:45:23     ppp: [wan_link0] Link: reconnection attempt 166 in 2 seconds
Apr 21 16:45:23     ppp: [wan_link0] LCP: Down event
Apr 21 16:45:23     ppp: [wan_link0] Link: DOWN event
Apr 21 16:45:23     ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Apr 21 16:45:14     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 21 16:45:14     ppp: [wan_link0] Link: reconnection attempt 165
Apr 21 16:45:11     ppp: [wan_link0] Link: reconnection attempt 165 in 3 seconds
Apr 21 16:45:11     ppp: [wan_link0] LCP: Down event
Apr 21 16:45:11     ppp: [wan_link0] Link: DOWN event
Apr 21 16:45:11     ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Apr 21 16:45:02     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 21 16:45:02     ppp: [wan_link0] Link: reconnection attempt 164
Apr 21 16:44:58     ppp: [wan_link0] Link: reconnection attempt 164 in 4 seconds
Apr 21 16:44:58     ppp: [wan_link0] LCP: Down event
Apr 21 16:44:58     ppp: [wan_link0] Link: DOWN event
Apr 21 16:44:58     ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Apr 21 16:44:49     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 21 16:44:49     ppp: [wan_link0] Link: reconnection attempt 163
Apr 21 16:44:47     ppp: [wan_link0] Link: reconnection attempt 163 in 2 seconds
Apr 21 16:44:47     ppp: [wan_link0] LCP: Down event
Apr 21 16:44:47     ppp: [wan_link0] Link: DOWN event
Apr 21 16:44:47     ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Apr 21 16:44:38     ppp: [wan_link0] PPPoE: Connecting to ''
Apr 21 16:44:38     ppp: [wan_link0] Link: reconnection attempt 162
Apr 21 16:44:37     ppp: [wan_link0] Link: reconnection attempt 162 in 1 seconds
Apr 21 16:44:37     ppp: [wan_link0] LCP: Down event
Apr 21 16:44:37     ppp: [wan_link0] Link: DOWN event
Apr 21 16:44:37     ppp: [wan_link0] PPPoE connection timeout after 9 seconds

Actions
Copy link
 #1

Updated by Dmitriy K almost 11 years ago

Temporal solution to this problem is to check "Use RAM Disks" box in System: Advanced: Miscellaneous.

Actions
Copy link
 #2

Updated by Gareth Davies almost 11 years ago

Have tried the suggested work-around but it doesn't work for me. In case my symptoms are different I'm adding a short section of my log with RAM Disks enabled. I get this sequence rather often, where ppp receives a fatal signal, closes down, then the next ppp process is not able to open the configuration file, so everything stops.

I would be very interested if anyone has suggestions for
  1. how to investigate the reason for the fatal signal, or
  2. how to automatically spawn a new ppp process after the single attempt at reconnecting, or
  3. maybe how to introduce a short delay after one ppp process is closed before the next one is started.
Jul 5 20:43:11     ppp: caught fatal signal term
Jul 5 20:43:11     ppp: [wan] IFACE: Close event
Jul 5 20:43:11     ppp: [wan] IPCP: Close event
Jul 5 20:43:11     ppp: [wan] IPCP: state change Opened --> Closing
Jul 5 20:43:11     ppp: [wan] IPCP: SendTerminateReq #3
Jul 5 20:43:11     ppp: [wan] IPCP: LayerDown
Jul 5 20:43:11     ppp: [wan] IFACE: Down event
Jul 5 20:43:11     ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0
Jul 5 20:43:11     ppp: [wan] IPV6CP: Close event
Jul 5 20:43:11     ppp: [wan] IPV6CP: state change Stopped --> Closed
Jul 5 20:43:11     ppp: [wan] IPCP: rec'd Terminate Ack #3 (Closing)
Jul 5 20:43:11     ppp: [wan] IPCP: state change Closing --> Closed
Jul 5 20:43:11     ppp: [wan] IPCP: LayerFinish
Jul 5 20:43:11     ppp: [wan] Bundle: No NCPs left. Closing links...
Jul 5 20:43:11     ppp: [wan] Bundle: closing link "wan_link0"...
Jul 5 20:43:11     ppp: [wan_link0] Link: CLOSE event
Jul 5 20:43:11     ppp: [wan_link0] LCP: Close event
Jul 5 20:43:11     ppp: [wan_link0] LCP: state change Opened --> Closing
Jul 5 20:43:11     ppp: [wan_link0] Link: Leave bundle "wan" 
Jul 5 20:43:11     ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Jul 5 20:43:11     ppp: [wan] IPCP: Close event
Jul 5 20:43:11     ppp: [wan] IPV6CP: Close event
Jul 5 20:43:11     ppp: [wan] IPCP: Down event
Jul 5 20:43:11     ppp: [wan] IPCP: state change Closed --> Initial
Jul 5 20:43:11     ppp: [wan] IPV6CP: Down event
Jul 5 20:43:11     ppp: [wan] IPV6CP: state change Closed --> Initial
Jul 5 20:43:11     ppp: [wan_link0] LCP: SendTerminateReq #2
Jul 5 20:43:11     ppp: [wan_link0] LCP: LayerDown
Jul 5 20:43:11     ppp: [wan_link0] LCP: rec'd Terminate Ack #2 (Closing)
Jul 5 20:43:11     ppp: [wan_link0] LCP: state change Closing --> Closed
Jul 5 20:43:11     ppp: [wan_link0] LCP: LayerFinish
Jul 5 20:43:11     ppp: [wan_link0] Link: DOWN event
Jul 5 20:43:11     ppp: [wan_link0] LCP: Down event
Jul 5 20:43:11     ppp: [wan_link0] LCP: state change Closed --> Initial
Jul 5 20:43:13     ppp: Multi-link PPP daemon for FreeBSD
Jul 5 20:43:13     ppp:
Jul 5 20:43:13     ppp: process 35842 started, version 5.7 (root@snapshots-8_3-amd64.builders.pfsense.org 19:46 27-Mar-2014)
Jul 5 20:43:13     ppp: waiting for process 2922 to die...
Jul 5 20:43:13     ppp: [wan] Bundle: Shutdown
Jul 5 20:43:13     ppp: [wan_link0] Link: Shutdown
Jul 5 20:43:13     ppp: process 2922 terminated
Jul 5 20:43:14     ppp: OpenConfFile: Can't open file '/var/etc/mpd_wan.conf': No such file or directory
Jul 5 20:43:14     ppp: OpenConfFile: Can't open file '/var/etc/mpd_wan.conf': No such file or directory
Jul 5 20:43:14     ppp: can't read configuration for "pppoeclient" 
Jul 5 20:43:14     ppp: process 35842 terminated
Actions
Copy link
 #3

Updated by Gareth Davies almost 11 years ago

Forgot to say, I was having this problem on 2.1.3-RELEASE (amd64) but now am having the same problem on 2.1.4-RELEASE (amd64).

Actions
Copy link
 #4

Updated by Gareth Davies almost 11 years ago

Follow-up to say my problems are partly resolved ... I replaced the network cable to the ADSL2 modem (Vigor 120). Now there have been no fatal signals, (five days and counting), so I do not see the problem where it cannot reconnect! I'm sure, though, that if I reverted to the old cable I'd the PPPoE connection would have trouble staying up for 24 hours and wouldn't reconnect.

Actions
Copy link
 #5

Updated by Chris Buechler about 9 years ago

  • Status changed from New to Closed
  • Affected Version deleted (2.1.2)

no indications of this happening to anyone on 2.2.x and newer versions

Actions
Copy link

Also available in: Atom PDF