MLPPP not working - Multilink is not Negociated by MPD5 - PPPOE Changes
-
This was working for me, but has since stopped since 2.2.4. I cannot see why for it to stop all of a sudden. I have been through trouble shooting with my ISP, but they indicate that it is an issue with my router PFSENSE.
|
Here is the CONF file for MPD5:
startup:
configure the console
set console close
configure the web server
set web close
default:
pppoeclient:
create bundle static wan
#set bundle links wan_link0 wan_link1
set bundle enable IPV6CP
set bundle disable round-robin
set bundle disable bw-manage
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 iface mtu 1485
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
set ipcp disable vjcomp
log +bund +bund2 +ccp +chat +iface +ipcp +ipcp2 +lcp +lcp2 +link +auth +auth2create link static wan_link0 pppoe
set auth authname "xxxxxxxx@wiredhighspeed.com"
set auth password xxxxxxxxxx
set pppoe iface em1
set pppoe service "teksavvy"
set link max-redial 0
set link keep-alive 10 60
set link enable multilink
set link enable shortseq
set link disable protocomp
set link disable chap pap
set link accept chap pap eap
set link disable incoming
set link mrru 1486
set link mtu 1487
#set pppoe max-payload 1500
set link bandwidth 5056000
set link action bundle wan
opencreate link static wan_link1 pppoe
set auth authname "xxxxxxxx@wiredhighspeed.com"
set auth password xxxxxxxxxx
set pppoe iface em2
set pppoe service "teksavvy"
set link max-redial 0
set link keep-alive 10 60
set link enable multilink
set link enable shortseq
set link disable protocomp
set link disable chap pap
set link accept chap pap eap
set link disable incoming
set link mrru 1486
set link mtu 1487
#set pppoe max-payload 1500
set link bandwidth 5056000
set link action bundle wan
openPlease see attached log:
Sep 28 22:53:44 imperialhome ppp: Multi-link PPP daemon for FreeBSD
Sep 28 22:53:44 imperialhome ppp:
Sep 28 22:53:44 imperialhome ppp: process 84848 started, version 5.7 (root@pfSense_RELENG_2_2_amd64_amd64-pfSense_REL
ENG_2_2-job-01 00:07 9-Jun-2015)
Sep 28 22:53:44 imperialhome ppp: web: web is not running
Sep 28 22:53:44 imperialhome ppp: [wan] Bundle: Interface ng0 created
Sep 28 22:53:44 imperialhome ppp: [wan_link0] Link: OPEN event
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: Open event
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: state change Initial –> Starting
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: LayerStart
Sep 28 22:53:44 imperialhome ppp: [wan_link1] Link: OPEN event
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: Open event
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: state change Initial –> Starting
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: LayerStart
Sep 28 22:53:44 imperialhome ppp: [wan_link0] PPPoE: Connecting to 'teksavvy'
Sep 28 22:53:44 imperialhome ppp: [wan_link1] PPPoE: Connecting to 'teksavvy'
Sep 28 22:53:44 imperialhome ppp: PPPoE: rec'd ACNAME "xxxxxxxxxAR04"
Sep 28 22:53:44 imperialhome ppp: PPPoE: rec'd ACNAME "xxxxxxxxxAR04"
Sep 28 22:53:44 imperialhome ppp: [wan_link0] PPPoE: connection successful
Sep 28 22:53:44 imperialhome ppp: [wan_link0] Link: UP event
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: Up event
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: state change Starting –> Req-Sent
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: phase shift DEAD –> ESTABLISH
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: SendConfigReq #1
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MRU 1492
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MAGICNUM 27f1a640
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MP MRRU 1486
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MP SHORTSEQ
Sep 28 22:53:44 imperialhome ppp: [wan_link0] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 28 22:53:44 imperialhome ppp: [wan_link1] PPPoE: connection successful
Sep 28 22:53:44 imperialhome ppp: [wan_link1] Link: UP event
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: Up event
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: state change Starting –> Req-Sent
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: phase shift DEAD –> ESTABLISH
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: SendConfigReq #1
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MAGICNUM 2b41a9d0
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MP MRRU 1486
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MP SHORTSEQ
Sep 28 22:53:44 imperialhome ppp: [wan_link1] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: rec'd Configure Request #107 (Req-Sent)
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MRU 1460
Sep 28 22:53:44 imperialhome ppp: [wan_link0] AUTHPROTO CHAP MD5
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MAGICNUM 29ceb3b3
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: SendConfigAck #107
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MRU 1460
Sep 28 22:53:44 imperialhome ppp: [wan_link0] AUTHPROTO CHAP MD5
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MAGICNUM 29ceb3b3
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: state change Req-Sent –> Ack-Sent
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: rec'd Configure Reject #1 (Ack-Sent)
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MP MRRU 1486
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MP SHORTSEQ
Sep 28 22:53:44 imperialhome ppp: [wan_link0] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: SendConfigReq #2
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MRU 1492
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MAGICNUM 27f1a640
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: rec'd Configure Request #69 (Req-Sent)
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MRU 1460
Sep 28 22:53:44 imperialhome ppp: [wan_link1] AUTHPROTO CHAP MD5
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MAGICNUM 4f5fccd0
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: SendConfigAck #69
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MRU 1460
Sep 28 22:53:44 imperialhome ppp: [wan_link1] AUTHPROTO CHAP MD5
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MAGICNUM 4f5fccd0
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: state change Req-Sent –> Ack-Sent
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: rec'd Configure Reject #1 (Ack-Sent)
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MP MRRU 1486
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MP SHORTSEQ
Sep 28 22:53:44 imperialhome ppp: [wan_link1] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: SendConfigReq #2
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MAGICNUM 2b41a9d0
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: rec'd Configure Ack #2 (Ack-Sent)
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MRU 1492
Sep 28 22:53:44 imperialhome ppp: [wan_link0] MAGICNUM 27f1a640
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: state change Ack-Sent –> Opened
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: phase shift ESTABLISH –> AUTHENTICATE
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Sep 28 22:53:44 imperialhome ppp: [wan_link0] LCP: LayerUp
Sep 28 22:53:44 imperialhome ppp: [wan_link0] CHAP: rec'd CHALLENGE #183 len: 48
Sep 28 22:53:44 imperialhome ppp: [wan_link0] Name: "xxxxxxxxAR04"
Sep 28 22:53:44 imperialhome ppp: [wan_link0] CHAP: Using authname "xxxxxxx@wiredhighspeed.com"
Sep 28 22:53:44 imperialhome ppp: [wan_link0] CHAP: sending RESPONSE #183 len: 48
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: rec'd Configure Ack #2 (Ack-Sent)
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 22:53:44 imperialhome ppp: [wan_link1] MAGICNUM 2b41a9d0
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: state change Ack-Sent –> Opened
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: phase shift ESTABLISH –> AUTHENTICATE
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: auth: peer wants CHAP, I want nothing
Sep 28 22:53:44 imperialhome ppp: [wan_link1] LCP: LayerUp
Sep 28 22:53:44 imperialhome ppp: [wan_link1] CHAP: rec'd CHALLENGE #56 len: 36
Sep 28 22:53:44 imperialhome ppp: [wan_link1] Name: "xxxxxxxxAR04"
Sep 28 22:53:44 imperialhome ppp: [wan_link1] CHAP: Using authname "xxxxxxx@wiredhighspeed.com"
Sep 28 22:53:44 imperialhome ppp: [wan_link1] CHAP: sending RESPONSE #56 len: 48
Sep 28 22:53:46 imperialhome ppp: [wan_link0] LCP: SendConfigReq #3
Sep 28 22:53:46 imperialhome ppp: [wan_link0] MRU 1492
Sep 28 22:53:46 imperialhome ppp: [wan_link0] MAGICNUM 27f1a640
Sep 28 22:53:46 imperialhome ppp: [wan_link0] LCP: SendConfigAck #40
Sep 28 22:53:46 imperialhome ppp: [wan_link0] MRU 1452
Sep 28 22:53:46 imperialhome ppp: [wan_link0] AUTHPROTO PAP
Sep 28 22:53:46 imperialhome ppp: [wan_link0] MAGICNUM 756edb26
Sep 28 22:53:46 imperialhome ppp: [wan_link0] LCP: state change Opened –> Ack-Sent
Sep 28 22:53:46 imperialhome ppp: [wan_link0] LCP: phase shift AUTHENTICATE –> ESTABLISH
Sep 28 22:53:46 imperialhome ppp: [wan_link0] AUTH: Cleanup
Sep 28 22:53:46 imperialhome ppp: [wan_link1] LCP: rec'd Configure Ack #3 (Ack-Sent)
Sep 28 22:53:46 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 22:53:46 imperialhome ppp: [wan_link1] MAGICNUM 2b41a9d0
Sep 28 22:53:46 imperialhome ppp: [wan_link1] LCP: state change Ack-Sent –> Opened
Sep 28 22:53:46 imperialhome ppp: [wan_link1] LCP: phase shift ESTABLISH –> AUTHENTICATE
Sep 28 22:53:46 imperialhome ppp: [wan_link1] LCP: auth: peer wants PAP, I want nothing
Sep 28 22:53:46 imperialhome ppp: [wan_link1] PAP: using authname "xxxxxxx@wiredhighspeed.com"
Sep 28 22:53:46 imperialhome ppp: [wan_link1] PAP: sending REQUEST #1 len: 43
Sep 28 22:53:46 imperialhome ppp: [wan_link1] LCP: LayerUp
Sep 28 22:53:46 imperialhome ppp: [wan_link0] LCP: rec'd Configure Ack #3 (Ack-Sent)
Sep 28 22:53:46 imperialhome ppp: [wan_link0] MRU 1492
Sep 28 22:53:46 imperialhome ppp: [wan_link0] MAGICNUM 27f1a640
Sep 28 22:53:46 imperialhome ppp: [wan_link0] LCP: state change Ack-Sent –> Opened
Sep 28 22:53:46 imperialhome ppp: [wan_link0] LCP: phase shift ESTABLISH –> AUTHENTICATE
Sep 28 22:53:46 imperialhome ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing
Sep 28 22:53:46 imperialhome ppp: [wan_link0] PAP: using authname "xxxxxxx@wiredhighspeed.com"
Sep 28 22:53:46 imperialhome ppp: [wan_link0] PAP: sending REQUEST #1 len: 43
Sep 28 22:53:46 imperialhome ppp: [wan_link0] LCP: LayerUp
Sep 28 22:53:47 imperialhome ppp: [wan_link1] PAP: rec'd ACK #1 len: 5
Sep 28 22:53:47 imperialhome ppp: [wan_link1] LCP: authorization successful
Sep 28 22:53:47 imperialhome ppp: [wan_link1] LCP: phase shift AUTHENTICATE –> NETWORK
Sep 28 22:53:47 imperialhome ppp: [wan_link1] Link: Matched action 'bundle "wan" ""'
Sep 28 22:53:47 imperialhome ppp: [wan_link1] Link: Join bundle "wan"
Sep 28 22:53:47 imperialhome ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 5056000 bps
Sep 28 22:53:47 imperialhome ppp: [wan] IPCP: Open event
Sep 28 22:53:47 imperialhome ppp: [wan] IPCP: state change Initial –> Starting
Sep 28 22:53:47 imperialhome ppp: [wan] IPCP: LayerStart
Sep 28 22:53:47 imperialhome ppp: [wan] IPV6CP: Open event
Sep 28 22:53:47 imperialhome ppp: [wan] IPV6CP: state change Initial –> Starting
Sep 28 22:53:47 imperialhome ppp: [wan] IPV6CP: LayerStart
Sep 28 22:53:47 imperialhome ppp: [wan] IPCP: Up event
Sep 28 22:53:47 imperialhome ppp: [wan] IPCP: state change Starting –> Req-Sent
Sep 28 22:53:47 imperialhome ppp: [wan] IPCP: SendConfigReq #1
Sep 28 22:53:47 imperialhome ppp: [wan] IPADDR 0.0.0.0
Sep 28 22:53:47 imperialhome ppp: [wan] PRIDNS 0.0.0.0
Sep 28 22:53:47 imperialhome ppp: [wan] SECDNS 0.0.0.0
Sep 28 22:53:47 imperialhome ppp: [wan] IPV6CP: Up event
Sep 28 22:53:47 imperialhome ppp: [wan] IPV6CP: state change Starting –> Req-Sent
Sep 28 22:53:47 imperialhome ppp: [wan] IPV6CP: SendConfigReq #1
Sep 28 22:53:47 imperialhome ppp: [wan_link0] PAP: rec'd ACK #1 len: 5
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: authorization successful
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: phase shift AUTHENTICATE –> NETWORK
Sep 28 22:53:47 imperialhome ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Sep 28 22:53:47 imperialhome ppp: [wan_link0] Can't join bundle wan without multilink negotiated.
Sep 28 22:53:47 imperialhome ppp: [wan_link0] link did not validate in bundle
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: parameter negotiation failed
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: state change Opened –> Stopping
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: phase shift NETWORK –> TERMINATE
Sep 28 22:53:47 imperialhome ppp: [wan_link0] AUTH: Cleanup
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: SendTerminateReq #4
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: LayerDown
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: rec'd Terminate Ack #4 (Stopping)
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: state change Stopping –> Stopped
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: phase shift TERMINATE –> ESTABLISH
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: LayerFinish
Sep 28 22:53:47 imperialhome ppp: [wan_link0] PPPoE: connection closed
Sep 28 22:53:47 imperialhome ppp: [wan_link0] Link: DOWN event
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: Down event
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: state change Stopped –> Starting
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: phase shift ESTABLISH –> DEAD
Sep 28 22:53:47 imperialhome ppp: [wan_link0] LCP: LayerStart
Sep 28 22:53:47 imperialhome ppp: [wan_link0] Link: reconnection attempt 1 in 1 seconds|
-
Get latest 2.2.5 snapshot to have any chance of this working… https://redmine.pfsense.org/issues/4568
-
Sorry forgot to mention that I am on the latest snapshot, and I don't think my issue has to do with losing settings. I am aware of the issue, and I have put a custom mpd_wan.conf file in the /conf/ folder to circumvent any issues.
Also here is a VERBOSE log showing one iteration of a re-connection retry, I do believe that it is a PFSENSE issue as it is working with Mikrotik router.Here's a more verbose log:
Sep 28 23:32:45 imperialhome ppp: Multi-link PPP daemon for FreeBSD
Sep 28 23:32:45 imperialhome ppp:
Sep 28 23:32:45 imperialhome ppp: process 39949 started, version 5.7 (root@pfSense_RELENG_2_2_amd64_amd64-pfSense_REL
ENG_2_2-job-01 00:07 9-Jun-2015)
Sep 28 23:32:45 imperialhome ppp: web: web is not running
Sep 28 23:32:45 imperialhome ppp: [wan] Bundle: Interface ng0 created
Sep 28 23:32:45 imperialhome ppp: [wan_link0] Link: OPEN event
Sep 28 23:32:45 imperialhome ppp: [wan_link0] LCP: Open event
Sep 28 23:32:45 imperialhome ppp: [wan_link0] LCP: state change Initial –> Starting
Sep 28 23:32:45 imperialhome ppp: [wan_link0] LCP: LayerStart
Sep 28 23:32:45 imperialhome ppp: [wan_link1] Link: OPEN event
Sep 28 23:32:45 imperialhome ppp: [wan_link1] LCP: Open event
Sep 28 23:32:45 imperialhome ppp: [wan_link1] LCP: state change Initial –> Starting
Sep 28 23:32:45 imperialhome ppp: [wan_link1] LCP: LayerStart
Sep 28 23:32:45 imperialhome ppp: [wan_link0] device: OPEN event
Sep 28 23:32:45 imperialhome ppp: [em1] exec: /sbin/ifconfig em1 up
Sep 28 23:32:45 imperialhome ppp: [wan_link0] PPPoE: Connecting to 'teksavvy'
Sep 28 23:32:45 imperialhome ppp: [wan_link1] device: OPEN event
Sep 28 23:32:45 imperialhome ppp: [em2] exec: /sbin/ifconfig em2 up
Sep 28 23:32:45 imperialhome ppp: [wan_link1] PPPoE: Connecting to 'teksavvy'
Sep 28 23:32:45 imperialhome ppp: PPPoE: rec'd ACNAME "CLGRAB21AR04"
Sep 28 23:32:45 imperialhome ppp: PPPoE: rec'd ACNAME "CLGRAB21AR04"
Sep 28 23:32:45 imperialhome ppp: [wan_link0] PPPoE: connection successful
Sep 28 23:32:45 imperialhome ppp: [wan_link0] device: UP event
Sep 28 23:32:45 imperialhome ppp: [wan_link0] Link: UP event
Sep 28 23:32:45 imperialhome ppp: [wan_link0] Link: origination is local
Sep 28 23:32:45 imperialhome ppp: [wan_link0] LCP: Up event
Sep 28 23:32:45 imperialhome ppp: [wan_link0] LCP: state change Starting –> Req-Sent
Sep 28 23:32:45 imperialhome ppp: [wan_link0] LCP: phase shift DEAD –> ESTABLISH
Sep 28 23:32:45 imperialhome ppp: [wan_link0] LCP: SendConfigReq #1
Sep 28 23:32:45 imperialhome ppp: [wan_link0] MRU 1492
Sep 28 23:32:45 imperialhome ppp: [wan_link0] MAGICNUM 182d263d
Sep 28 23:32:45 imperialhome ppp: [wan_link0] MP MRRU 1486
Sep 28 23:32:45 imperialhome ppp: [wan_link0] MP SHORTSEQ
Sep 28 23:32:45 imperialhome ppp: [wan_link0] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 28 23:32:45 imperialhome ppp: [wan_link1] PPPoE: connection successful
Sep 28 23:32:45 imperialhome ppp: [wan_link1] device: UP event
Sep 28 23:32:45 imperialhome ppp: [wan_link1] Link: UP event
Sep 28 23:32:45 imperialhome ppp: [wan_link1] Link: origination is local
Sep 28 23:32:45 imperialhome ppp: [wan_link1] LCP: Up event
Sep 28 23:32:45 imperialhome ppp: [wan_link1] LCP: state change Starting –> Req-Sent
Sep 28 23:32:45 imperialhome ppp: [wan_link1] LCP: phase shift DEAD –> ESTABLISH
Sep 28 23:32:45 imperialhome ppp: [wan_link1] LCP: SendConfigReq #1
Sep 28 23:32:45 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 23:32:45 imperialhome ppp: [wan_link1] MAGICNUM 1b8b160b
Sep 28 23:32:45 imperialhome ppp: [wan_link1] MP MRRU 1486
Sep 28 23:32:45 imperialhome ppp: [wan_link1] MP SHORTSEQ
Sep 28 23:32:45 imperialhome ppp: [wan_link1] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 28 23:32:45 imperialhome ppp: [wan_link0] LCP: rec'd Configure Request #80 (Req-Sent)
Sep 28 23:32:45 imperialhome ppp: [wan_link0] MRU 1460
Sep 28 23:32:45 imperialhome ppp: [wan_link0] AUTHPROTO CHAP MD5
Sep 28 23:32:45 imperialhome ppp: [wan_link0] MAGICNUM 481ecdea
Sep 28 23:32:45 imperialhome ppp: [wan_link0] LCP: SendConfigAck #80
Sep 28 23:32:45 imperialhome ppp: [wan_link0] MRU 1460
Sep 28 23:32:45 imperialhome ppp: [wan_link0] AUTHPROTO CHAP MD5
Sep 28 23:32:45 imperialhome ppp: [wan_link0] MAGICNUM 481ecdea
Sep 28 23:32:45 imperialhome ppp: [wan_link0] LCP: state change Req-Sent –> Ack-Sent
Sep 28 23:32:45 imperialhome ppp: [wan_link0] LCP: rec'd Configure Reject #1 (Ack-Sent)
Sep 28 23:32:45 imperialhome ppp: [wan_link0] MP MRRU 1486
Sep 28 23:32:45 imperialhome ppp: [wan_link0] MP SHORTSEQ
Sep 28 23:32:45 imperialhome ppp: [wan_link0] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 28 23:32:45 imperialhome ppp: [wan_link0] LCP: SendConfigReq #2
Sep 28 23:32:45 imperialhome ppp: [wan_link0] MRU 1492
Sep 28 23:32:45 imperialhome ppp: [wan_link0] MAGICNUM 182d263d
Sep 28 23:32:45 imperialhome ppp: [wan_link1] LCP: rec'd Configure Request #85 (Req-Sent)
Sep 28 23:32:45 imperialhome ppp: [wan_link1] MRU 1460
Sep 28 23:32:45 imperialhome ppp: [wan_link1] AUTHPROTO CHAP MD5
Sep 28 23:32:45 imperialhome ppp: [wan_link1] MAGICNUM 4be8a949
Sep 28 23:32:45 imperialhome ppp: [wan_link1] LCP: SendConfigAck #85
Sep 28 23:32:45 imperialhome ppp: [wan_link1] MRU 1460
Sep 28 23:32:45 imperialhome ppp: [wan_link1] AUTHPROTO CHAP MD5
Sep 28 23:32:45 imperialhome ppp: [wan_link1] MAGICNUM 4be8a949
Sep 28 23:32:45 imperialhome ppp: [wan_link1] LCP: state change Req-Sent –> Ack-Sent
Sep 28 23:32:45 imperialhome ppp: [wan_link1] LCP: rec'd Configure Reject #1 (Ack-Sent)
Sep 28 23:32:45 imperialhome ppp: [wan_link1] MP MRRU 1486
Sep 28 23:32:45 imperialhome ppp: [wan_link1] MP SHORTSEQ
Sep 28 23:32:45 imperialhome ppp: [wan_link1] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 28 23:32:45 imperialhome ppp: [wan_link1] LCP: SendConfigReq #2
Sep 28 23:32:45 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 23:32:45 imperialhome ppp: [wan_link1] MAGICNUM 1b8b160b
Sep 28 23:32:46 imperialhome ppp: [wan_link0] LCP: rec'd Configure Ack #2 (Ack-Sent)
Sep 28 23:32:46 imperialhome ppp: [wan_link0] MRU 1492
Sep 28 23:32:46 imperialhome ppp: [wan_link0] MAGICNUM 182d263d
Sep 28 23:32:46 imperialhome ppp: [wan_link0] LCP: state change Ack-Sent –> Opened
Sep 28 23:32:46 imperialhome ppp: [wan_link0] LCP: phase shift ESTABLISH –> AUTHENTICATE
Sep 28 23:32:46 imperialhome ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Sep 28 23:32:46 imperialhome ppp: [wan_link0] LCP: LayerUp
Sep 28 23:32:46 imperialhome ppp: [wan_link0] CHAP: rec'd CHALLENGE #159 len: 45
Sep 28 23:32:46 imperialhome ppp: [wan_link0] Name: "CLGRAB21AR04"
Sep 28 23:32:46 imperialhome ppp: [wan_link0] CHAP: Using authname "xxxxxxxx@wiredhighspeed.com"
Sep 28 23:32:46 imperialhome ppp: [wan_link0] CHAP: sending RESPONSE #159 len: 48
Sep 28 23:32:46 imperialhome ppp: [wan_link1] LCP: rec'd Configure Ack #2 (Ack-Sent)
Sep 28 23:32:46 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 23:32:46 imperialhome ppp: [wan_link1] MAGICNUM 1b8b160b
Sep 28 23:32:46 imperialhome ppp: [wan_link1] LCP: state change Ack-Sent –> Opened
Sep 28 23:32:46 imperialhome ppp: [wan_link1] LCP: phase shift ESTABLISH –> AUTHENTICATE
Sep 28 23:32:46 imperialhome ppp: [wan_link1] LCP: auth: peer wants CHAP, I want nothing
Sep 28 23:32:46 imperialhome ppp: [wan_link1] LCP: LayerUp
Sep 28 23:32:46 imperialhome ppp: [wan_link1] CHAP: rec'd CHALLENGE #208 len: 46
Sep 28 23:32:46 imperialhome ppp: [wan_link1] Name: "CLGRAB21AR04"
Sep 28 23:32:46 imperialhome ppp: [wan_link1] CHAP: Using authname "xxxxxxxx@wiredhighspeed.com"
Sep 28 23:32:46 imperialhome ppp: [wan_link1] CHAP: sending RESPONSE #208 len: 48
Sep 28 23:32:48 imperialhome ppp: [wan_link1] CHAP: sending RESPONSE #208 len: 48
Sep 28 23:32:48 imperialhome ppp: [wan_link0] CHAP: sending RESPONSE #159 len: 48
Sep 28 23:32:49 imperialhome ppp: [wan_link0] LCP: rec'd Configure Request #236 (Opened)
Sep 28 23:32:49 imperialhome ppp: [wan_link0] MRU 1452
Sep 28 23:32:49 imperialhome ppp: [wan_link0] AUTHPROTO PAP
Sep 28 23:32:49 imperialhome ppp: [wan_link0] MAGICNUM 56fb027b
Sep 28 23:32:49 imperialhome ppp: [wan_link0] LCP: LayerDown
Sep 28 23:32:49 imperialhome ppp: [wan_link0] LCP: SendConfigReq #3
Sep 28 23:32:49 imperialhome ppp: [wan_link0] MRU 1492
Sep 28 23:32:49 imperialhome ppp: [wan_link0] MAGICNUM 182d263d
Sep 28 23:32:49 imperialhome ppp: [wan_link0] LCP: SendConfigAck #236
Sep 28 23:32:49 imperialhome ppp: [wan_link0] MRU 1452
Sep 28 23:32:49 imperialhome ppp: [wan_link0] AUTHPROTO PAP
Sep 28 23:32:49 imperialhome ppp: [wan_link0] MAGICNUM 56fb027b
Sep 28 23:32:49 imperialhome ppp: [wan_link0] LCP: state change Opened –> Ack-Sent
Sep 28 23:32:49 imperialhome ppp: [wan_link0] LCP: phase shift AUTHENTICATE –> ESTABLISH
Sep 28 23:32:49 imperialhome ppp: [wan_link0] AUTH: Cleanup
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: rec'd Configure Request #178 (Opened)
Sep 28 23:32:49 imperialhome ppp: [wan_link1] MRU 1452
Sep 28 23:32:49 imperialhome ppp: [wan_link1] AUTHPROTO PAP
Sep 28 23:32:49 imperialhome ppp: [wan_link1] MAGICNUM 0c22a3c7
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: LayerDown
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: SendConfigReq #3
Sep 28 23:32:49 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 23:32:49 imperialhome ppp: [wan_link1] MAGICNUM 1b8b160b
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: SendConfigAck #178
Sep 28 23:32:49 imperialhome ppp: [wan_link1] MRU 1452
Sep 28 23:32:49 imperialhome ppp: [wan_link1] AUTHPROTO PAP
Sep 28 23:32:49 imperialhome ppp: [wan_link1] MAGICNUM 0c22a3c7
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: state change Opened –> Ack-Sent
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: phase shift AUTHENTICATE –> ESTABLISH
Sep 28 23:32:49 imperialhome ppp: [wan_link1] AUTH: Cleanup
Sep 28 23:32:49 imperialhome ppp: [wan_link0] LCP: rec'd Configure Ack #3 (Ack-Sent)
Sep 28 23:32:49 imperialhome ppp: [wan_link0] MRU 1492
Sep 28 23:32:49 imperialhome ppp: [wan_link0] MAGICNUM 182d263d
Sep 28 23:32:49 imperialhome ppp: [wan_link0] LCP: state change Ack-Sent –> Opened
Sep 28 23:32:49 imperialhome ppp: [wan_link0] LCP: phase shift ESTABLISH –> AUTHENTICATE
Sep 28 23:32:49 imperialhome ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing
Sep 28 23:32:49 imperialhome ppp: [wan_link0] PAP: using authname "xxxxxxxx@wiredhighspeed.com"
Sep 28 23:32:49 imperialhome ppp: [wan_link0] PAP: sending REQUEST #1 len: 43
Sep 28 23:32:49 imperialhome ppp: [wan_link0] LCP: LayerUp
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: rec'd Configure Ack #3 (Ack-Sent)
Sep 28 23:32:49 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 23:32:49 imperialhome ppp: [wan_link1] MAGICNUM 1b8b160b
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: state change Ack-Sent –> Opened
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: phase shift ESTABLISH –> AUTHENTICATE
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: auth: peer wants PAP, I want nothing
Sep 28 23:32:49 imperialhome ppp: [wan_link1] PAP: using authname "xxxxxxxx@wiredhighspeed.com"
Sep 28 23:32:49 imperialhome ppp: [wan_link1] PAP: sending REQUEST #1 len: 43
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: LayerUp
Sep 28 23:32:49 imperialhome ppp: [wan_link0] PAP: rec'd ACK #1 len: 5
Sep 28 23:32:49 imperialhome ppp: [wan_link0] LCP: authorization successful
Sep 28 23:32:49 imperialhome ppp: [wan_link0] LCP: phase shift AUTHENTICATE –> NETWORK
Sep 28 23:32:49 imperialhome ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Sep 28 23:32:49 imperialhome ppp: [wan_link0] Link: Join bundle "wan"
Sep 28 23:32:49 imperialhome ppp: [wan] IFACE: setting pppoe0 MTU to 1452 bytes
Sep 28 23:32:49 imperialhome ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 5056000 bps
Sep 28 23:32:49 imperialhome ppp: [wan] IPCP: Open event
Sep 28 23:32:49 imperialhome ppp: [wan] IPCP: state change Initial –> Starting
Sep 28 23:32:49 imperialhome ppp: [wan] IPCP: LayerStart
Sep 28 23:32:49 imperialhome ppp: [wan] IPV6CP: Open event
Sep 28 23:32:49 imperialhome ppp: [wan] IPV6CP: state change Initial –> Starting
Sep 28 23:32:49 imperialhome ppp: [wan] IPV6CP: LayerStart
Sep 28 23:32:49 imperialhome ppp: [wan] IPCP: Up event
Sep 28 23:32:49 imperialhome ppp: [wan] IPCP: state change Starting –> Req-Sent
Sep 28 23:32:49 imperialhome ppp: [wan] IPCP: SendConfigReq #1
Sep 28 23:32:49 imperialhome ppp: [wan] IPADDR 0.0.0.0
Sep 28 23:32:49 imperialhome ppp: [wan] PRIDNS 0.0.0.0
Sep 28 23:32:49 imperialhome ppp: [wan] SECDNS 0.0.0.0
Sep 28 23:32:49 imperialhome ppp: [wan] IPV6CP: Up event
Sep 28 23:32:49 imperialhome ppp: [wan] IPV6CP: state change Starting –> Req-Sent
Sep 28 23:32:49 imperialhome ppp: [wan] IPV6CP: SendConfigReq #1
Sep 28 23:32:49 imperialhome ppp: [wan] INTIDENT 0230:18ff:fea3:b13f
Sep 28 23:32:49 imperialhome ppp: [wan_link1] PAP: rec'd ACK #1 len: 5
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: authorization successful
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: phase shift AUTHENTICATE –> NETWORK
Sep 28 23:32:49 imperialhome ppp: [wan_link1] Link: Matched action 'bundle "wan" ""'
Sep 28 23:32:49 imperialhome ppp: [wan_link1] Can't join bundle wan without multilink negotiated.
Sep 28 23:32:49 imperialhome ppp: [wan_link1] link did not validate in bundle
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: parameter negotiation failed
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: state change Opened –> Stopping
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: phase shift NETWORK –> TERMINATE
Sep 28 23:32:49 imperialhome ppp: [wan_link1] AUTH: Cleanup
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: SendTerminateReq #4
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: LayerDown
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: rec'd Terminate Ack #4 (Stopping)
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: state change Stopping –> Stopped
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: phase shift TERMINATE –> ESTABLISH
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: LayerFinish
Sep 28 23:32:49 imperialhome ppp: [wan_link1] PPPoE: connection closed
Sep 28 23:32:49 imperialhome ppp: [wan_link1] device: DOWN event
Sep 28 23:32:49 imperialhome ppp: [wan_link1] Link: DOWN event
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: Down event
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: state change Stopped –> Starting
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: phase shift ESTABLISH –> DEAD
Sep 28 23:32:49 imperialhome ppp: [wan_link1] LCP: LayerStart
Sep 28 23:32:49 imperialhome ppp: [wan_link1] Link: reconnection attempt 1 in 4 seconds
Sep 28 23:32:49 imperialhome ppp: [wan_link1] device: CLOSE event
Sep 28 23:32:50 imperialhome ppp: [wan] IPCP: rec'd Configure Request #196 (Req-Sent)
Sep 28 23:32:50 imperialhome ppp: [wan] IPADDR 76.10.191.6
Sep 28 23:32:50 imperialhome ppp: [wan] 76.10.191.6 is OK
Sep 28 23:32:50 imperialhome ppp: [wan] IPCP: SendConfigAck #196
Sep 28 23:32:50 imperialhome ppp: [wan] IPADDR 76.10.191.6
Sep 28 23:32:50 imperialhome ppp: [wan] IPCP: state change Req-Sent –> Ack-Sent
Sep 28 23:32:50 imperialhome ppp: [wan] IPCP: rec'd Configure Nak #1 (Ack-Sent)
Sep 28 23:32:50 imperialhome ppp: [wan] IPADDR 76.10.188.94
Sep 28 23:32:50 imperialhome ppp: [wan] 76.10.188.94 is OK
Sep 28 23:32:50 imperialhome ppp: [wan] PRIDNS 76.10.191.198
Sep 28 23:32:50 imperialhome ppp: [wan] SECDNS 76.10.191.199
Sep 28 23:32:50 imperialhome ppp: [wan] IPCP: SendConfigReq #2
Sep 28 23:32:50 imperialhome ppp: [wan] IPADDR 76.10.188.94
Sep 28 23:32:50 imperialhome ppp: [wan] PRIDNS 76.10.191.198
Sep 28 23:32:50 imperialhome ppp: [wan] SECDNS 76.10.191.199
Sep 28 23:32:50 imperialhome ppp: [wan] IPV6CP: rec'd Configure Request #9 (Req-Sent)
Sep 28 23:32:50 imperialhome ppp: [wan] INTIDENT 0090:1a00:0343:14ab
Sep 28 23:32:50 imperialhome ppp: [wan] It's OK.
Sep 28 23:32:50 imperialhome ppp: [wan] IPV6CP: SendConfigAck #9
Sep 28 23:32:50 imperialhome ppp: [wan] INTIDENT 0090:1a00:0343:14ab
Sep 28 23:32:50 imperialhome ppp: [wan] IPV6CP: state change Req-Sent –> Ack-Sent
Sep 28 23:32:50 imperialhome ppp: [wan] IPV6CP: rec'd Configure Ack #1 (Ack-Sent)
Sep 28 23:32:50 imperialhome ppp: [wan] INTIDENT 0230:18ff:fea3:b13f
Sep 28 23:32:50 imperialhome ppp: [wan] IPV6CP: state change Ack-Sent –> Opened
Sep 28 23:32:50 imperialhome ppp: [wan] IPV6CP: LayerUp
Sep 28 23:32:50 imperialhome ppp: [wan] 0230:18ff:fea3:b13f -> 0090:1a00:0343:14ab
Sep 28 23:32:50 imperialhome ppp: [wan] IFACE: Add address fe80::230:18ff:fea3:b13f/64->fe80::90:1a00:343:14ab to pppoe0
Sep 28 23:32:50 imperialhome ppp: [wan] system: /usr/local/sbin/ppp-linkup pppoe0 inet6 fe80::230:18ff:fea3:b13f%pppoe0 fe80::90:1a00:343:14ab%pppoe0 '-' '00:00:00:00:00:00'
Sep 28 23:32:51 imperialhome ppp: [wan] IFACE: Up event
Sep 28 23:32:51 imperialhome ppp: [wan] IFACE: Rename interface ng0 to pppoe0
Sep 28 23:32:51 imperialhome ppp: [wan] IFACE: Change interface pppoe0 flags: -0 +1
Sep 28 23:32:51 imperialhome ppp: [wan] IPCP: rec'd Configure Ack #2 (Ack-Sent)
Sep 28 23:32:51 imperialhome ppp: [wan] IPADDR 76.10.188.94
Sep 28 23:32:51 imperialhome ppp: [wan] PRIDNS 76.10.191.198
Sep 28 23:32:51 imperialhome ppp: [wan] SECDNS 76.10.191.199
Sep 28 23:32:51 imperialhome ppp: [wan] IPCP: state change Ack-Sent –> Opened
Sep 28 23:32:51 imperialhome ppp: [wan] IPCP: LayerUp
Sep 28 23:32:51 imperialhome ppp: [wan] 76.10.188.94 -> 76.10.191.6
Sep 28 23:32:51 imperialhome ppp: [wan] IFACE: Connecting tcpmssfix
Sep 28 23:32:51 imperialhome ppp: [wan] IFACE: Add address 76.10.188.94/32->76.10.191.6 to pppoe0
Sep 28 23:32:51 imperialhome ppp: [wan] IFACE: Add route 0.0.0.0/0 76.10.191.6
Sep 28 23:32:51 imperialhome ppp: [wan] system: /usr/local/sbin/ppp-linkup pppoe0 inet 76.10.188.94/32 76.10.191.6 '-' 'dns1 76.10.191.198' 'dns2 76.10.191.199' '00:00:00:00:00:00'
Sep 28 23:32:56 imperialhome ppp: [wan_link1] Link: reconnection attempt 1
Sep 28 23:32:56 imperialhome ppp: [wan_link1] device: OPEN event
Sep 28 23:32:56 imperialhome ppp: [wan_link1] PPPoE: Connecting to 'teksavvy'
Sep 28 23:32:56 imperialhome ppp: PPPoE: rec'd ACNAME "CLGRAB21AR04"
Sep 28 23:32:56 imperialhome ppp: [wan_link1] PPPoE: connection successful
Sep 28 23:32:56 imperialhome ppp: [wan_link1] device: UP event
Sep 28 23:32:56 imperialhome ppp: [wan_link1] Link: UP event
Sep 28 23:32:56 imperialhome ppp: [wan_link1] Link: origination is local
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: Up event
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: state change Starting –> Req-Sent
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: phase shift DEAD –> ESTABLISH
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: SendConfigReq #5
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MAGICNUM 89eaf4d2
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MP MRRU 1486
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MP SHORTSEQ
Sep 28 23:32:56 imperialhome ppp: [wan_link1] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: rec'd Configure Request #141 (Req-Sent)
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MRU 1460
Sep 28 23:32:56 imperialhome ppp: [wan_link1] AUTHPROTO CHAP MD5
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MAGICNUM 4521bfc5
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: SendConfigAck #141
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MRU 1460
Sep 28 23:32:56 imperialhome ppp: [wan_link1] AUTHPROTO CHAP MD5
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MAGICNUM 4521bfc5
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: state change Req-Sent –> Ack-Sent
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: rec'd Configure Reject #5 (Ack-Sent)
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MP MRRU 1486
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MP SHORTSEQ
Sep 28 23:32:56 imperialhome ppp: [wan_link1] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: SendConfigReq #6
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MAGICNUM 89eaf4d2
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: rec'd Configure Ack #6 (Ack-Sent)
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 23:32:56 imperialhome ppp: [wan_link1] MAGICNUM 89eaf4d2
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: state change Ack-Sent –> Opened
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: phase shift ESTABLISH –> AUTHENTICATE
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: auth: peer wants CHAP, I want nothing
Sep 28 23:32:56 imperialhome ppp: [wan_link1] LCP: LayerUp
Sep 28 23:32:56 imperialhome ppp: [wan_link1] CHAP: rec'd CHALLENGE #25 len: 41
Sep 28 23:32:56 imperialhome ppp: [wan_link1] Name: "CLGRAB21AR04"
Sep 28 23:32:56 imperialhome ppp: [wan_link1] CHAP: Using authname "xxxxxxxx@wiredhighspeed.com"
Sep 28 23:32:56 imperialhome ppp: [wan_link1] CHAP: sending RESPONSE #25 len: 48
Sep 28 23:32:58 imperialhome ppp: [wan_link1] CHAP: sending RESPONSE #25 len: 48
Sep 28 23:32:59 imperialhome ppp: [wan_link1] LCP: rec'd Configure Request #73 (Opened)
Sep 28 23:32:59 imperialhome ppp: [wan_link1] MRU 1452
Sep 28 23:32:59 imperialhome ppp: [wan_link1] AUTHPROTO PAP
Sep 28 23:32:59 imperialhome ppp: [wan_link1] MAGICNUM 6adce9c5
Sep 28 23:32:59 imperialhome ppp: [wan_link1] LCP: LayerDown
Sep 28 23:32:59 imperialhome ppp: [wan_link1] LCP: SendConfigReq #7
Sep 28 23:32:59 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 23:32:59 imperialhome ppp: [wan_link1] MAGICNUM 89eaf4d2
Sep 28 23:32:59 imperialhome ppp: [wan_link1] LCP: SendConfigAck #73
Sep 28 23:32:59 imperialhome ppp: [wan_link1] MRU 1452
Sep 28 23:32:59 imperialhome ppp: [wan_link1] AUTHPROTO PAP
Sep 28 23:32:59 imperialhome ppp: [wan_link1] MAGICNUM 6adce9c5
Sep 28 23:32:59 imperialhome ppp: [wan_link1] LCP: state change Opened –> Ack-Sent
Sep 28 23:32:59 imperialhome ppp: [wan_link1] LCP: phase shift AUTHENTICATE –> ESTABLISH
Sep 28 23:32:59 imperialhome ppp: [wan_link1] AUTH: Cleanup
Sep 28 23:32:59 imperialhome ppp: [wan_link1] LCP: rec'd Configure Ack #7 (Ack-Sent)
Sep 28 23:32:59 imperialhome ppp: [wan_link1] MRU 1492
Sep 28 23:32:59 imperialhome ppp: [wan_link1] MAGICNUM 89eaf4d2
Sep 28 23:32:59 imperialhome ppp: [wan_link1] LCP: state change Ack-Sent –> Opened
Sep 28 23:32:59 imperialhome ppp: [wan_link1] LCP: phase shift ESTABLISH –> AUTHENTICATE
Sep 28 23:32:59 imperialhome ppp: [wan_link1] LCP: auth: peer wants PAP, I want nothing
Sep 28 23:32:59 imperialhome ppp: [wan_link1] PAP: using authname "xxxxxxxx@wiredhighspeed.com"
Sep 28 23:32:59 imperialhome ppp: [wan_link1] PAP: sending REQUEST #1 len: 43
Sep 28 23:32:59 imperialhome ppp: [wan_link1] LCP: LayerUp
Sep 28 23:33:00 imperialhome ppp: [wan_link1] PAP: rec'd NAK #1 len: 31
Sep 28 23:33:00 imperialhome ppp: [wan_link1] MESG: duplicate address detected
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: authorization failed
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: parameter negotiation failed
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: state change Opened –> Stopping
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: phase shift AUTHENTICATE –> TERMINATE
Sep 28 23:33:00 imperialhome ppp: [wan_link1] AUTH: Cleanup
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: SendTerminateReq #8
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: LayerDown
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: rec'd Terminate Request #109 (Stopping)
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: SendTerminateAck #9
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: rec'd Terminate Ack #8 (Stopping)
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: state change Stopping –> Stopped
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: phase shift TERMINATE –> ESTABLISH
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: LayerFinish
Sep 28 23:33:00 imperialhome ppp: [wan_link1] PPPoE: connection closed
Sep 28 23:33:00 imperialhome ppp: [wan_link1] device: DOWN event
Sep 28 23:33:00 imperialhome ppp: [wan_link1] Link: DOWN event
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: Down event
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: state change Stopped –> Starting
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: phase shift ESTABLISH –> DEAD
Sep 28 23:33:00 imperialhome ppp: [wan_link1] LCP: LayerStart
Sep 28 23:33:00 imperialhome ppp: [wan_link1] Link: reconnection attempt 2 in 1 seconds
Sep 28 23:33:00 imperialhome ppp: [wan_link1] device: CLOSE event -
Not really sure what you mean by putting custom conf somewhere. These things get overwritten.
-
If you copy a mpd_wan.conf file in to the /conf/ directory, PFSENSE will override the web-based generated version with that one. I just updated to the latest snapshot. No progress!! Anyone?
-
Why are you putting a custom mpd conf in place? On 2.2.5 the auto-generated config should be fine. It's fine on earlier versions too, though the bug doktornotor linked would break it if you saved interface changes.
-
It was easier (faster/convenient) to modify the .conf file to debug. Going through the interface also limits the arguments you can put into the file. Either way no success, it should work and it did work. The Mikrotik 750 works fine, but I hate it as there are limitations for what you can do with OpenVPN. Also not as pretty as my PFSENSE Box both on in the inside and out ;)
This is driving me nuts. If I had more of an understanding as to why it is failing, I can endorse the issue back to my ISP, but as the Mikrotik works, they are blaming PFSENSE. I have limited knowledge with MPD5 and its workings.
Jacob
-
BTW, there are other users with this problem using the latest iteration of PFSENSE for MLPPP. That is on my side of the fence up here in Western Canada.
-
If you look at when MPD5 is called to Bundle the links together the second line is rejected as there is an error stating that the second "link did not validate in bundle". The logs are not verbose enough to find out what part of the link did not validate? Authentication looks fine, MTU/MRRU/MRU doesn't show issues, could it be that the second connection is trying to bond too early as IP Address has not negociated with the First link yet? Anyways, after the IP Address negotiates and resolves every subsequent retry is denied with a Message of "MESG: duplicate address detected". Another administrator indicates that it is PFSENSE that is throwing the messages and rejects the link?
Anyone have any suggestions or know who's door I can knock on? My only other option is the run the Mikrotik to resolve the MLPPP VDSL connection and then setup a 1:1 NAT so that I can still use PFSENSE as the serverside router to my internal network. I've been told for the most part OpenVPN will not have a problem (I run a Server with four clients for a distributed LAN).
Jacob
-
While you are messing with configuration in shell in unknown ways, you really won't get any meaningful support here. As already noted, it will get overwritten plus is completely undesired.
-
While you are messing with configuration in shell in unknown ways, you really won't get any meaningful support here.
That was the point I was trying to get at earlier. Right now this is more a "please debug my custom mpd.conf", which isn't going to get much attention.
Go back to what the system generates on its own, and post your PPP logs. That I'm happy to help with.
-
I have a site connected via MLPPP running the latest snaps and things are fine. Nothing custom needed.
-
It was essentially a copy of the auto generated one. I will post the auto generated one, but same result in the logs. How can one get verbose logs utilizing the auto generated config, we will start there.
-
Alright here is the log from the PFSENSE 2.2.5 (Snapshot from updating yesterday), running on a standard MLPPP PPPOE setup with two VDSL 50/10 links (Zyxel Modems setup in Bridge). The modems are setup on two adapters EM1 and EM2 which are setup in the Interface Page under the Tab in a PPPs and assigned the identifier PPPOE0(em1, em2). WAN is assigned the PPPOE0 and all the necessary details are setup. (I can provide screenshots if needed). The error is the same. Does anyone have a clue as to what could be the issue?
Again it works with other MLPPP routers such as the Mikrotik 750, but I really really do not want to use it.
Sep 29 22:51:56 imperialhome ppp: Multi-link PPP daemon for FreeBSD
Sep 29 22:51:56 imperialhome ppp:
Sep 29 22:51:56 imperialhome ppp: process 57962 started, version 5.7 (root@pfSense_RELENG_2_2_amd64_amd64-pfSense_RELENG_2_2-job-01 00:07 9-Jun-2015)
Sep 29 22:51:56 imperialhome ppp: web: web is not running
Sep 29 22:51:56 imperialhome ppp: [wan] Bundle: Interface ng0 created
Sep 29 22:51:56 imperialhome ppp: [wan_link0] Link: OPEN event
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: Open event
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: state change Initial –> Starting
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: LayerStart
Sep 29 22:51:56 imperialhome ppp: [wan_link1] Link: OPEN event
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: Open event
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: state change Initial –> Starting
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: LayerStart
Sep 29 22:51:56 imperialhome ppp: [wan_link0] PPPoE: Connecting to 'Teksavvy'
Sep 29 22:51:56 imperialhome ppp: [wan_link1] PPPoE: Connecting to 'Teksavvy'
Sep 29 22:51:56 imperialhome ppp: PPPoE: rec'd ACNAME "CLGRAB21AR04"
Sep 29 22:51:56 imperialhome ppp: PPPoE: rec'd ACNAME "CLGRAB21AR04"
Sep 29 22:51:56 imperialhome ppp: [wan_link0] PPPoE: connection successful
Sep 29 22:51:56 imperialhome ppp: [wan_link0] Link: UP event
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: Up event
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: state change Starting –> Req-Sent
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: SendConfigReq #1
Sep 29 22:51:56 imperialhome ppp: [wan_link0] PROTOCOMP
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MRU 1492
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MAGICNUM b54bbafa
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MP MRRU 1486
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MP SHORTSEQ
Sep 29 22:51:56 imperialhome ppp: [wan_link0] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 29 22:51:56 imperialhome ppp: [wan_link1] PPPoE: connection successful
Sep 29 22:51:56 imperialhome ppp: [wan_link1] Link: UP event
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: Up event
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: state change Starting –> Req-Sent
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: SendConfigReq #1
Sep 29 22:51:56 imperialhome ppp: [wan_link1] PROTOCOMP
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MRU 1492
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MAGICNUM b88404e2
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MP MRRU 1486
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MP SHORTSEQ
Sep 29 22:51:56 imperialhome ppp: [wan_link1] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: rec'd Configure Request #245 (Req-Sent)
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MRU 1460
Sep 29 22:51:56 imperialhome ppp: [wan_link0] AUTHPROTO CHAP MD5
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MAGICNUM 4c75d946
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: SendConfigAck #245
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MRU 1460
Sep 29 22:51:56 imperialhome ppp: [wan_link0] AUTHPROTO CHAP MD5
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MAGICNUM 4c75d946
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: state change Req-Sent –> Ack-Sent
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: rec'd Configure Reject #1 (Ack-Sent)
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MP MRRU 1486
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MP SHORTSEQ
Sep 29 22:51:56 imperialhome ppp: [wan_link0] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: SendConfigReq #2
Sep 29 22:51:56 imperialhome ppp: [wan_link0] PROTOCOMP
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MRU 1492
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MAGICNUM b54bbafa
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: rec'd Configure Request #105 (Req-Sent)
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MRU 1460
Sep 29 22:51:56 imperialhome ppp: [wan_link1] AUTHPROTO CHAP MD5
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MAGICNUM 34423367
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: SendConfigAck #105
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MRU 1460
Sep 29 22:51:56 imperialhome ppp: [wan_link1] AUTHPROTO CHAP MD5
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MAGICNUM 34423367
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: state change Req-Sent –> Ack-Sent
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: rec'd Configure Reject #1 (Ack-Sent)
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MP MRRU 1486
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MP SHORTSEQ
Sep 29 22:51:56 imperialhome ppp: [wan_link1] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: SendConfigReq #2
Sep 29 22:51:56 imperialhome ppp: [wan_link1] PROTOCOMP
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MRU 1492
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MAGICNUM b88404e2
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: rec'd Configure Ack #2 (Ack-Sent)
Sep 29 22:51:56 imperialhome ppp: [wan_link0] PROTOCOMP
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MRU 1492
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MAGICNUM b54bbafa
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: state change Ack-Sent –> Opened
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: LayerUp
Sep 29 22:51:56 imperialhome ppp: [wan_link0] CHAP: rec'd CHALLENGE #84 len: 37
Sep 29 22:51:56 imperialhome ppp: [wan_link0] Name: "CLGRAB21AR04"
Sep 29 22:51:56 imperialhome ppp: [wan_link0] CHAP: Using authname "xxxxxxxx@wiredhighspeed.com"
Sep 29 22:51:56 imperialhome ppp: [wan_link0] CHAP: sending RESPONSE #84 len: 48
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: rec'd Configure Ack #2 (Ack-Sent)
Sep 29 22:51:56 imperialhome ppp: [wan_link1] PROTOCOMP
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MRU 1492
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MAGICNUM b88404e2
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: state change Ack-Sent –> Opened
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: auth: peer wants CHAP, I want nothing
Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: LayerUp
Sep 29 22:51:56 imperialhome ppp: [wan_link1] CHAP: rec'd CHALLENGE #244 len: 40
Sep 29 22:51:56 imperialhome ppp: [wan_link1] Name: "CLGRAB21AR04"
Sep 29 22:51:56 imperialhome ppp: [wan_link1] CHAP: Using authname "xxxxxxxx@wiredhighspeed.com"
Sep 29 22:51:56 imperialhome ppp: [wan_link1] CHAP: sending RESPONSE #244 len: 48
Sep 29 22:51:57 imperialhome ppp: [wan_link0] LCP: rec'd Configure Request #230 (Opened)
Sep 29 22:51:57 imperialhome ppp: [wan_link0] MRU 1452
Sep 29 22:51:57 imperialhome ppp: [wan_link0] AUTHPROTO PAP
Sep 29 22:51:57 imperialhome ppp: [wan_link0] MAGICNUM 4e76549f
Sep 29 22:51:57 imperialhome ppp: [wan_link0] LCP: LayerDown
Sep 29 22:51:57 imperialhome ppp: [wan_link0] LCP: SendConfigReq #3
Sep 29 22:51:57 imperialhome ppp: [wan_link0] PROTOCOMP
Sep 29 22:51:57 imperialhome ppp: [wan_link0] MRU 1492
Sep 29 22:51:57 imperialhome ppp: [wan_link0] MAGICNUM b54bbafa
Sep 29 22:51:57 imperialhome ppp: [wan_link0] LCP: SendConfigAck #230
Sep 29 22:51:57 imperialhome ppp: [wan_link0] MRU 1452
Sep 29 22:51:57 imperialhome ppp: [wan_link0] AUTHPROTO PAP
Sep 29 22:51:57 imperialhome ppp: [wan_link0] MAGICNUM 4e76549f
Sep 29 22:51:57 imperialhome ppp: [wan_link0] LCP: state change Opened –> Ack-Sent
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: rec'd Configure Request #180 (Opened)
Sep 29 22:51:57 imperialhome ppp: [wan_link1] MRU 1452
Sep 29 22:51:57 imperialhome ppp: [wan_link1] AUTHPROTO PAP
Sep 29 22:51:57 imperialhome ppp: [wan_link1] MAGICNUM 2d1d6c80
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: LayerDown
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: SendConfigReq #3
Sep 29 22:51:57 imperialhome ppp: [wan_link1] PROTOCOMP
Sep 29 22:51:57 imperialhome ppp: [wan_link1] MRU 1492
Sep 29 22:51:57 imperialhome ppp: [wan_link1] MAGICNUM b88404e2
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: SendConfigAck #180
Sep 29 22:51:57 imperialhome ppp: [wan_link1] MRU 1452
Sep 29 22:51:57 imperialhome ppp: [wan_link1] AUTHPROTO PAP
Sep 29 22:51:57 imperialhome ppp: [wan_link1] MAGICNUM 2d1d6c80
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: state change Opened –> Ack-Sent
Sep 29 22:51:57 imperialhome ppp: [wan_link0] LCP: rec'd Configure Ack #3 (Ack-Sent)
Sep 29 22:51:57 imperialhome ppp: [wan_link0] PROTOCOMP
Sep 29 22:51:57 imperialhome ppp: [wan_link0] MRU 1492
Sep 29 22:51:57 imperialhome ppp: [wan_link0] MAGICNUM b54bbafa
Sep 29 22:51:57 imperialhome ppp: [wan_link0] LCP: state change Ack-Sent –> Opened
Sep 29 22:51:57 imperialhome ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing
Sep 29 22:51:57 imperialhome ppp: [wan_link0] PAP: using authname "xxxxxxxx@wiredhighspeed.com"
Sep 29 22:51:57 imperialhome ppp: [wan_link0] PAP: sending REQUEST #1 len: 43
Sep 29 22:51:57 imperialhome ppp: [wan_link0] LCP: LayerUp
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: rec'd Configure Ack #3 (Ack-Sent)
Sep 29 22:51:57 imperialhome ppp: [wan_link1] PROTOCOMP
Sep 29 22:51:57 imperialhome ppp: [wan_link1] MRU 1492
Sep 29 22:51:57 imperialhome ppp: [wan_link1] MAGICNUM b88404e2
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: state change Ack-Sent –> Opened
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: auth: peer wants PAP, I want nothing
Sep 29 22:51:57 imperialhome ppp: [wan_link1] PAP: using authname "xxxxxxxx@wiredhighspeed.com"
Sep 29 22:51:57 imperialhome ppp: [wan_link1] PAP: sending REQUEST #1 len: 43
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: LayerUp
Sep 29 22:51:57 imperialhome ppp: [wan_link0] PAP: rec'd ACK #1 len: 5
Sep 29 22:51:57 imperialhome ppp: [wan_link0] LCP: authorization successful
Sep 29 22:51:57 imperialhome ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Sep 29 22:51:57 imperialhome ppp: [wan_link0] Link: Join bundle "wan"
Sep 29 22:51:57 imperialhome ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Sep 29 22:51:57 imperialhome ppp: [wan] IPCP: Open event
Sep 29 22:51:57 imperialhome ppp: [wan] IPCP: state change Initial –> Starting
Sep 29 22:51:57 imperialhome ppp: [wan] IPCP: LayerStart
Sep 29 22:51:57 imperialhome ppp: [wan] IPV6CP: Open event
Sep 29 22:51:57 imperialhome ppp: [wan] IPV6CP: state change Initial –> Starting
Sep 29 22:51:57 imperialhome ppp: [wan] IPV6CP: LayerStart
Sep 29 22:51:57 imperialhome ppp: [wan] IPCP: Up event
Sep 29 22:51:57 imperialhome ppp: [wan] IPCP: state change Starting –> Req-Sent
Sep 29 22:51:57 imperialhome ppp: [wan] IPCP: SendConfigReq #1
Sep 29 22:51:57 imperialhome ppp: [wan] IPADDR 0.0.0.0
Sep 29 22:51:57 imperialhome ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Sep 29 22:51:57 imperialhome ppp: [wan] PRIDNS 0.0.0.0
Sep 29 22:51:57 imperialhome ppp: [wan] SECDNS 0.0.0.0
Sep 29 22:51:57 imperialhome ppp: [wan] IPV6CP: Up event
Sep 29 22:51:57 imperialhome ppp: [wan] IPV6CP: state change Starting –> Req-Sent
Sep 29 22:51:57 imperialhome ppp: [wan] IPV6CP: SendConfigReq #1
Sep 29 22:51:57 imperialhome ppp: [wan_link1] PAP: rec'd ACK #1 len: 5
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: authorization successful
Sep 29 22:51:57 imperialhome ppp: [wan_link1] Link: Matched action 'bundle "wan" ""'
Sep 29 22:51:57 imperialhome ppp: [wan_link1] Can't join bundle wan without multilink negotiated.
Sep 29 22:51:57 imperialhome ppp: [wan_link1] link did not validate in bundle
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: parameter negotiation failed
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: state change Opened –> Stopping
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: SendTerminateReq #4
Sep 29 22:51:57 imperialhome ppp: [wan_link1] LCP: LayerDown
Sep 29 22:51:58 imperialhome ppp: [wan_link1] LCP: rec'd Terminate Ack #4 (Stopping)
Sep 29 22:51:58 imperialhome ppp: [wan_link1] LCP: state change Stopping –> Stopped
Sep 29 22:51:58 imperialhome ppp: [wan_link1] LCP: LayerFinish
Sep 29 22:51:58 imperialhome ppp: [wan_link1] PPPoE: connection closed
Sep 29 22:51:58 imperialhome ppp: [wan_link1] Link: DOWN event
Sep 29 22:51:58 imperialhome ppp: [wan_link1] LCP: Down event
Sep 29 22:51:58 imperialhome ppp: [wan_link1] LCP: state change Stopped –> Starting
Sep 29 22:51:58 imperialhome ppp: [wan_link1] LCP: LayerStart
Sep 29 22:51:58 imperialhome ppp: [wan_link1] Link: reconnection attempt 1 in 1 seconds
Sep 29 22:51:58 imperialhome ppp: [wan] IPCP: rec'd Configure Request #166 (Req-Sent)
Sep 29 22:51:58 imperialhome ppp: [wan] IPADDR 76.10.191.6
Sep 29 22:51:58 imperialhome ppp: [wan] 76.10.191.6 is OK
Sep 29 22:51:58 imperialhome ppp: [wan] IPCP: SendConfigAck #166
Sep 29 22:51:58 imperialhome ppp: [wan] IPADDR 76.10.191.6
Sep 29 22:51:58 imperialhome ppp: [wan] IPCP: state change Req-Sent –> Ack-Sent
Sep 29 22:51:58 imperialhome ppp: [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Sep 29 22:51:58 imperialhome ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Sep 29 22:51:58 imperialhome ppp: [wan] IPCP: SendConfigReq #2
Sep 29 22:51:58 imperialhome ppp: [wan] IPADDR 0.0.0.0
Sep 29 22:51:58 imperialhome ppp: [wan] PRIDNS 0.0.0.0
Sep 29 22:51:58 imperialhome ppp: [wan] SECDNS 0.0.0.0
Sep 29 22:51:58 imperialhome ppp: [wan] IPV6CP: rec'd Configure Request #199 (Req-Sent)
Sep 29 22:51:58 imperialhome ppp: [wan] IPV6CP: SendConfigAck #199
Sep 29 22:51:58 imperialhome ppp: [wan] IPV6CP: state change Req-Sent –> Ack-Sent
Sep 29 22:51:58 imperialhome ppp: [wan] IPV6CP: rec'd Configure Ack #1 (Ack-Sent)
Sep 29 22:51:58 imperialhome ppp: [wan] IPV6CP: state change Ack-Sent –> Opened
Sep 29 22:51:58 imperialhome ppp: [wan] IPV6CP: LayerUp
Sep 29 22:51:58 imperialhome ppp: [wan] 0230:18ff:fea3:b13f -> 0090:1a00:0343:14ab
Sep 29 22:51:59 imperialhome ppp: [wan] IFACE: Up event
Sep 29 22:51:59 imperialhome ppp: [wan] IFACE: Rename interface ng0 to pppoe0
Sep 29 22:51:59 imperialhome ppp: [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Sep 29 22:51:59 imperialhome ppp: [wan] IPADDR 76.10.188.94
Sep 29 22:51:59 imperialhome ppp: [wan] 76.10.188.94 is OK
Sep 29 22:51:59 imperialhome ppp: [wan] PRIDNS 76.10.191.198
Sep 29 22:51:59 imperialhome ppp: [wan] SECDNS 76.10.191.199
Sep 29 22:51:59 imperialhome ppp: [wan] IPCP: SendConfigReq #3
Sep 29 22:51:59 imperialhome ppp: [wan] IPADDR 76.10.188.94
Sep 29 22:51:59 imperialhome ppp: [wan] PRIDNS 76.10.191.198
Sep 29 22:51:59 imperialhome ppp: [wan] SECDNS 76.10.191.199
Sep 29 22:51:59 imperialhome ppp: [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Sep 29 22:51:59 imperialhome ppp: [wan] IPADDR 76.10.188.94
Sep 29 22:51:59 imperialhome ppp: [wan] PRIDNS 76.10.191.198
Sep 29 22:51:59 imperialhome ppp: [wan] SECDNS 76.10.191.199
Sep 29 22:51:59 imperialhome ppp: [wan] IPCP: state change Ack-Sent –> Opened
Sep 29 22:51:59 imperialhome ppp: [wan] IPCP: LayerUp
Sep 29 22:51:59 imperialhome ppp: [wan] 76.10.188.94 -> 76.10.191.6
Sep 29 22:52:00 imperialhome ppp: [wan_link1] Link: reconnection attempt 1
Sep 29 22:52:00 imperialhome ppp: [wan_link1] PPPoE: Connecting to 'Teksavvy'
Sep 29 22:52:00 imperialhome ppp: PPPoE: rec'd ACNAME "CLGRAB21AR04"
Sep 29 22:52:01 imperialhome ppp: [wan_link1] PPPoE: connection successful
Sep 29 22:52:01 imperialhome ppp: [wan_link1] Link: UP event
Sep 29 22:52:01 imperialhome ppp: [wan_link1] LCP: Up event
Sep 29 22:52:01 imperialhome ppp: [wan_link1] LCP: state change Starting –> Req-Sent
Sep 29 22:52:01 imperialhome ppp: [wan_link1] LCP: SendConfigReq #5
Sep 29 22:52:01 imperialhome ppp: [wan_link1] PROTOCOMP
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MRU 1492
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MAGICNUM 8e771b44
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MP MRRU 1486
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MP SHORTSEQ
Sep 29 22:52:01 imperialhome ppp: [wan_link1] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 29 22:52:01 imperialhome ppp: [wan_link1] LCP: rec'd Configure Request #148 (Req-Sent)
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MRU 1460
Sep 29 22:52:01 imperialhome ppp: [wan_link1] AUTHPROTO CHAP MD5
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MAGICNUM 30f53071
Sep 29 22:52:01 imperialhome ppp: [wan_link1] LCP: SendConfigAck #148
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MRU 1460
Sep 29 22:52:01 imperialhome ppp: [wan_link1] AUTHPROTO CHAP MD5
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MAGICNUM 30f53071
Sep 29 22:52:01 imperialhome ppp: [wan_link1] LCP: state change Req-Sent –> Ack-Sent
Sep 29 22:52:01 imperialhome ppp: [wan_link1] LCP: rec'd Configure Reject #5 (Ack-Sent)
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MP MRRU 1486
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MP SHORTSEQ
Sep 29 22:52:01 imperialhome ppp: [wan_link1] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f
Sep 29 22:52:01 imperialhome ppp: [wan_link1] LCP: SendConfigReq #6
Sep 29 22:52:01 imperialhome ppp: [wan_link1] PROTOCOMP
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MRU 1492
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MAGICNUM 8e771b44
Sep 29 22:52:01 imperialhome ppp: [wan_link1] LCP: rec'd Configure Ack #6 (Ack-Sent)
Sep 29 22:52:01 imperialhome ppp: [wan_link1] PROTOCOMP
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MRU 1492
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MAGICNUM 8e771b44
Sep 29 22:52:01 imperialhome ppp: [wan_link1] LCP: state change Ack-Sent –> Opened
Sep 29 22:52:01 imperialhome ppp: [wan_link1] LCP: auth: peer wants CHAP, I want nothing
Sep 29 22:52:01 imperialhome ppp: [wan_link1] LCP: LayerUp
Sep 29 22:52:01 imperialhome ppp: [wan_link1] CHAP: rec'd CHALLENGE #114 len: 34
Sep 29 22:52:01 imperialhome ppp: [wan_link1] Name: "CLGRAB21AR04"
Sep 29 22:52:01 imperialhome ppp: [wan_link1] CHAP: Using authname "xxxxxxx@wiredhighspeed.com"
Sep 29 22:52:01 imperialhome ppp: [wan_link1] CHAP: sending RESPONSE #114 len: 48
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: rec'd Configure Request #228 (Opened)
Sep 29 22:52:02 imperialhome ppp: [wan_link1] MRU 1452
Sep 29 22:52:02 imperialhome ppp: [wan_link1] AUTHPROTO PAP
Sep 29 22:52:02 imperialhome ppp: [wan_link1] MAGICNUM 76e3c276
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: LayerDown
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: SendConfigReq #7
Sep 29 22:52:02 imperialhome ppp: [wan_link1] PROTOCOMP
Sep 29 22:52:02 imperialhome ppp: [wan_link1] MRU 1492
Sep 29 22:52:02 imperialhome ppp: [wan_link1] MAGICNUM 8e771b44
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: SendConfigAck #228
Sep 29 22:52:02 imperialhome ppp: [wan_link1] MRU 1452
Sep 29 22:52:02 imperialhome ppp: [wan_link1] AUTHPROTO PAP
Sep 29 22:52:02 imperialhome ppp: [wan_link1] MAGICNUM 76e3c276
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: state change Opened –> Ack-Sent
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: rec'd Configure Ack #7 (Ack-Sent)
Sep 29 22:52:02 imperialhome ppp: [wan_link1] PROTOCOMP
Sep 29 22:52:02 imperialhome ppp: [wan_link1] MRU 1492
Sep 29 22:52:02 imperialhome ppp: [wan_link1] MAGICNUM 8e771b44
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: state change Ack-Sent –> Opened
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: auth: peer wants PAP, I want nothing
Sep 29 22:52:02 imperialhome ppp: [wan_link1] PAP: using authname "xxxxxxxx@wiredhighspeed.com"
Sep 29 22:52:02 imperialhome ppp: [wan_link1] PAP: sending REQUEST #1 len: 43
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: LayerUp
Sep 29 22:52:02 imperialhome ppp: [wan_link1] PAP: rec'd NAK #1 len: 31
Sep 29 22:52:02 imperialhome ppp: [wan_link1] MESG: duplicate address detected
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: authorization failed
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: parameter negotiation failed
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: state change Opened –> Stopping
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: SendTerminateReq #8
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: LayerDown
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: rec'd Terminate Request #141 (Stopping)
Sep 29 22:52:02 imperialhome ppp: [wan_link1] LCP: SendTerminateAck #9
Sep 29 22:52:03 imperialhome ppp: [wan_link1] PPPoE: connection closed
Sep 29 22:52:03 imperialhome ppp: [wan_link1] Link: DOWN event
Sep 29 22:52:03 imperialhome ppp: [wan_link1] LCP: Down event
Sep 29 22:52:03 imperialhome ppp: [wan_link1] LCP: state change Stopping –> Starting
Sep 29 22:52:03 imperialhome ppp: [wan_link1] Link: reconnection attempt 2 in 4 seconds -
Had you run into this thread- https://forum.pfsense.org/index.php?topic=36966.0 ??
-
Yeah,
If you refer to one of the member "Clarknova" he's local here to me and he has had the same problem!! He posted to our ISP's forum and same thing they are blaming PFSENSE for having the issue.Jacob
-
Has anyone else seen this same? There is something definitely wrong with PFSense or MPD5. My MLPPP connection works with a Mikrotik 750, but is a no go with PFSENSE (both Stable and Current)
Jacob
-
My MLPPP connection works fine.
Olypen in Washington State.
-
<snip>Sep 29 22:51:56 imperialhome ppp: [wan_link0] LCP: rec'd Configure Reject #1 (Ack-Sent)
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MP MRRU 1486
Sep 29 22:51:56 imperialhome ppp: [wan_link0] MP SHORTSEQ
Sep 29 22:51:56 imperialhome ppp: [wan_link0] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f<snip more="">Sep 29 22:51:56 imperialhome ppp: [wan_link1] LCP: rec'd Configure Reject #1 (Ack-Sent)
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MP MRRU 1486
Sep 29 22:51:56 imperialhome ppp: [wan_link1] MP SHORTSEQ
Sep 29 22:51:56 imperialhome ppp: [wan_link1] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f<snip more="">Sep 29 22:52:01 imperialhome ppp: [wan_link1] LCP: rec'd Configure Reject #5 (Ack-Sent)
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MP MRRU 1486
Sep 29 22:52:01 imperialhome ppp: [wan_link1] MP SHORTSEQ
Sep 29 22:52:01 imperialhome ppp: [wan_link1] ENDPOINTDISC [802.1] 00 30 18 a3 b1 3f</snip></snip></snip>The remote end rejects the multilink parameters on both links, so multilink has not been negotiated and the two links cannot be joined.
In 'Advanced Options' check 'Disable shortseq'. Does it work now?
-
No unfortunately not at all. Same problem.
