No automatic connect to ISP

cmb

In System log I've noticed that MPD is killed immediately after establishing the PPPoE connection, and before receiving the DNS server from my ISP.

Please paste all mpd logs from your system logs.

_igor_

I sent you a PM, cmb. Thanks in advance!

_igor_

Here the last logs from mpd:

Dec 14 23:41:23 pfsense mpd: PPPoE: rec'd ACNAME "netdsl"
Dec 14 23:41:23 pfsense mpd: [pppoeclient] PPPoE: connection successful
Dec 14 23:41:23 pfsense mpd: [pppoeclient] link: UP event
Dec 14 23:41:23 pfsense mpd: [pppoeclient] link: origination is local
Dec 14 23:41:23 pfsense mpd: [pppoeclient] LCP: Up event
Dec 14 23:41:23 pfsense mpd: [pppoeclient] LCP: state change Starting –> Req-Sent
Dec 14 23:41:23 pfsense mpd: [pppoeclient] LCP: SendConfigReq #16
Dec 14 23:41:23 pfsense mpd: MRU 1492
Dec 14 23:41:23 pfsense mpd: MAGICNUM 603dfef4
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: rec'd Configure Request #6 (Req-Sent)
Dec 14 23:41:24 pfsense mpd: MRU 1492
Dec 14 23:41:24 pfsense mpd: AUTHPROTO PAP
Dec 14 23:41:24 pfsense mpd: MAGICNUM 5651823c
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: SendConfigAck #6
Dec 14 23:41:24 pfsense mpd: MRU 1492
Dec 14 23:41:24 pfsense mpd: AUTHPROTO PAP
Dec 14 23:41:24 pfsense mpd: MAGICNUM 5651823c
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: state change Req-Sent –> Ack-Sent
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: rec'd Configure Ack #16 (Ack-Sent)
Dec 14 23:41:24 pfsense mpd: MRU 1492
Dec 14 23:41:24 pfsense mpd: MAGICNUM 603dfef4
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: state change Ack-Sent –> Opened
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: auth: peer wants PAP, I want nothing
Dec 14 23:41:24 pfsense mpd: [pppoeclient] PAP: using authname "nc-ochska2@netcologne.de"
Dec 14 23:41:24 pfsense mpd: [pppoeclient] PAP: sending REQUEST len:32
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: LayerUp
Dec 14 23:41:24 pfsense mpd: [pppoeclient] PAP: rec'd NAK #1
Dec 14 23:41:24 pfsense mpd: MESG: Request Denied
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: authorization failed
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: parameter negotiation failed
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: state change Opened –> Stopping
Dec 14 23:41:24 pfsense mpd: [pppoeclient] AUTH: Cleanup
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: SendTerminateReq #17
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: LayerDown
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: rec'd Terminate Request #7 (Stopping)
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: SendTerminateAck #18
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: rec'd Terminate Ack #17 (Stopping)
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: state change Stopping –> Stopped
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: LayerFinish
Dec 14 23:41:24 pfsense mpd: [pppoeclient] link: DOWN event
Dec 14 23:41:24 pfsense mpd: [pppoeclient] link: reconnection attempt 6
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: Down event
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: state change Stopped –> Starting
Dec 14 23:41:24 pfsense mpd: [pppoeclient] LCP: LayerStart
Dec 14 23:41:24 pfsense mpd: [pppoeclient] pausing 5 seconds before open
Dec 14 23:41:24 pfsense php: : Could not find gateway for interface(wan).
Dec 14 23:41:24 pfsense last message repeated 6 times
snipped other things…
Dec 14 23:41:29 pfsense mpd: [pppoeclient] PPPoE: Connecting to 'netdsl'
Dec 14 23:41:38 pfsense mpd: [pppoeclient] PPPoE connection timeout after 9 seconds
Dec 14 23:41:38 pfsense mpd: [pppoeclient] link: DOWN event
Dec 14 23:41:38 pfsense mpd: [pppoeclient] link: reconnection attempt 7
Dec 14 23:41:38 pfsense mpd: [pppoeclient] LCP: Down event
Dec 14 23:41:38 pfsense mpd: [pppoeclient] pausing 6 seconds before open
Dec 14 23:41:39 pfsense php: : Could not find gateway for interface(wan).
Dec 14 23:41:39 pfsense last message repeated 6 times
Dec 14 23:41:41 pfsense check_reload_status: reloading filter
Dec 14 23:41:42 pfsense php: : Could not find gateway for interface(wan).
Dec 14 23:41:44 pfsense mpd: [pppoeclient] PPPoE: Connecting to 'netdsl'
Dec 14 23:41:44 pfsense mpd: PPPoE: rec'd ACNAME "netdsl"
Dec 14 23:41:44 pfsense mpd: [pppoeclient] PPPoE: connection successful
Dec 14 23:41:44 pfsense mpd: [pppoeclient] link: UP event
Dec 14 23:41:44 pfsense mpd: [pppoeclient] link: origination is local
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: Up event
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: state change Starting –> Req-Sent
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: SendConfigReq #19
Dec 14 23:41:44 pfsense mpd: MRU 1492
Dec 14 23:41:44 pfsense mpd: MAGICNUM c01eb27e
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: rec'd Configure Request #62 (Req-Sent)
Dec 14 23:41:44 pfsense mpd: MRU 1492
Dec 14 23:41:44 pfsense mpd: AUTHPROTO PAP
Dec 14 23:41:44 pfsense mpd: MAGICNUM 3bf57d5d
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: SendConfigAck #62
Dec 14 23:41:44 pfsense mpd: MRU 1492
Dec 14 23:41:44 pfsense mpd: AUTHPROTO PAP
Dec 14 23:41:44 pfsense mpd: MAGICNUM 3bf57d5d
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: state change Req-Sent –> Ack-Sent
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: rec'd Configure Ack #19 (Ack-Sent)
Dec 14 23:41:44 pfsense mpd: MRU 1492
Dec 14 23:41:44 pfsense mpd: MAGICNUM c01eb27e
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: state change Ack-Sent –> Opened
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: auth: peer wants PAP, I want nothing
Dec 14 23:41:44 pfsense mpd: [pppoeclient] PAP: using authname "nc-ochska2@netcologne.de"
Dec 14 23:41:44 pfsense mpd: [pppoeclient] PAP: sending REQUEST len:32
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: LayerUp
Dec 14 23:41:44 pfsense mpd: [pppoeclient] PAP: rec'd NAK #1
Dec 14 23:41:44 pfsense mpd: MESG: Request Denied
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: authorization failed
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: parameter negotiation failed
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: state change Opened –> Stopping
Dec 14 23:41:44 pfsense mpd: [pppoeclient] AUTH: Cleanup
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: SendTerminateReq #20
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: LayerDown
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: rec'd Terminate Request #63 (Stopping)
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: SendTerminateAck #21
Dec 14 23:41:44 pfsense mpd: [pppoeclient] PPPoE: connection closed
Dec 14 23:41:44 pfsense mpd: [pppoeclient] link: DOWN event
Dec 14 23:41:44 pfsense mpd: [pppoeclient] link: reconnection attempt 8
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: Down event
Dec 14 23:41:44 pfsense mpd: [pppoeclient] LCP: state change Stopping –> Starting
Dec 14 23:41:44 pfsense mpd: [pppoeclient] pausing 6 seconds before open
Dec 14 23:41:50 pfsense mpd: [pppoeclient] PPPoE: Connecting to 'netdsl'
Dec 14 23:41:59 pfsense mpd: [pppoeclient] PPPoE connection timeout after 9 seconds
Dec 14 23:41:59 pfsense mpd: [pppoeclient] link: DOWN event
Dec 14 23:41:59 pfsense mpd: [pppoeclient] link: reconnection attempt 9
Dec 14 23:41:59 pfsense mpd: [pppoeclient] LCP: Down event
Dec 14 23:41:59 pfsense mpd: [pppoeclient] pausing 5 seconds before open
Dec 14 23:42:04 pfsense mpd: [pppoeclient] PPPoE: Connecting to 'netdsl'
Dec 14 23:42:13 pfsense mpd: [pppoeclient] PPPoE connection timeout after 9 seconds
Dec 14 23:42:13 pfsense mpd: [pppoeclient] link: DOWN event
Dec 14 23:42:13 pfsense mpd: [pppoeclient] link: reconnection attempt 10
Dec 14 23:42:13 pfsense mpd: [pppoeclient] LCP: Down event
Dec 14 23:42:13 pfsense mpd: [pppoeclient] pausing 6 seconds before open
Dec 14 23:41:42 pfsense last message repeated 6 times
Dec 14 23:42:19 pfsense mpd: [pppoeclient] PPPoE: Connecting to 'netdsl'
Dec 14 23:42:19 pfsense mpd: PPPoE: rec'd ACNAME "netdsl"
Dec 14 23:42:19 pfsense mpd: [pppoeclient] PPPoE: connection successful
Dec 14 23:42:19 pfsense mpd: [pppoeclient] link: UP event
Dec 14 23:42:19 pfsense mpd: [pppoeclient] link: origination is local
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: Up event
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: state change Starting –> Req-Sent
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: SendConfigReq #22
Dec 14 23:42:19 pfsense mpd: MRU 1492
Dec 14 23:42:19 pfsense mpd: MAGICNUM eabacaf8
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: rec'd Configure Request #104 (Req-Sent)
Dec 14 23:42:19 pfsense mpd: MRU 1492
Dec 14 23:42:19 pfsense mpd: AUTHPROTO PAP
Dec 14 23:42:19 pfsense mpd: MAGICNUM 358fa2c2
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: SendConfigAck #104
Dec 14 23:42:19 pfsense mpd: MRU 1492
Dec 14 23:42:19 pfsense mpd: AUTHPROTO PAP
Dec 14 23:42:19 pfsense mpd: MAGICNUM 358fa2c2
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: state change Req-Sent –> Ack-Sent
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: rec'd Configure Ack #22 (Ack-Sent)
Dec 14 23:42:19 pfsense mpd: MRU 1492
Dec 14 23:42:19 pfsense mpd: MAGICNUM eabacaf8
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: state change Ack-Sent –> Opened
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: auth: peer wants PAP, I want nothing
Dec 14 23:42:19 pfsense mpd: [pppoeclient] PAP: using authname "nc-ochska2@netcologne.de"
Dec 14 23:42:19 pfsense mpd: [pppoeclient] PAP: sending REQUEST len:32
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: LayerUp
Dec 14 23:42:19 pfsense mpd: [pppoeclient] PAP: rec'd NAK #1
Dec 14 23:42:19 pfsense mpd: MESG: Request Denied
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: authorization failed
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: parameter negotiation failed
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: state change Opened –> Stopping
Dec 14 23:42:19 pfsense mpd: [pppoeclient] AUTH: Cleanup
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: SendTerminateReq #23
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: LayerDown
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: rec'd Terminate Request #105 (Stopping)
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: SendTerminateAck #24
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: rec'd Terminate Ack #23 (Stopping)
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: state change Stopping –> Stopped
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: LayerFinish
Dec 14 23:42:19 pfsense mpd: [pppoeclient] link: DOWN event
Dec 14 23:42:19 pfsense mpd: [pppoeclient] link: reconnection attempt 11
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: Down event
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: state change Stopped –> Starting
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: LayerStart
Dec 14 23:42:19 pfsense mpd: [pppoeclient] pausing 6 seconds before open
Dec 14 23:42:25 pfsense mpd: [pppoeclient] PPPoE: Connecting to 'netdsl'
Dec 14 23:42:34 pfsense mpd: [pppoeclient] PPPoE connection timeout after 9 seconds
Dec 14 23:42:34 pfsense mpd: [pppoeclient] link: DOWN event
Dec 14 23:42:34 pfsense mpd: [pppoeclient] link: reconnection attempt 12
Dec 14 23:42:34 pfsense mpd: [pppoeclient] LCP: Down event
Dec 14 23:42:34 pfsense mpd: [pppoeclient] pausing 6 seconds before open
Dec 14 23:42:40 pfsense mpd: [pppoeclient] PPPoE: Connecting to 'netdsl'
Dec 14 23:42:49 pfsense mpd: [pppoeclient] PPPoE connection timeout after 9 seconds
Dec 14 23:42:49 pfsense mpd: [pppoeclient] link: DOWN event
Dec 14 23:42:49 pfsense mpd: [pppoeclient] link: reconnection attempt 13
Dec 14 23:42:49 pfsense mpd: [pppoeclient] LCP: Down event
Dec 14 23:42:49 pfsense mpd: [pppoeclient] pausing 6 seconds before open
Dec 14 23:42:55 pfsense mpd: [pppoeclient] PPPoE: Connecting to 'netdsl'
Dec 14 23:43:04 pfsense mpd: [pppoeclient] PPPoE connection timeout after 9 seconds
Dec 14 23:43:04 pfsense mpd: [pppoeclient] link: DOWN event
Dec 14 23:43:04 pfsense mpd: [pppoeclient] link: reconnection attempt 14
Dec 14 23:43:04 pfsense mpd: [pppoeclient] LCP: Down event
Dec 14 23:43:04 pfsense mpd: [pppoeclient] pausing 5 seconds before open
Dec 14 23:43:09 pfsense mpd: [pppoeclient] PPPoE: Connecting to 'netdsl'
Dec 14 23:43:18 pfsense mpd: [pppoeclient] PPPoE connection timeout after 9 seconds
Dec 14 23:43:18 pfsense mpd: [pppoeclient] link: DOWN event
Dec 14 23:43:18 pfsense mpd: [pppoeclient] link: reconnection attempt 15
Dec 14 23:43:18 pfsense mpd: [pppoeclient] LCP: Down event
Dec 14 23:43:18 pfsense mpd: [pppoeclient] pausing 5 seconds before open
Dec 14 23:43:23 pfsense mpd: [pppoeclient] PPPoE: Connecting to 'netdsl'
Dec 14 23:43:24 pfsense mpd: PPPoE: rec'd ACNAME "netdsl"
Dec 14 23:43:24 pfsense mpd: [pppoeclient] PPPoE: connection successful
Dec 14 23:43:24 pfsense mpd: [pppoeclient] link: UP event
Dec 14 23:43:24 pfsense mpd: [pppoeclient] link: origination is local
Dec 14 23:43:24 pfsense mpd: [pppoeclient] LCP: Up event
Dec 14 23:43:24 pfsense mpd: [pppoeclient] LCP: state change Starting –> Req-Sent
Dec 14 23:43:24 pfsense mpd: [pppoeclient] LCP: SendConfigReq #25
Dec 14 23:43:24 pfsense mpd: MRU 1492
Dec 14 23:43:24 pfsense mpd: MAGICNUM 45d3319c
Dec 14 23:43:24 pfsense mpd: [pppoeclient] LCP: rec'd Configure Request #190 (Req-Sent)
Dec 14 23:43:24 pfsense mpd: MRU 1492
Dec 14 23:43:24 pfsense mpd: AUTHPROTO PAP
Dec 14 23:43:24 pfsense mpd: MAGICNUM 0a298f03
Dec 14 23:43:24 pfsense mpd: [pppoeclient] LCP: SendConfigAck #190
Dec 14 23:43:24 pfsense mpd: MRU 1492
Dec 14 23:43:24 pfsense mpd: AUTHPROTO PAP
Dec 14 23:43:24 pfsense mpd: MAGICNUM 0a298f03
Dec 14 23:43:24 pfsense mpd: [pppoeclient] LCP: state change Req-Sent –> Ack-Sent
Dec 14 23:43:24 pfsense mpd: [pppoeclient] LCP: rec'd Configure Ack #25 (Ack-Sent)
Dec 14 23:43:24 pfsense mpd: MRU 1492
Dec 14 23:43:24 pfsense mpd: MAGICNUM 45d3319c
Dec 14 23:43:24 pfsense mpd: [pppoeclient] LCP: state change Ack-Sent –> Opened
Dec 14 23:43:24 pfsense mpd: [pppoeclient] LCP: auth: peer wants PAP, I want nothing
Dec 14 23:43:24 pfsense mpd: [pppoeclient] PAP: using authname "nc-ochska2@netcologne.de"
Dec 14 23:43:24 pfsense mpd: [pppoeclient] PAP: sending REQUEST len:32
Dec 14 23:43:24 pfsense mpd: [pppoeclient] LCP: LayerUp
Dec 14 23:43:24 pfsense mpd: [pppoeclient] PAP: rec'd ACK #1
Dec 14 23:43:24 pfsense mpd: [pppoeclient] LCP: authorization successful
Dec 14 23:43:24 pfsense mpd: [pppoeclient] Bundle up: 1 link, total bandwidth 64000 bps
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: Open event
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: state change Initial –> Starting
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: LayerStart
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: Up event
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: state change Starting –> Req-Sent
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: SendConfigReq #1
Dec 14 23:43:24 pfsense mpd: IPADDR 0.0.0.0
Dec 14 23:43:24 pfsense mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Dec 14 23:43:24 pfsense mpd: PRIDNS 0.0.0.0
Dec 14 23:43:24 pfsense mpd: SECDNS 0.0.0.0
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: rec'd Configure Reject #1 (Req-Sent)
Dec 14 23:43:24 pfsense mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: SendConfigReq #2
Dec 14 23:43:24 pfsense mpd: IPADDR 0.0.0.0
Dec 14 23:43:24 pfsense mpd: PRIDNS 0.0.0.0
Dec 14 23:43:24 pfsense mpd: SECDNS 0.0.0.0
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: rec'd Configure Nak #2 (Req-Sent)
Dec 14 23:43:24 pfsense mpd: IPADDR 78.34.176.178
Dec 14 23:43:24 pfsense mpd: 78.34.176.178 is OK
Dec 14 23:43:24 pfsense mpd: PRIDNS 81.173.194.76
Dec 14 23:43:24 pfsense mpd: SECDNS 81.173.194.69
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: SendConfigReq #3
Dec 14 23:43:24 pfsense mpd: IPADDR 78.34.176.178
Dec 14 23:43:24 pfsense mpd: PRIDNS 81.173.194.76
Dec 14 23:43:24 pfsense mpd: SECDNS 81.173.194.69
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: rec'd Configure Ack #3 (Req-Sent)
Dec 14 23:43:24 pfsense mpd: IPADDR 78.34.176.178
Dec 14 23:43:24 pfsense mpd: PRIDNS 81.173.194.76
Dec 14 23:43:24 pfsense mpd: SECDNS 81.173.194.69
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: state change Req-Sent –> Ack-Rcvd
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: rec'd Configure Request #43 (Ack-Rcvd)
Dec 14 23:43:24 pfsense mpd: IPADDR 195.14.226.7
Dec 14 23:43:24 pfsense mpd: 195.14.226.7 is OK
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: SendConfigAck #43
Dec 14 23:43:24 pfsense mpd: IPADDR 195.14.226.7
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: state change Ack-Rcvd –> Opened
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IPCP: LayerUp
Dec 14 23:43:24 pfsense mpd: 78.34.176.178 -> 195.14.226.7
Dec 14 23:43:24 pfsense mpd: [pppoeclient] IFACE: Up event
Dec 14 23:43:28 pfsense check_reload_status: rc.newwanip starting
end of connect, here i had to wait maybe 4-5 minutes to get a connection to internet.

