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 +auth2

    create 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
    open

    create 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
    open

    Please 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

    |


  • Banned

    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


  • Banned

    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


  • Banned

    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.



  • @doktornotor:

    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.



  • @jacobhuh:

    <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.



  • As disabling shortseq hasn't solved the problem, I'd try a lower MRRU (you need to press the 'Advanced Options' button for that setting). The remote end is requesting an MRU of 1460, so I'd start by trying an MRRU of 1454 (which allows for the 6 byte multilink overhead). If that doesn't work, try an even lower MRRU - maybe try 1400.

    If you find a working MRRU, start increasing MRRU until it fails, then use the highest setting that works. At that point, re-enable shortseq and see if things continue to work.

    This is likely to involve finding working settings by trial and error unless you can get any hints from the ISP or from the logs of a system that successfully negotiates multilink with your ISP.


Log in to reply