Upgrade from 12/13 to 12/18 -> PPPoE dead…
-
Actually I tried again, because I needed to reboot. (Still the Internal Server Error after installing avahi package)
I do not need to kill mpd5. I just need to click save on WAN… -
2.0-BETA4 (amd64)
built on Thu Dec 23 12:13:59 EST 2010I updated 8 hours ago and still had to hit the Connect button to get a WAN.
-
Same problem here with newest snap of 12/26…
But if I wait long enough pfSense connects by itself after 3-4 minutes... -
Post your mpd logs.
-
Here is some output of my ppp.log:
Dec 28 00:16:03 ppp: [wan_link0] Link: reconnection attempt 2 Dec 28 00:16:03 ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd8264-0" and "[f]:"->"left": No such file or directory Dec 28 00:16:03 ppp: [wan_link0] can't remove hook mpd8264-0 from node "[12]:": No such file or directory Dec 28 00:16:03 ppp: [wan_link0] Link: DOWN event Dec 28 00:16:03 ppp: [wan_link0] LCP: Down event Dec 28 00:16:03 ppp: [wan_link0] Link: reconnection attempt 3 in 4 seconds Dec 28 00:16:07 ppp: [wan_link0] Link: reconnection attempt 3 Dec 28 00:16:07 ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd8264-0" and "[f]:"->"left": No such file or directory Dec 28 00:16:07 ppp: [wan_link0] can't remove hook mpd8264-0 from node "[12]:": No such file or directory Dec 28 00:16:07 ppp: [wan_link0] Link: DOWN event Dec 28 00:16:07 ppp: [wan_link0] LCP: Down event Dec 28 00:16:07 ppp: [wan_link0] Link: reconnection attempt 4 in 2 seconds Dec 28 00:16:09 ppp: [wan_link0] Link: reconnection attempt 4 Dec 28 00:16:09 ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd8264-0" and "[f]:"->"left": No such file or directory Dec 28 00:16:09 ppp: [wan_link0] can't remove hook mpd8264-0 from node "[12]:": No such file or directory Dec 28 00:16:09 ppp: [wan_link0] Link: DOWN event Dec 28 00:16:09 ppp: [wan_link0] LCP: Down event Dec 28 00:16:09 ppp: [wan_link0] Link: reconnection attempt 5 in 2 seconds Dec 28 00:16:11 ppp: [wan_link0] Link: reconnection attempt 5 Dec 28 00:16:11 ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd8264-0" and "[f]:"->"left": No such file or directory Dec 28 00:16:11 ppp: [wan_link0] can't remove hook mpd8264-0 from node "[12]:": No such file or directory Dec 28 00:16:11 ppp: [wan_link0] Link: DOWN event Dec 28 00:16:11 ppp: [wan_link0] LCP: Down event Dec 28 00:16:11 ppp: [wan_link0] Link: reconnection attempt 6 in 2 seconds Dec 28 00:16:13 ppp: [wan_link0] Link: reconnection attempt 6 Dec 28 00:16:13 ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd8264-0" and "[f]:"->"left": No such file or directory Dec 28 00:16:13 ppp: [wan_link0] can't remove hook mpd8264-0 from node "[12]:": No such file or directory Dec 28 00:16:13 ppp: [wan_link0] Link: DOWN event Dec 28 00:16:13 ppp: [wan_link0] LCP: Down event Dec 28 00:16:13 ppp: [wan_link0] Link: reconnection attempt 7 in 4 seconds Dec 28 00:16:17 ppp: caught fatal signal term Dec 28 00:16:17 ppp: [wan] IFACE: Close event Dec 28 00:16:17 ppp: [wan] IPCP: Close event Dec 28 00:16:17 ppp: [wan_link0] LCP: Close event Dec 28 00:16:17 ppp: [wan_link0] LCP: state change Starting --> Initial Dec 28 00:16:17 ppp: [wan_link0] LCP: LayerFinish Dec 28 00:16:19 ppp: [wan] Bundle: Shutdown Dec 28 00:16:19 ppp: [wan_link0] Link: Shutdown Dec 28 00:16:19 ppp: process 8264 terminated Dec 28 00:16:25 ppp: Multi-link PPP daemon for FreeBSD Dec 28 00:16:25 ppp: Dec 28 00:16:25 ppp: process 38608 started, version 5.5 (root@FreeBSD_8.0_pfSense_2.0-snaps.pfsense.org 21:35 22-Dec-2010) Dec 28 00:16:25 ppp: web: web is not running Dec 28 00:16:25 ppp: [wan] Bundle: Interface ng0 created Dec 28 00:16:25 ppp: [wan_link0] Link: OPEN event Dec 28 00:16:25 ppp: [wan_link0] LCP: Open event Dec 28 00:16:25 ppp: [wan_link0] LCP: state change Initial --> Starting Dec 28 00:16:25 ppp: [wan_link0] LCP: LayerStart Dec 28 00:16:25 ppp: [wan_link0] PPPoE: Connecting to '*' Dec 28 00:16:25 ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478"That goes on for like 2-3 minutes and then suddenly it works…
Dec 28 00:17:44 ppp: [wan_link0] Link: reconnection attempt 7 in 2 seconds Dec 28 00:17:46 ppp: [wan_link0] Link: reconnection attempt 7 Dec 28 00:17:46 ppp: [wan_link0] PPPoE: Connecting to '*' Dec 28 00:17:46 ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478" Dec 28 00:17:55 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Dec 28 00:17:55 ppp: [wan_link0] Link: DOWN event Dec 28 00:17:55 ppp: [wan_link0] LCP: Down event Dec 28 00:17:55 ppp: [wan_link0] Link: reconnection attempt 8 in 3 seconds Dec 28 00:17:58 ppp: [wan_link0] Link: reconnection attempt 8 Dec 28 00:17:58 ppp: [wan_link0] PPPoE: Connecting to '*' Dec 28 00:17:58 ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478" Dec 28 00:18:04 ppp: [wan_link0] PPPoE: connection successful Dec 28 00:18:04 ppp: [wan_link0] Link: UP event Dec 28 00:18:04 ppp: [wan_link0] LCP: Up event Dec 28 00:18:04 ppp: [wan_link0] LCP: state change Starting --> Req-Sent Dec 28 00:18:04 ppp: [wan_link0] LCP: SendConfigReq #1 Dec 28 00:18:04 ppp: [wan_link0] PROTOCOMP Dec 28 00:18:04 ppp: [wan_link0] MRU 1492 Dec 28 00:18:04 ppp: [wan_link0] MAGICNUM a8eef870 Dec 28 00:18:04 ppp: [wan_link0] LCP: rec'd Configure Request #52 (Req-Sent) Dec 28 00:18:04 ppp: [wan_link0] MRU 1492 Dec 28 00:18:04 ppp: [wan_link0] AUTHPROTO PAP Dec 28 00:18:04 ppp: [wan_link0] MAGICNUM 49a2d6c8 Dec 28 00:18:04 ppp: [wan_link0] LCP: SendConfigAck #52 Dec 28 00:18:04 ppp: [wan_link0] MRU 1492 Dec 28 00:18:04 ppp: [wan_link0] AUTHPROTO PAP Dec 28 00:18:04 ppp: [wan_link0] MAGICNUM 49a2d6c8 Dec 28 00:18:04 ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent Dec 28 00:18:04 ppp: [wan_link0] LCP: rec'd Configure Reject #1 (Ack-Sent) Dec 28 00:18:04 ppp: [wan_link0] PROTOCOMP Dec 28 00:18:04 ppp: [wan_link0] LCP: SendConfigReq #2 Dec 28 00:18:04 ppp: [wan_link0] MRU 1492 Dec 28 00:18:04 ppp: [wan_link0] MAGICNUM a8eef870 Dec 28 00:18:04 ppp: [wan_link0] LCP: rec'd Configure Ack #2 (Ack-Sent) Dec 28 00:18:04 ppp: [wan_link0] MRU 1492 Dec 28 00:18:04 ppp: [wan_link0] MAGICNUM a8eef870 Dec 28 00:18:04 ppp: [wan_link0] LCP: state change Ack-Sent --> Opened Dec 28 00:18:04 ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing Dec 28 00:18:04 ppp: [wan_link0] PAP: using authname "xxxx@t-online.de" Dec 28 00:18:04 ppp: [wan_link0] PAP: sending REQUEST #1 len: 54 Dec 28 00:18:04 ppp: [wan_link0] LCP: LayerUp Dec 28 00:18:05 ppp: [wan_link0] PAP: rec'd ACK #1 len: 5 Dec 28 00:18:05 ppp: [wan_link0] LCP: authorization successful Dec 28 00:18:05 ppp: [wan_link0] Link: Matched action 'bundle "wan" ""' Dec 28 00:18:05 ppp: [wan_link0] Link: Join bundle "wan" Dec 28 00:18:05 ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps Dec 28 00:18:05 ppp: [wan] IPCP: Open event Dec 28 00:18:05 ppp: [wan] IPCP: state change Initial --> Starting Dec 28 00:18:05 ppp: [wan] IPCP: LayerStart Dec 28 00:18:05 ppp: [wan] IPCP: Up event Dec 28 00:18:05 ppp: [wan] IPCP: state change Starting --> Req-Sent Dec 28 00:18:05 ppp: [wan] IPCP: SendConfigReq #1 Dec 28 00:18:05 ppp: [wan] IPADDR 0.0.0.0 Dec 28 00:18:05 ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 28 00:18:05 ppp: [wan] IPCP: rec'd Configure Request #229 (Req-Sent) Dec 28 00:18:05 ppp: [wan] IPADDR 87.186.224.38 Dec 28 00:18:05 ppp: [wan] 87.186.224.38 is OK Dec 28 00:18:05 ppp: [wan] IPCP: SendConfigAck #229 Dec 28 00:18:05 ppp: [wan] IPADDR 87.186.224.38 Dec 28 00:18:05 ppp: [wan] IPCP: state change Req-Sent --> Ack-Sent Dec 28 00:18:05 ppp: [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent) Dec 28 00:18:05 ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 28 00:18:05 ppp: [wan] IPCP: SendConfigReq #2 Dec 28 00:18:05 ppp: [wan] IPADDR 0.0.0.0 Dec 28 00:18:05 ppp: [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent) Dec 28 00:18:05 ppp: [wan] IPADDR xxxx Dec 28 00:18:05 ppp: [wan] xxxx is OK Dec 28 00:18:05 ppp: [wan] IPCP: SendConfigReq #3 Dec 28 00:18:05 ppp: [wan] IPADDR xxxx Dec 28 00:18:05 ppp: [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent) Dec 28 00:18:05 ppp: [wan] IPADDR xxxx Dec 28 00:18:05 ppp: [wan] IPCP: state change Ack-Sent --> Opened Dec 28 00:18:05 ppp: [wan] IPCP: LayerUp Dec 28 00:18:05 ppp: [wan] xxxx -> 87.186.224.38 Dec 28 00:18:05 ppp: [wan] IFACE: Up eventThis whole thing was working fine for me before…
-
ppp logs if it helps
Dec 27 21:37:47 ppp: [wan] IFACE: Up event
Dec 27 21:37:47 ppp: [wan] 173.191.242.233 -> 151.213.8.5
Dec 27 21:37:47 ppp: [wan] IPCP: LayerUp
Dec 27 21:37:47 ppp: [wan] IPCP: state change Ack-Sent –> Opened
Dec 27 21:37:47 ppp: [wan] IPADDR 173.191.242.233
Dec 27 21:37:47 ppp: [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Dec 27 21:37:47 ppp: [wan] IPADDR 173.191.242.233
Dec 27 21:37:47 ppp: [wan] IPCP: SendConfigReq #3
Dec 27 21:37:47 ppp: [wan] 173.191.242.233 is OK
Dec 27 21:37:47 ppp: [wan] IPADDR 173.191.242.233
Dec 27 21:37:47 ppp: [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Dec 27 21:37:47 ppp: [wan] IPADDR 0.0.0.0
Dec 27 21:37:47 ppp: [wan] IPCP: SendConfigReq #2
Dec 27 21:37:47 ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Dec 27 21:37:47 ppp: [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Dec 27 21:37:47 ppp: [wan] IPCP: state change Req-Sent –> Ack-Sent
Dec 27 21:37:47 ppp: [wan] IPADDR 151.213.8.5
Dec 27 21:37:47 ppp: [wan] IPCP: SendConfigAck #1
Dec 27 21:37:47 ppp: [wan] 151.213.8.5 is OK
Dec 27 21:37:47 ppp: [wan] IPADDR 151.213.8.5
Dec 27 21:37:47 ppp: [wan] IPCP: rec'd Configure Request #1 (Req-Sent)
Dec 27 21:37:47 ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Dec 27 21:37:47 ppp: [wan] IPADDR 0.0.0.0
Dec 27 21:37:47 ppp: [wan] IPCP: SendConfigReq #1
Dec 27 21:37:47 ppp: [wan] IPCP: state change Starting –> Req-Sent
Dec 27 21:37:47 ppp: [wan] IPCP: Up event
Dec 27 21:37:47 ppp: [wan] IPCP: LayerStart
Dec 27 21:37:47 ppp: [wan] IPCP: state change Initial –> Starting
Dec 27 21:37:47 ppp: [wan] IPCP: Open event
Dec 27 21:37:47 ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Dec 27 21:37:47 ppp: [wan_link0] Link: Join bundle "wan"
Dec 27 21:37:47 ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Dec 27 21:37:47 ppp: [wan_link0] LCP: authorization successful
Dec 27 21:37:47 ppp: [wan_link0] PAP: rec'd ACK #1 len: 5
Dec 27 21:37:46 ppp: [wan_link0] LCP: LayerUp
Dec 27 21:37:46 ppp: [wan_link0] PAP: sending REQUEST #1 len: 26
Dec 27 21:37:46 ppp: [wan_link0] PAP: using authname "grandrivers"
Dec 27 21:37:46 ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing
Dec 27 21:37:46 ppp: [wan_link0] LCP: state change Ack-Sent –> Opened
Dec 27 21:37:46 ppp: [wan_link0] MAGICNUM 6f07b4dc
Dec 27 21:37:46 ppp: [wan_link0] MRU 1492
Dec 27 21:37:46 ppp: [wan_link0] PROTOCOMP
Dec 27 21:37:46 ppp: [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)
Dec 27 21:37:46 ppp: [wan_link0] LCP: state change Req-Sent –> Ack-Sent
Dec 27 21:37:46 ppp: [wan_link0] MAGICNUM c9e5f758
Dec 27 21:37:46 ppp: [wan_link0] AUTHPROTO PAP
Dec 27 21:37:46 ppp: [wan_link0] MRU 1492
Dec 27 21:37:46 ppp: [wan_link0] LCP: SendConfigAck #1
Dec 27 21:37:46 ppp: [wan_link0] MAGICNUM c9e5f758
Dec 27 21:37:46 ppp: [wan_link0] AUTHPROTO PAP
Dec 27 21:37:46 ppp: [wan_link0] MRU 1492
Dec 27 21:37:46 ppp: [wan_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Dec 27 21:37:46 ppp: [wan_link0] MAGICNUM 6f07b4dc
Dec 27 21:37:46 ppp: [wan_link0] MRU 1492
Dec 27 21:37:46 ppp: [wan_link0] PROTOCOMP
Dec 27 21:37:46 ppp: [wan_link0] LCP: SendConfigReq #1
Dec 27 21:37:46 ppp: [wan_link0] LCP: state change Starting –> Req-Sent
Dec 27 21:37:46 ppp: [wan_link0] LCP: Up event
Dec 27 21:37:46 ppp: [wan_link0] Link: UP event
Dec 27 21:37:46 ppp: [wan_link0] PPPoE: connection successful
Dec 27 21:37:46 ppp: PPPoE: rec'd ACNAME "ashtohlcs03-7200"
Dec 27 21:37:46 ppp: [wan_link0] PPPoE: Connecting to ''
Dec 27 21:37:46 ppp: [wan_link0] LCP: LayerStart
Dec 27 21:37:46 ppp: [wan_link0] LCP: state change Initial –> Starting
Dec 27 21:37:46 ppp: [wan_link0] LCP: Open event
Dec 27 21:37:46 ppp: [wan_link0] Link: OPEN event
Dec 27 21:37:46 ppp: [wan] Bundle: Interface ng0 created
Dec 27 21:37:46 ppp: web: web is not running
Dec 27 21:37:46 ppp: process 8957 started, version 5.5 (root@FreeBSD_8.0_pfSense_2.0-snaps.pfsense.org 21:35 22-Dec-2010)
Dec 27 21:37:46 ppp:
Dec 27 21:37:46 ppp: Multi-link PPP daemon for FreeBSD
Dec 27 21:37:43 ppp: process 4206 terminated
Dec 27 21:37:43 ppp: [wan_link0] Link: Shutdown
Dec 27 21:37:43 ppp: [wan] Bundle: Shutdown
Dec 27 21:37:42 ppp: [wan_link0] LCP: state change Closed –> Initial
Dec 27 21:37:42 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:42 ppp: [wan_link0] LCP: state change Stopped –> Closed
Dec 27 21:37:42 ppp: [wan_link0] LCP: Close event
Dec 27 21:37:42 ppp: [wan_link0] Link: giving up after 0 reconnection attempts
Dec 27 21:37:42 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:42 ppp: [wan_link0] PPPoE: connection closed
Dec 27 21:37:42 ppp: [wan_link0] LCP: LayerFinish
Dec 27 21:37:42 ppp: [wan_link0] LCP: state change Stopping –> Stopped
Dec 27 21:37:42 ppp: [wan_link0] LCP: rec'd Terminate Ack #2 (Stopping)
Dec 27 21:37:41 ppp: [wan_link0] rec'd proto IPCP during terminate phase
Dec 27 21:37:41 ppp: [wan_link0] LCP: LayerDown
Dec 27 21:37:41 ppp: [wan_link0] LCP: SendTerminateReq #2
Dec 27 21:37:41 ppp: [wan_link0] LCP: state change Opened –> Stopping
Dec 27 21:37:41 ppp: [wan_link0] LCP: parameter negotiation failed
Dec 27 21:37:41 ppp: [wan_link0] link did not validate in bundle
Dec 27 21:37:41 ppp: Shutdown sequence in progress, BundJoin() denied
Dec 27 21:37:41 ppp: [wan_link0] LCP: authorization successful
Dec 27 21:37:41 ppp: [wan_link0] PAP: rec'd ACK #1 len: 5
Dec 27 21:37:41 ppp: [wan_link0] LCP: LayerUp
Dec 27 21:37:41 ppp: [wan_link0] PAP: sending REQUEST #1 len: 26
Dec 27 21:37:41 ppp: [wan_link0] PAP: using authname "grandrivers"
Dec 27 21:37:41 ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing
Dec 27 21:37:41 ppp: [wan_link0] LCP: state change Ack-Sent –> Opened
Dec 27 21:37:41 ppp: [wan_link0] MAGICNUM 29185664
Dec 27 21:37:41 ppp: [wan_link0] MRU 1492
Dec 27 21:37:41 ppp: [wan_link0] PROTOCOMP
Dec 27 21:37:41 ppp: [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)
Dec 27 21:37:41 ppp: [wan_link0] LCP: state change Req-Sent –> Ack-Sent
Dec 27 21:37:41 ppp: [wan_link0] MAGICNUM c9e5e352
Dec 27 21:37:41 ppp: [wan_link0] AUTHPROTO PAP
Dec 27 21:37:41 ppp: [wan_link0] MRU 1492
Dec 27 21:37:41 ppp: [wan_link0] LCP: SendConfigAck #1
Dec 27 21:37:41 ppp: [wan_link0] MAGICNUM c9e5e352
Dec 27 21:37:41 ppp: [wan_link0] AUTHPROTO PAP
Dec 27 21:37:41 ppp: [wan_link0] MRU 1492
Dec 27 21:37:41 ppp: [wan_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Dec 27 21:37:41 ppp: [wan_link0] MAGICNUM 29185664
Dec 27 21:37:41 ppp: [wan_link0] MRU 1492
Dec 27 21:37:41 ppp: [wan_link0] PROTOCOMP
Dec 27 21:37:41 ppp: [wan_link0] LCP: SendConfigReq #1
Dec 27 21:37:41 ppp: [wan_link0] LCP: state change Starting –> Req-Sent
Dec 27 21:37:41 ppp: [wan_link0] LCP: Up event
Dec 27 21:37:41 ppp: [wan_link0] Link: UP event
Dec 27 21:37:41 ppp: [wan_link0] PPPoE: connection successful
Dec 27 21:37:41 ppp: PPPoE: rec'd ACNAME "ashtohlcs03-7200"
Dec 27 21:37:41 ppp: [wan] IPCP: Close event
Dec 27 21:37:41 ppp: [wan] IFACE: Close event
Dec 27 21:37:41 ppp: caught fatal signal term
Dec 27 21:37:41 ppp: [wan_link0] PPPoE: Connecting to ''
Dec 27 21:37:41 ppp: [wan_link0] LCP: LayerStart
Dec 27 21:37:41 ppp: [wan_link0] LCP: state change Initial –> Starting
Dec 27 21:37:41 ppp: [wan_link0] LCP: Open event
Dec 27 21:37:41 ppp: [wan_link0] Link: OPEN event
Dec 27 21:37:41 ppp: [wan] Bundle: Interface ng0 created
Dec 27 21:37:41 ppp: web: web is not running
Dec 27 21:37:41 ppp: process 4206 started, version 5.5 (root@FreeBSD_8.0_pfSense_2.0-snaps.pfsense.org 21:35 22-Dec-2010)
Dec 27 21:37:41 ppp:
Dec 27 21:37:41 ppp: Multi-link PPP daemon for FreeBSD
Dec 27 21:37:38 ppp: process 8816 terminated
Dec 27 21:37:38 ppp: [wan_link0] Link: Shutdown
Dec 27 21:37:38 ppp: [wan] Bundle: Shutdown
Dec 27 21:37:37 ppp: [wan_link0] LCP: LayerFinish
Dec 27 21:37:37 ppp: [wan_link0] LCP: state change Starting –> Initial
Dec 27 21:37:37 ppp: [wan_link0] LCP: Close event
Dec 27 21:37:36 ppp: [wan] IPCP: Close event
Dec 27 21:37:36 ppp: [wan] IFACE: Close event
Dec 27 21:37:36 ppp: caught fatal signal term
Dec 27 21:37:35 ppp: [wan_link0] Link: reconnection attempt 103 in 2 seconds
Dec 27 21:37:35 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:35 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:35 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:35 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:35 ppp: [wan_link0] Link: reconnection attempt 102
Dec 27 21:37:34 ppp: [wan_link0] Link: reconnection attempt 102 in 1 seconds
Dec 27 21:37:34 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:34 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:34 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:34 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:34 ppp: [wan_link0] Link: reconnection attempt 101
Dec 27 21:37:33 ppp: [wan_link0] Link: reconnection attempt 101 in 1 seconds
Dec 27 21:37:33 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:33 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:33 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:33 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:33 ppp: [wan_link0] Link: reconnection attempt 100
Dec 27 21:37:31 ppp: [wan_link0] Link: reconnection attempt 100 in 2 seconds
Dec 27 21:37:31 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:31 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:31 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:31 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:31 ppp: [wan_link0] Link: reconnection attempt 99
Dec 27 21:37:27 ppp: [wan_link0] Link: reconnection attempt 99 in 4 seconds
Dec 27 21:37:27 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:27 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:27 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:27 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:27 ppp: [wan_link0] Link: reconnection attempt 98
Dec 27 21:37:24 ppp: [wan_link0] Link: reconnection attempt 98 in 3 seconds
Dec 27 21:37:24 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:24 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:24 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:24 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:24 ppp: [wan_link0] Link: reconnection attempt 97
Dec 27 21:37:21 ppp: [wan_link0] Link: reconnection attempt 97 in 3 seconds
Dec 27 21:37:21 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:21 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:21 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:21 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:21 ppp: [wan_link0] Link: reconnection attempt 96
Dec 27 21:37:20 ppp: [wan_link0] Link: reconnection attempt 96 in 1 seconds
Dec 27 21:37:20 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:20 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:20 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:20 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:20 ppp: [wan_link0] Link: reconnection attempt 95
Dec 27 21:37:19 ppp: [wan_link0] Link: reconnection attempt 95 in 1 seconds
Dec 27 21:37:19 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:19 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:19 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:19 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:19 ppp: [wan_link0] Link: reconnection attempt 94
Dec 27 21:37:15 ppp: [wan_link0] Link: reconnection attempt 94 in 4 seconds
Dec 27 21:37:15 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:15 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:15 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:15 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:15 ppp: [wan_link0] Link: reconnection attempt 93
Dec 27 21:37:13 ppp: [wan_link0] Link: reconnection attempt 93 in 2 seconds
Dec 27 21:37:13 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:13 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:13 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:13 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:13 ppp: [wan_link0] Link: reconnection attempt 92
Dec 27 21:37:12 ppp: [wan_link0] Link: reconnection attempt 92 in 1 seconds
Dec 27 21:37:12 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:12 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:12 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:12 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:12 ppp: [wan_link0] Link: reconnection attempt 91
Dec 27 21:37:10 ppp: [wan_link0] Link: reconnection attempt 91 in 2 seconds
Dec 27 21:37:10 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:10 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:10 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:10 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:10 ppp: [wan_link0] Link: reconnection attempt 90
Dec 27 21:37:06 ppp: [wan_link0] Link: reconnection attempt 90 in 4 seconds
Dec 27 21:37:06 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:06 ppp: [wan_link0] Link: DOWN event -
At the exact moment it's having the problem, grab the mpd_wan.conf (or whichever interface it is) from /var/etc/ and post it here - make sure to edit out your username and password though, assuming they are really your username and password.
Also the output of this may help:
ps uxawww | grep mpd5 -
/var/etc/mpd_wan.conf:
startup: # configure the console set console close # configure the web server set web close default: pppoeclient: create bundle static wan set iface name pppoe0 set iface route default set iface disable on-demand set iface idle 0 set iface enable tcpmssfix set iface up-script /usr/local/sbin/ppp-linkup set iface down-script /usr/local/sbin/ppp-linkdown set ipcp ranges 0.0.0.0/0 0.0.0.0/0 #log -bund -ccp -chat -iface -ipcp -lcp -link create link static wan_link0 pppoe set link action bundle wan set link disable multilink set link keep-alive 10 60 set link max-redial 0 set link disable chap pap set link accept chap pap eap set link disable incoming set link mtu 1492 set auth authname "xxxx@t-online.de" set auth password xxxx set pppoe iface vr1 openps uxawww | grep mpd5:
root 31262 0.0 1.6 7440 3940 ?? SNs 2:22PM 0:00.02 /usr/local/sbin/mpd5 -b -k -d /var/etc -f mpd_wan.conf -p /var/run/pppoe_wan.pid -s ppp pppoeclientAlso check out the screenshot attached. If I open up mpd_wan.conf with vi there is some sort of control character at the end of the file…
-
So those are the files from the same time you are getting the errors in the logs?
Dec 27 21:37:35 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory Dec 27 21:37:35 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directoryThat ^M is just a windows carriage return, usually programs are smart enough to ignore those. I've got one on my home router running a working snap from late November, so it's probably not the cause here.
-
I think so. I took this as soon as I could ssh into the box. But these errors go away and for some time you have the other stuff I posted above going on until it finally connects…
-
I just committed a patch which hopefully will resolve this.
Please test with a new snapshot because you need a new mpd binary. -
FYI- I just rebuilt it on the builder after Ermal's patch, and restarted the build, so the next new snapshot that uploads should include the fix.
-
It got even worse over here…
After a reboot the ppp.log showed this:Dec 29 16:34:25 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory Dec 29 16:34:25 voldemort ppp: [wan_link0] Link: DOWN event Dec 29 16:34:25 voldemort ppp: [wan_link0] LCP: Down event Dec 29 16:34:25 voldemort ppp: [wan_link0] Link: reconnection attempt 2 in 1 seconds Dec 29 16:34:26 voldemort ppp: [wan_link0] Link: reconnection attempt 2 Dec 29 16:34:26 voldemort ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd10506-0" and "[f]:"->"left": No such file or directory Dec 29 16:34:26 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory Dec 29 16:34:26 voldemort ppp: [wan_link0] Link: DOWN event Dec 29 16:34:26 voldemort ppp: [wan_link0] LCP: Down event Dec 29 16:34:26 voldemort ppp: [wan_link0] Link: reconnection attempt 3 in 2 seconds Dec 29 16:34:28 voldemort ppp: [wan_link0] Link: reconnection attempt 3 Dec 29 16:34:28 voldemort ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd10506-0" and "[f]:"->"left": No such file or directory Dec 29 16:34:28 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory Dec 29 16:34:28 voldemort ppp: [wan_link0] Link: DOWN event Dec 29 16:34:28 voldemort ppp: [wan_link0] LCP: Down event Dec 29 16:34:28 voldemort ppp: [wan_link0] Link: reconnection attempt 4 in 2 seconds Dec 29 16:34:30 voldemort ppp: [wan_link0] Link: reconnection attempt 4 Dec 29 16:34:30 voldemort ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd10506-0" and "[f]:"->"left": No such file or directory Dec 29 16:34:30 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory Dec 29 16:34:30 voldemort ppp: [wan_link0] Link: DOWN event Dec 29 16:34:30 voldemort ppp: [wan_link0] LCP: Down event Dec 29 16:34:30 voldemort ppp: [wan_link0] Link: reconnection attempt 5 in 4 seconds Dec 29 16:34:34 voldemort ppp: [wan_link0] Link: reconnection attempt 5 Dec 29 16:34:34 voldemort ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd10506-0" and "[f]:"->"left": No such file or directory Dec 29 16:34:34 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory Dec 29 16:34:34 voldemort ppp: [wan_link0] Link: DOWN event Dec 29 16:34:34 voldemort ppp: [wan_link0] LCP: Down event Dec 29 16:34:34 voldemort ppp: [wan_link0] Link: reconnection attempt 6 in 4 seconds Dec 29 16:34:38 voldemort ppp: [wan_link0] Link: reconnection attempt 6 Dec 29 16:34:38 voldemort ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd10506-0" and "[f]:"->"left": No such file or directory Dec 29 16:34:38 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory Dec 29 16:34:38 voldemort ppp: [wan_link0] Link: DOWN event Dec 29 16:34:38 voldemort ppp: [wan_link0] LCP: Down event Dec 29 16:34:38 voldemort ppp: [wan_link0] Link: reconnection attempt 7 in 1 seconds Dec 29 16:34:39 voldemort ppp: [wan_link0] Link: reconnection attempt 7 Dec 29 16:34:39 voldemort ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd10506-0" and "[f]:"->"left": No such file or directory Dec 29 16:34:39 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory Dec 29 16:34:39 voldemort ppp: [wan_link0] Link: DOWN event Dec 29 16:34:39 voldemort ppp: [wan_link0] LCP: Down event Dec 29 16:34:39 voldemort ppp: [wan_link0] Link: reconnection attempt 8 in 3 seconds Dec 29 16:34:41 voldemort ppp: caught fatal signal term Dec 29 16:34:41 voldemort ppp: [wan] IFACE: Close event Dec 29 16:34:41 voldemort ppp: [wan] IPCP: Close event Dec 29 16:34:42 voldemort ppp: [wan_link0] LCP: Close event Dec 29 16:34:42 voldemort ppp: [wan_link0] LCP: state change Starting --> Initial Dec 29 16:34:42 voldemort ppp: [wan_link0] LCP: LayerFinish Dec 29 16:34:43 voldemort ppp: [wan] Bundle: Shutdown Dec 29 16:34:43 voldemort ppp: [wan_link0] Link: Shutdown Dec 29 16:34:43 voldemort ppp: process 10506 terminated Dec 29 16:34:46 voldemort ppp: Multi-link PPP daemon for FreeBSD Dec 29 16:34:46 voldemort ppp: Dec 29 16:34:46 voldemort ppp: process 25103 started, version 5.5 (root@FreeBSD_8.0_pfSense_2.0-snaps.pfsense.org 16:48 28-Dec-2010) Dec 29 16:34:46 voldemort ppp: web: web is not running Dec 29 16:34:46 voldemort ppp: [wan] Bundle: Interface ng0 created Dec 29 16:34:46 voldemort ppp: [wan_link0] Link: OPEN event Dec 29 16:34:46 voldemort ppp: [wan_link0] LCP: Open event Dec 29 16:34:46 voldemort ppp: [wan_link0] LCP: state change Initial --> Starting Dec 29 16:34:46 voldemort ppp: [wan_link0] LCP: LayerStart Dec 29 16:34:46 voldemort ppp: [wan_link0] PPPoE: Connecting to '*' Dec 29 16:34:46 voldemort ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478" Dec 29 16:34:55 voldemort ppp: [wan_link0] PPPoE connection timeout after 9 seconds Dec 29 16:34:55 voldemort ppp: [wan_link0] Link: DOWN event Dec 29 16:34:55 voldemort ppp: [wan_link0] LCP: Down event Dec 29 16:34:55 voldemort ppp: [wan_link0] Link: reconnection attempt 1 in 1 seconds Dec 29 16:34:56 voldemort ppp: [wan_link0] Link: reconnection attempt 1 Dec 29 16:34:56 voldemort ppp: [wan_link0] PPPoE: Connecting to '*' Dec 29 16:34:57 voldemort ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478" Dec 29 16:35:06 voldemort ppp: [wan_link0] PPPoE connection timeout after 9 seconds Dec 29 16:35:06 voldemort ppp: [wan_link0] Link: DOWN event Dec 29 16:35:06 voldemort ppp: [wan_link0] LCP: Down event Dec 29 16:35:06 voldemort ppp: [wan_link0] Link: reconnection attempt 2 in 1 seconds Dec 29 16:35:07 voldemort ppp: [wan_link0] Link: reconnection attempt 2 Dec 29 16:35:07 voldemort ppp: [wan_link0] PPPoE: Connecting to '*' Dec 29 16:35:07 voldemort ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478" Dec 29 16:35:16 voldemort ppp: [wan_link0] PPPoE connection timeout after 9 seconds Dec 29 16:35:16 voldemort ppp: [wan_link0] Link: DOWN event Dec 29 16:35:16 voldemort ppp: [wan_link0] LCP: Down event Dec 29 16:35:16 voldemort ppp: [wan_link0] Link: reconnection attempt 3 in 3 seconds Dec 29 16:35:19 voldemort ppp: [wan_link0] Link: reconnection attempt 3 Dec 29 16:35:19 voldemort ppp: [wan_link0] PPPoE: Connecting to '*' Dec 29 16:35:19 voldemort ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478"Then suddenly it stopped and I checked if mpd5 was running which was not. Also the WebGUI wasn't accessable…
system.log showed the following:
Dec 29 16:37:35 voldemort kernel: pid 14143 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 14463 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 14739 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 14314 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 14793 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 15107 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 15387 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 15807 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 16146 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 16160 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 15704 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 16285 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 16331 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 16761 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 16748 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 17055 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 17089 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 17394 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 16638 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 16853 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 17664 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 18136 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 17773 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 18389 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 18687 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 18083 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 18495 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 18919 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 18798 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 19008 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 19298 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 19625 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 19589 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 19925 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 20132 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 20418 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 20714 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 20804 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 20829 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 21317 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 21104 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 21314 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 21511 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 21839 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 22209 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 22150 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 22368 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 22644 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 22831 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 22645 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 23037 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 23171 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 23461 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 24007 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 23702 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 24061 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 24346 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 24312 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 24044 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 24669 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 24820 (php), uid 0: exited on signal 11 Dec 29 16:37:35 voldemort kernel: pid 24933 (php), uid 0: exited on signal 11So I had to do a 'killall php' and restart the Web Configurator. After that I could click save on the WAN interface so that mpd5 would start again. This time the ppp logs started again and it took some more minutes before the connection came up. Still not everything was right (see screenshot attached) - the IP on WAN was missing and I could not access website although the connection seemed to be up…
After another reboot and waiting some minutes for the connection to come up everything looked fine. But the packages (at the moment only avahi installed) did not get reinstalled automatically, again... After manually reinstalling avahi that was the log:
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Found user 'avahi' (UID 558) and group 'avahi' (GID 558). Dec 29 16:41:22 voldemort avahi-daemon[38866]: Successfully dropped root privileges. Dec 29 16:41:22 voldemort avahi-daemon[38866]: avahi-daemon 0.6.24 starting up. Dec 29 16:41:22 voldemort avahi-daemon[38866]: WARNING: No NSS support for mDNS detected, consider installing nss-mdns! Dec 29 16:41:22 voldemort avahi-daemon[38866]: WARNING: No NSS support for mDNS detected, consider installing nss-mdns! Dec 29 16:41:22 voldemort avahi-daemon[38866]: Loading service file /usr/local/etc/avahi/services/sftp-ssh.service. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Loading service file /usr/local/etc/avahi/services/ssh.service. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Joining mDNS multicast group on interface ng1.IPv4 with address xxxx. Dec 29 16:41:22 voldemort avahi-daemon[38866]: New relevant interface ng1.IPv4 for mDNS. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Joining mDNS multicast group on interface ath0_wlan1.IPv4 with address 192.168.100.254. Dec 29 16:41:22 voldemort avahi-daemon[38866]: New relevant interface ath0_wlan1.IPv4 for mDNS. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Joining mDNS multicast group on interface ath0_wlan0.IPv4 with address 172.16.100.254. Dec 29 16:41:22 voldemort avahi-daemon[38866]: New relevant interface ath0_wlan0.IPv4 for mDNS. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Joining mDNS multicast group on interface vr0.IPv4 with address 10.0.100.254. Dec 29 16:41:22 voldemort avahi-daemon[38866]: New relevant interface vr0.IPv4 for mDNS. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Network interface enumeration completed. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for fe80::20d:b9ff:fe13:54a8 on ng1.*. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for xxxx on ng1.IPv4. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for fe80::480:48ff:fe62:fa57 on ath0_wlan1.*. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for 192.168.100.254 on ath0_wlan1.IPv4. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for fe80::280:48ff:fe62:fa57 on ath0_wlan0.*. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for 172.16.100.254 on ath0_wlan0.IPv4. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for fe80::20d:b9ff:fe13:54a8 on vr0.*. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for 10.0.100.254 on vr0.IPv4. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering HINFO record with values 'I386'/'FREEBSD'. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Short read() on signal pipe. Dec 29 16:41:22 voldemort avahi-daemon[38866]: Short read() on signal pipe. Dec 29 16:41:22 voldemort avahi-daemon[38866]: daemon_signal_next() failed Dec 29 16:41:22 voldemort avahi-daemon[38866]: daemon_signal_next() failed Dec 29 16:41:22 voldemort check_reload_status: reloading filter Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: Server startup complete. Host name is voldemort.hogwarts.lan. Local service cookie is 3244555418. Dec 29 16:41:23 voldemort avahi-daemon[38866]: Failed to add service 'voldemort' of type '_ssh._tcp', ignoring service group (/usr/local/etc/avahi/services/ssh.service): Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: Failed to add service 'voldemort' of type '_ssh._tcp', ignoring service group (/usr/local/etc/avahi/services/ssh.service): Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: Failed to add service 'SFTP File Transfer on voldemort' of type '_sftp-ssh._tcp', ignoring service group (/usr/local/etc/avahi/services/sftp-ssh.service): Not supported Dec 29 16:41:23 voldemort avahi-daemon[38866]: Failed to add service 'SFTP File Transfer on voldemort' of type '_sftp-ssh._tcp', ignoring service group (/usr/local/etc/avahi/services/sftp-ssh.service): Not supportedSo still some error messages. What are these. Are they 'normal'?
 -
I think your problems come from avahi package because it does some very bad things to base system.
Please uninstall avahi and if you can upgrade to a latest snap. -
Ok. I can test this, but I rely heavily on avahi for the apple bonjour stuff…
-
Bonjour works without the avahi package on your router. I have lots of macs and mac products on my networks and no avahi package on my routers.
I'm not really even sure of the purpose of a router running avahi. What are you trying to do?
GB
-
Well if you have LAN and WLAN on separate interfaces you need avahi in order for Bonjour to work…
(eg iTunes Remote / Connection from AppleTV(WLAN) to iTunes(LAN)) -
Hello…. same problem on Dec 28 pppoe dead after afew minute connected. AMD64
-
Please update to a snapshot after 28 December.
-
A fix was introduced on the 28th which means you should be running a snap from the 29th or 30th. Can someone please test this… This is one of our last issues remaining before rc1.
-
mine is still acting up have to hit connect to make it connect
-
Just updated my home router from a late November snap to:
2.0-BETA5 (i386) built on Thu Dec 30 11:06:13 EST 2010
PPPoE connected fine at boot without intervention. No problems here.
EDIT: Note that this is an always-on connection so I do not have dial-on-demand checked. I have a blank idle timeout, and periodic reset is disabled.
-
mine is still acting up have to hit connect to make it connect
dedicated or dial up? can you compare your settings with jim-p?
-
Updated to Thu Dec 30 21:38:56 EST 2010 right now. Still seeing the same behavior as before. Doesn't come up after reboot, mpd5 dies after some time, WebUI not accessable (500 - Internal Server Error) after the first connect so I need another reboot! After that I also need to click save on WAN interface again…
-
not had the web gui problem but do have to make it connect after boot my settings are the same as jims
-
Updated to Thu Dec 30 21:38:56 EST 2010 right now. Still seeing the same behavior as before. Doesn't come up after reboot, mpd5 dies after some time, WebUI not accessable (500 - Internal Server Error) after the first connect so I need another reboot! After that I also need to click save on WAN interface again…
The 500 error is due to package issues, but it could be related to this in some way I suppose.
-
What we really need to know from everyone having issues is at least:
- Architecture (i386 or amd64)
- Full install or NanoBSD
- Packages installed
- If it's WAN or an OPT on PPPoE (or both)
- PPPoE settings on the interface page (dial on demand, idle timeout, periodic reset)
- PPPoE connection logs from the system logs
- Your mpd_*.conf files from /var/etc/ - make sure to remove or mask your login credentials
-
Hi all .. this could be off topic … but I wonder has anyone ever dialed two or more PPPOE simultaneously? like practically using the interface type as PPPOE on more than 1 interface to dial to ISPs?
-
Hi there,
I don't have time to post all of the requested info now, but I think I may be experiencing this issue now too, though I don't believe it impacted me before. I updated from a Dec.26 snap to 2.0-BETA5 (i386)
built on Fri Dec 31 06:22:39 EST 2010.I have 2 PPPoE interfaces. My WAN and OPT1 (renamed VDSL and DSL respectively) are the PPPoE interfaces. WAN/VDSL works, OPT1/DSL does not.
Anyway - I've tried Dial on demand and not. I set the idle timeout to 0, with no periodic reset enabled. Doesn't seem to allow my OPT1 to connect.
Snip from GUI PPP Log page :
Dec 31 17:25:32 ppp: [opt1_link0] Link: Shutdown
Dec 31 17:25:32 ppp: [opt1] Bundle: Shutdown
Dec 31 17:25:32 ppp: [opt1_link0] LCP: LayerFinish
Dec 31 17:25:32 ppp: [opt1_link0] LCP: state change Starting –> Initial
Dec 31 17:25:32 ppp: [opt1_link0] LCP: Close event
Dec 31 17:25:30 ppp: [opt1] IPCP: Close event
Dec 31 17:25:30 ppp: [opt1] IFACE: Close event
Dec 31 17:25:30 ppp: caught fatal signal term
Dec 31 17:25:30 ppp: [opt1_link0] Link: reconnection attempt 81 in 2 seconds
Dec 31 17:25:30 ppp: [opt1_link0] LCP: Down event
Dec 31 17:25:30 ppp: [opt1_link0] Link: DOWN event
Dec 31 17:25:30 ppp: [opt1_link0] can't remove hook mpd32090-0 from node "[bc]:": No such file or directory
Dec 31 17:25:30 ppp: [opt1_link0] PPPoE: can't connect "[bc]:"->"mpd32090-0" and "[ba]:"->"left": No such file or directory
Dec 31 17:25:30 ppp: [opt1_link0] Link: reconnection attempt 80
Dec 31 17:25:26 ppp: [opt1_link0] Link: reconnection attempt 80 in 4 seconds
Dec 31 17:25:26 ppp: [opt1_link0] LCP: Down event
Dec 31 17:25:26 ppp: [opt1_link0] Link: DOWN event
Dec 31 17:25:26 ppp: [opt1_link0] can't remove hook mpd32090-0 from node "[bc]:": No such file or directory
Dec 31 17:25:26 ppp: [opt1_link0] PPPoE: can't connect "[bc]:"->"mpd32090-0" and "[ba]:"->"left": No such file or directory
Dec 31 17:25:26 ppp: [opt1_link0] Link: reconnection attempt 79
Dec 31 17:25:23 ppp: [opt1_link0] Link: reconnection attempt 79 in 3 seconds
Dec 31 17:25:23 ppp: [opt1_link0] LCP: Down event
Dec 31 17:25:23 ppp: [opt1_link0] Link: DOWN event
Dec 31 17:25:23 ppp: [opt1_link0] can't remove hook mpd32090-0 from node "[bc]:": No such file or directory
Dec 31 17:25:23 ppp: [opt1_link0] PPPoE: can't connect "[bc]:"->"mpd32090-0" and "[ba]:"->"left": No such file or directoryHope this is helpful,
– Phob
-
Sorry, it's going to be a few days before I'm back in town to try an update.
-
Hi again,
Just checked my packages screen - I only have the Open-VM-Tools installed at present.
– Phob
-
Hrm - I disabled my OPT1 interface for a while to stop the constant PPPoE requests, and just re-enabled it to discover that it has negotiated another session.
Might be a carrier issue as I see some packet loss today in my graphs.
Anyway - if the issue resurfaces, I'll post again.
– Phob
-
Hi again,
OK - further to my last, with dial on demand un-ticked, I have to manually start my PPPoE sessions from Status –> Interfaces on reboot.
Also, entering the down interface from Interfaces --> DSL or VDSL then clicking save also seems to get my PPPoE back online, but when I do that, I have to manually go into System --> Routing and delete the automatically created gateways which overwrite my working gateway setup. (the system creates GW_WAN and GW_OPT1 when I've already got gateways defined with different names (VDSL and DSL) that are in turn members of gateway groups for load balancing and failover)
Sorry to muddy the waters - I re-read this entire thread which I admit not to have done the first time I posted. I appear to have the same issue as others.
-- Phob
-
What we really need to know from everyone having issues is at least:
- Architecture (i386 or amd64)
- Full install or NanoBSD
- Packages installed
- If it's WAN or an OPT on PPPoE (or both)
- PPPoE settings on the interface page (dial on demand, idle timeout, periodic reset)
- PPPoE connection logs from the system logs
- Your mpd_*.conf files from /var/etc/ - make sure to remove or mask your login credentials
- i386
- NanoBSD
- Avahi, Backup, RRD Summary, Nmap, Unbound, Shellcmd
- WAN (PPPoE)
- Standard Settings, except periodic reset at midnight each day
- For configs/logfiles see my previous posts
-
Same stuff happening now with snap built on Sat Jan 1 19:56:40 EST 2011…
Also pfsense.com seems to be down for packages installation now, so I spare one reboot because of the packages installation of avahi ;) -
Copy from another thread:
http://forum.pfsense.org/index.php?topic=31316.new;topicseen#newPPP Logs:
Jan 1 23:53:59 ppp: [wan_link0] PPPoE: Skipping ink "wan_link0" with undefined interfaceIt was impossible to connect. Click on the connect button caused:
Dec 21 17:22:17 php: /status_interfaces.php: Backtrace: Array
Dec 21 17:22:17 php: /status_interfaces.php: interfaces_bring_up() was called but no variable defined.Details logged are available in the thread mentioned above.
- Architecture: i386
- NanoBSD / PC Engine Alix Board (pfSense-2.0-BETA5-4g-20101231-1506-nanobsd-upgrade.img.gz)
- Packages installed : pfflowd (broken since beta)
- Interface: WAN
- PPPoE settings on the interface page:
- MTU: 1492
- Enable Dial-On-Demand mode
- IDLE timeout: 1200 seconds
- logs (see above)
I solved this problem by playing a lot with the option and it's now fixed.
I think what solved the problem was changing the WAN from vr0 to vr2 and then back to vr0 (to be complete, vr2 is now unassigned).I upgraded today to pfSense-2.0-BETA5-4g-20110101-1956-nanobsd-upgrade.img and I cannot reproduce the problem (I tried to change option but it looks ok).
Should you need any more test/information, don't hesitate to contact me.
-
Hi all,
Sorry for the delay, but some more info about my setup :
- Architecture: i386
- Full Install (2.0-BETA5 (i386) built on Fri Dec 31 14:08:23 EST 2010)
- Packages installed : Open-VM-Tools
- Interface: WAN & OPT1 (renamed VDSL and DSL)
- PPPoE settings on the interface page :
- MTU: <blank>- NO Dial-On-Demand mode
- NO IDLE timeout: <blank>My setup is a VM running under VMWare (hence the Open-VM-Tools package).
– Phob</blank></blank>
-
I sorted out my problem I had created A Modem access (opt2) and that seemed to stop ppoe from working removed thta and all is fine
-
Ok I have the same problem:
- Architecture : i386
- Full install
- Packages installed : Darkstat
- WAN -> pppoe via em0 and opt (let's call it DSL) connected to em0
- dial on demand: checked
- idle timeout: 0
- periodic reset : disabled
- PPPoE connection logs from the system logs
see my post : http://forum.pfsense.org/index.php/topic,31409.0.html - mpd_wan.conf:
startup:
# configure the console
set console close
# configure the web server
set web closedefault:
pppoeclient:
create bundle static wan
set iface name pppoe0
set iface route default
set iface enable on-demand
set iface idle 0
set iface addrs 10.10.1.1 10.10.1.2
set iface enable tcpmssfix
set iface up-script /usr/local/sbin/ppp-linkup
set iface down-script /usr/local/sbin/ppp-linkdown
set ipcp ranges 0.0.0.0/0 0.0.0.0/0
#log -bund -ccp -chat -iface -ipcp -lcp -linkcreate link static wan_link0 pppoe
set link action bundle wan
set link disable multilink
set link keep-alive 10 60
set link max-redial 0
set link disable chap pap
set link accept chap pap eap
set link disable incoming
set link mtu 1492
set auth authname "xxxxx"
set auth password xxxxx
set pppoe iface em0
open^M -
So perhaps this is related to having pppoe on a physical interface and also having that interface assigned directly.