ambo

I am seeing similar problems with a number of the recent snapshots.

I am using a wireless provider and many of the times when my connection goes down and fails to come back up seem to coincide with bad weather.

Below is an extract from my logs… The WAN went down at around 00:00 (according to my graphs) and came back when I hit the 'connect' button this morning at about 7:30.

Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] link: DOWN event
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] link: reconnection attempt 1
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] LCP: Down event
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] LCP: state change Opened --> Starting
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] AUTH: Accounting data for user : 17470 seconds, 103489106 octets in, 12456702 octets out
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] Bundle up: 0 links, total bandwidth 9600 bps
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] IPCP: Close event
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] IPCP: state change Opened --> Closing
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] IPCP: SendTerminateReq #4
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] error writing len 8 frame to bypass: Network is down
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] IPCP: LayerDown
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] IFACE: Delete route 0.0.0.0/0 196.2.112.1 failed: No such process
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] IFACE: Removing IPv4 address from pppoe0 failed: Device not configured
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] can't remove hook inet from node "pppoe0:": No such file or directory
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] IFACE: Down event
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] IFACE: ioctl(pppoe0, SIOCGIFFLAGS): Device not configured
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] IPCP: Down event
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] IPCP: LayerFinish
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] No NCPs left. Closing links...
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] closing link "pppoeclient"...
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] IPCP: state change Closing --> Initial
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] AUTH: Cleanup
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] LCP: LayerDown
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] link: CLOSE event
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] LCP: Close event
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] LCP: state change Starting --> Initial
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] LCP: LayerFinish
Dec 15 00:09:34 pfSense2 mpd: [pppoeclient] pausing 5 seconds before open
Dec 15 07:24:17 pfSense2 mpd: caught fatal signal term
Dec 15 07:24:17 pfSense2 mpd: [pppoeclient] IFACE: Close event
Dec 15 07:24:17 pfSense2 mpd: [pppoeclient] IPCP: Close event
Dec 15 07:24:19 pfSense2 mpd: process 17585 terminated
Dec 15 07:24:19 pfSense2 mpd: Multi-link PPP daemon for FreeBSD
Dec 15 07:24:19 pfSense2 mpd:  
Dec 15 07:24:19 pfSense2 mpd: process 36029 started, version 4.4.1 (root@FreeBSD_8.0_pfSense_2.0-snaps.pfsense.org 17:43  5-Nov-2009)
Dec 15 07:24:19 pfSense2 mpd: [pppoeclient] using interface pppoe0
Dec 15 07:24:19 pfSense2 mpd: [pppoeclient] link: OPEN event
Dec 15 07:24:19 pfSense2 mpd: [pppoeclient] LCP: Open event
Dec 15 07:24:19 pfSense2 mpd: [pppoeclient] LCP: state change Initial --> Starting
Dec 15 07:24:19 pfSense2 mpd: [pppoeclient] LCP: LayerStart
Dec 15 07:24:19 pfSense2 mpd: [pppoeclient] PPPoE: Connecting to ''
Dec 15 07:24:19 pfSense2 mpd: PPPoE: rec'd ACNAME "00:C0:EE:C9:05:6A"
Dec 15 07:24:20 pfSense2 mpd: [pppoeclient] PPPoE: connection successful

eri--

igor

Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: authorization failed
Dec 14 23:42:19 pfsense mpd: [pppoeclient] LCP: parameter negotiation failed

_igor_

I've seen this, but with pfSense 1.2.x it works great. Auto-connect occurs whenever pfSense disconnects.
When i connect manually, mpd tries several times to get a connect. Thats good. When a request from network comes to connect, nothing happens. No entry in system.log, only notice is "Not available" inside browser or other programs which try to connect.

Auto-connect doesn't occur with 2.0. Whenever I want to connect i have to do it manually. Even trying with a ping in background pfSense disconnects from time to time and refuses to connect again.
I made a new install without packages to not disturb the system with eventually broken packages. Same thing.

Whenever the connection refuses to send/recieve data even when manual connect was successful, i sometimes have luck when starting the initial wizard, going thru it without changing anything. After saving the settings the connection works. But not everytime. Sometimes a reboot helps to get it pasing data from out/in to in/out. Very strange.
Sometimes I have to make a new "update" with the same version to get it running. Whenever this happens, the pause between connection and passing data is gone, say, when I connect i can surf directly without pause.

eri--

Post the 1.2 mpd log as you did for 2.0 to verify this.

_igor_

Here we go:

Edit: the whole thing is too long, added the whole file.

system.txt

eri--

It is something regarding your ISP that does not de-authenticate you correctly and something has changed in mpd to make it more strict on later versions.
It needs more investigation and more time though i have not much as of now.

Though you can take a packet trace and attach it here so i can follow later on.

_igor_

ok, will do. Should I do it with the 2.0 or the 1.2.3? I think it should be tcpdump, but how should I do this? Looked around the man-page, but i don't understand it much.

Should it be like this?

tcpdump -i bge0 -n ether proto 0x8863 '||' ether proto 0x8864

assuming that bge0 is my wan-interface.

_igor_

ok, the tcpdump in the mentioned way only worked with pfSense 1.2.3, so for 2.0 I did 2 separate tcpdumps. Strange…
Hope it helps.

First I disconnected the wan-IF, auto-connect worked on 1.2.3, so no further things to do. Surfed around, disconnected some times.
Same on 2.0, except the failing autoconnect. So I connected via Button, surfed, disconnected and connected several times.

If you need more, tell me please.
I zipped the files and attached .txt to upload it. Please remove the .txt to get the zip back.

tcpdump.zip.txt

ambo

@ermal:

something has changed in mpd to make it more strict on later versions

It seems to me that mpd stops reconnecting when it experiences a connect fail or auth fail. Can't say whether this is a feature or a bug but it is definitely a problem.

Even a connection that has auth failed should be retried periodically since the reason for the failure can go away and one would expect pfSense to recover from this within a reasonable time and without intervention.

I have a similar problem, PPOe disconnects every day, and don't connect back again automatically, I have to do it manually, with version 1.2.3 and previous this work perfectly, if ppoe disconnects for any reason it reconnects automatically.

nocer

Hi.

I've no clue how it was done in 1.2.x but just wondering if you guys force mpd to re-establish a connection by adding "set bundle no noretry" into appropriate section of your interfaces.inc. This will force mpd to re-connect whenever the keep-alive expires. Or sit tight and wait for it'd be fixed eventually in any later builds…

cheers,

_igor_

Thanks much! I'll try it.

Michael Sh.

This problem not only for PPoE.
I use separate PPTP mpd client connection. After linkdown sometimes reconnect sucsessful, but many times not.
Additional:
1. Cannot set MTU for PPTP link.
2. Static routes for PPTP link not activating after link down/up.

_igor_

It doesn't work. As I can see, mpd dies when a disconnect occurs. Logs show this: (reverse order)

Jan 17 19:58:47 mpd:
Jan 17 19:58:47 mpd: Multi-link PPP daemon for FreeBSD
Jan 17 19:58:46 mpd: process 37549 terminated
Jan 17 19:58:44 mpd: caught fatal signal term
Jan 17 19:57:41 last message repeated 7 times

cat /var/etc/mpd_wan.conf
startup:
pppoeclient:
new -i pppoe0 pppoeclient pppoeclient
set iface route default
set iface enable 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 iface addrs 192.0.2.112 192.0.2.113
set bundle disable multilink
set bundle no noretry
set auth authname "xxx"
set auth password "yyy"
set link keep-alive 10 60
set link max-redial 0
set link no acfcomp protocomp
set link disable pap chap
set link accept chap
set link mtu 1492
set ipcp yes vjcomp
set ipcp ranges 0.0.0.0/0 0.0.0.0/0
set ipcp enable req-pri-dns
set ipcp enable req-sec-dns
open

¿Any news about this?

I'm working with latest version, 2.0-BETA1 built on Fri Jan 22 00:26:29 EST 2010, and this problem persists, every morning I've to manually connect to my ISP.

_igor_

Does the option "set bundle no noretry" help? Did you try it?

nocer

Hi,

Again I have no clue about 1.2.x but auto-reconnect just works fine for me with;

set link max-redial 0
set bundle no noretry
(set link keep-alive 5 15) <- 10 60 is way too loooooooong to me

into my mpd.conf, just to clarify, I tested this by disconnecting utp to the modem, wait 'till keep-alive expired
then connect the utp to the modem again, link re-established again. Need to flush routing table some times.
Any chances to try on-demand mode instead if you've outgoing traffic constantly.

It's about to consider moving to mpd5 since it's been there for a while and quite stable for now…

cheers,