Menu ▾ ▴

#64 Cisco MLPPP unable to connect when multilink is enabled

None
closed-fixed
Eugene Grosbein
None
5
2019-01-30
2018-10-01
Serge Cormier
No

When trying to connect to a multilink enabled Cisco LNS with MRRU set to 1524 in mpd.conf as required, the connection immediately disconnects after getting established. I am currently using the latest 5.8_7 mpd5 version from FreeBSD ports.

Log file is as follows (last 2 octects of ip addresses masked):

Oct 1 02:07:05 chestnut mpd5: [L1] PPPoE: Connecting to '*'
Oct 1 02:07:05 chestnut mpd5: PPPoE: rec'd ACNAME "OKVLON3010W"
Oct 1 02:07:05 chestnut mpd5: [L1] rec'd proto LCP while dead
Oct 1 02:07:05 chestnut mpd5: [L1] PPPoE: connection successful
Oct 1 02:07:05 chestnut mpd5: [L1] Link: UP event
Oct 1 02:07:05 chestnut mpd5: [L1] LCP: Up event
Oct 1 02:07:05 chestnut mpd5: [L1] LCP: state change Starting --> Req-Sent
Oct 1 02:07:05 chestnut mpd5: [L1] LCP: phase shift DEAD --> ESTABLISH
Oct 1 02:07:05 chestnut mpd5: [L1] LCP: SendConfigReq #1
Oct 1 02:07:05 chestnut mpd5: [L1] MRU 1492
Oct 1 02:07:05 chestnut mpd5: [L1] MAGICNUM 0x83e0b858
Oct 1 02:07:05 chestnut mpd5: [L1] MP MRRU 1524
Oct 1 02:07:05 chestnut mpd5: [L1] ENDPOINTDISC [802.1] 00 12 17 51 38 28
Oct 1 02:07:05 chestnut mpd5: [L1] LCP: rec'd Configure Reject #1 (Req-Sent)
Oct 1 02:07:05 chestnut mpd5: [L1] MP MRRU 1524
Oct 1 02:07:05 chestnut mpd5: [L1] ENDPOINTDISC [802.1] 00 12 17 51 38 28
Oct 1 02:07:05 chestnut mpd5: [L1] LCP: SendConfigReq #2
Oct 1 02:07:05 chestnut mpd5: [L1] MRU 1492
Oct 1 02:07:05 chestnut mpd5: [L1] MAGICNUM 0x83e0b858
Oct 1 02:07:05 chestnut mpd5: [L1] LCP: rec'd Configure Ack #2 (Req-Sent)
Oct 1 02:07:05 chestnut mpd5: [L1] MRU 1492
Oct 1 02:07:05 chestnut mpd5: [L1] MAGICNUM 0x83e0b858
Oct 1 02:07:05 chestnut mpd5: [L1] LCP: state change Req-Sent --> Ack-Rcvd
Oct 1 02:07:07 chestnut mpd5: [L1] LCP: state change Ack-Rcvd --> Req-Sent
Oct 1 02:07:07 chestnut mpd5: [L1] LCP: SendConfigReq #3
Oct 1 02:07:07 chestnut mpd5: [L1] MRU 1492
Oct 1 02:07:07 chestnut mpd5: [L1] MAGICNUM 0x83e0b858
Oct 1 02:07:07 chestnut mpd5: [L1] LCP: rec'd Configure Ack #3 (Req-Sent)
Oct 1 02:07:07 chestnut mpd5: [L1] MRU 1492
Oct 1 02:07:07 chestnut mpd5: [L1] MAGICNUM 0x83e0b858
Oct 1 02:07:07 chestnut mpd5: [L1] LCP: state change Req-Sent --> Ack-Rcvd
Oct 1 02:07:08 chestnut mpd5: [L1] LCP: rec'd Configure Request #29 (Ack-Rcvd)
Oct 1 02:07:08 chestnut mpd5: [L1] MRU 1492
Oct 1 02:07:08 chestnut mpd5: [L1] AUTHPROTO PAP
Oct 1 02:07:08 chestnut mpd5: [L1] MAGICNUM 0x41109ecf
Oct 1 02:07:08 chestnut mpd5: [L1] LCP: SendConfigAck #29
Oct 1 02:07:08 chestnut mpd5: [L1] MRU 1492
Oct 1 02:07:08 chestnut mpd5: [L1] AUTHPROTO PAP
Oct 1 02:07:08 chestnut mpd5: [L1] MAGICNUM 0x41109ecf
Oct 1 02:07:08 chestnut mpd5: [L1] LCP: state change Ack-Rcvd --> Opened
Oct 1 02:07:08 chestnut mpd5: [L1] LCP: phase shift ESTABLISH --> AUTHENTICATE
Oct 1 02:07:08 chestnut mpd5: [L1] LCP: auth: peer wants PAP, I want nothing
Oct 1 02:07:08 chestnut mpd5: [L1] PAP: using authname "nrg@ispdomain.com"
Oct 1 02:07:08 chestnut mpd5: [L1] PAP: sending REQUEST #1 len: 42
Oct 1 02:07:08 chestnut mpd5: [L1] LCP: LayerUp
Oct 1 02:07:08 chestnut mpd5: [L1] LCP: rec'd Configure Request #1 (Opened)
Oct 1 02:07:08 chestnut mpd5: [L1] AUTHPROTO PAP
Oct 1 02:07:08 chestnut mpd5: [L1] MAGICNUM 0x8ab29f74
Oct 1 02:07:08 chestnut mpd5: [L1] MP MRRU 1524
Oct 1 02:07:08 chestnut mpd5: [L1] ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 1 02:07:08 chestnut mpd5: [L1] LCP: LayerDown
Oct 1 02:07:08 chestnut mpd5: [L1] LCP: SendConfigReq #4
Oct 1 02:07:08 chestnut mpd5: [L1] MRU 1492
Oct 1 02:07:08 chestnut mpd5: [L1] MAGICNUM 0x83e0b858
Oct 1 02:07:08 chestnut mpd5: [L1] LCP: SendConfigAck #1
Oct 1 02:07:08 chestnut mpd5: [L1] AUTHPROTO PAP
Oct 1 02:07:08 chestnut mpd5: [L1] MAGICNUM 0x8ab29f74
Oct 1 02:07:08 chestnut mpd5: [L1] MP MRRU 1524
Oct 1 02:07:08 chestnut mpd5: [L1] ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 1 02:07:08 chestnut mpd5: [L1] LCP: state change Opened --> Ack-Sent
Oct 1 02:07:08 chestnut mpd5: [L1] LCP: phase shift AUTHENTICATE --> ESTABLISH
Oct 1 02:07:10 chestnut mpd5: [L1] LCP: SendConfigReq #5
Oct 1 02:07:10 chestnut mpd5: [L1] MRU 1492
Oct 1 02:07:10 chestnut mpd5: [L1] MAGICNUM 0x83e0b858
Oct 1 02:07:10 chestnut mpd5: [L1] LCP: rec'd Configure Request #2 (Ack-Sent)
Oct 1 02:07:10 chestnut mpd5: [L1] AUTHPROTO PAP
Oct 1 02:07:10 chestnut mpd5: [L1] MAGICNUM 0x8ab29f74
Oct 1 02:07:10 chestnut mpd5: [L1] MP MRRU 1524
Oct 1 02:07:10 chestnut mpd5: [L1] ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 1 02:07:10 chestnut mpd5: [L1] LCP: SendConfigAck #2
Oct 1 02:07:10 chestnut mpd5: [L1] AUTHPROTO PAP
Oct 1 02:07:10 chestnut mpd5: [L1] MAGICNUM 0x8ab29f74
Oct 1 02:07:10 chestnut mpd5: [L1] MP MRRU 1524
Oct 1 02:07:10 chestnut mpd5: [L1] ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 1 02:07:10 chestnut mpd5: [L1] LCP: rec'd Configure Ack #5 (Ack-Sent)
Oct 1 02:07:10 chestnut mpd5: [L1] MRU 1492
Oct 1 02:07:10 chestnut mpd5: [L1] MAGICNUM 0x83e0b858
Oct 1 02:07:10 chestnut mpd5: [L1] LCP: state change Ack-Sent --> Opened
Oct 1 02:07:10 chestnut mpd5: [L1] LCP: phase shift ESTABLISH --> AUTHENTICATE
Oct 1 02:07:10 chestnut mpd5: [L1] LCP: auth: peer wants PAP, I want nothing
Oct 1 02:07:10 chestnut mpd5: [L1] PAP: using authname "nrg@ispdomain.com"
Oct 1 02:07:10 chestnut mpd5: [L1] PAP: sending REQUEST #1 len: 42
Oct 1 02:07:10 chestnut mpd5: [L1] LCP: LayerUp
Oct 1 02:07:10 chestnut mpd5: [L1] PAP: rec'd ACK #1 len: 5
Oct 1 02:07:10 chestnut mpd5: [L1] LCP: authorization successful
Oct 1 02:07:10 chestnut mpd5: [L1] LCP: phase shift AUTHENTICATE --> NETWORK
Oct 1 02:07:10 chestnut mpd5: [L1] Link: Matched action 'bundle "B1" ""'
Oct 1 02:07:10 chestnut mpd5: [L1] Link: Join bundle "B1"
Oct 1 02:07:10 chestnut mpd5: [B1] IFACE: setting ng0 MTU to 1500 bytes
Oct 1 02:07:10 chestnut mpd5: [B1] Bundle: Status update: up 1 link, total bandwidth 10000000 bps
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: Open event
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: state change Initial --> Starting
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: LayerStart
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: Up event
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: state change Starting --> Req-Sent
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: SendConfigReq #1
Oct 1 02:07:10 chestnut mpd5: [B1] IPADDR 0.0.0.0
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: rec'd Configure Request #1 (Req-Sent)
Oct 1 02:07:10 chestnut mpd5: [B1] IPADDR 96.125.1.1
Oct 1 02:07:10 chestnut mpd5: [B1] 96.125.1.1 is OK
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: SendConfigAck #1
Oct 1 02:07:10 chestnut mpd5: [B1] IPADDR 96.125.1.1
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: state change Req-Sent --> Ack-Sent
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: rec'd Configure Nak #1 (Ack-Sent)
Oct 1 02:07:10 chestnut mpd5: [B1] IPADDR 45.2.2.1
Oct 1 02:07:10 chestnut mpd5: [B1] 45.2.2.1 is OK
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: SendConfigReq #2
Oct 1 02:07:10 chestnut mpd5: [B1] IPADDR 45.2.2.1
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: rec'd Configure Ack #2 (Ack-Sent)
Oct 1 02:07:10 chestnut mpd5: [B1] IPADDR 45.2.2.1
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: state change Ack-Sent --> Opened
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: LayerUp
Oct 1 02:07:10 chestnut mpd5: [B1] 45.2.2.1 -> 96.125.1.1
Oct 1 02:07:10 chestnut mpd5: [B1] IFACE: Connecting NAT
Oct 1 02:07:10 chestnut mpd5: [B1] IFACE: Add address 45.2.2.1/32->96.125.1.1 to ng0
Oct 1 02:07:10 chestnut mpd5: [B1] IFACE: Add route 0.0.0.0/0 96.125.1.1
Oct 1 02:07:10 chestnut mpd5: [B1] system: /usr/local/etc/mpd5/mpd.linkup ng0 inet 45.2.2.1/32 96.125.1.1 '-' '' '' '00:00:00:00:0$
Oct 1 02:07:10 chestnut mpd5: [B1] IFACE: Up event
Oct 1 02:07:10 chestnut mpd5: [B1] IFACE: Change interface ng0 flags: -0 +1
Oct 1 02:07:10 chestnut mpd5: [L1] PPPoE: connection closed
Oct 1 02:07:10 chestnut mpd5: [L1] Link: DOWN event
Oct 1 02:07:10 chestnut mpd5: [L1] LCP: Down event
Oct 1 02:07:10 chestnut mpd5: [L1] LCP: state change Opened --> Starting
Oct 1 02:07:10 chestnut mpd5: [L1] LCP: phase shift NETWORK --> DEAD
Oct 1 02:07:10 chestnut mpd5: [L1] Link: Leave bundle "B1"
Oct 1 02:07:10 chestnut mpd5: [B1] IFACE: setting ng0 MTU to 1500 bytes
Oct 1 02:07:10 chestnut mpd5: [B1] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: Close event
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: state change Opened --> Closing
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: SendTerminateReq #3
Oct 1 02:07:10 chestnut mpd5: [B1] IPCP: LayerDown
Oct 1 02:07:10 chestnut mpd5: [B1] IFACE: Delete route 0.0.0.0/0 96.125.1,1
Oct 1 02:07:10 chestnut mpd5: [B1] IFACE: Remove address 45.2.2,1/32->96.125.1.1 from ng0
Oct 1 02:07:11 chestnut mpd5: [B1] IFACE: Down event
Oct 1 02:07:11 chestnut mpd5: [B1] IFACE: Change interface ng0 flags: -4097 +0
Oct 1 02:07:11 chestnut mpd5: [B1] IPCP: Down event
Oct 1 02:07:11 chestnut mpd5: [B1] IPCP: LayerFinish
Oct 1 02:07:11 chestnut mpd5: [B1] Bundle: No NCPs left. Closing links...
Oct 1 02:07:11 chestnut mpd5: [B1] IPCP: state change Closing --> Initial
Oct 1 02:07:11 chestnut mpd5: [L1] LCP: LayerDown
Oct 1 02:07:11 chestnut mpd5: [L1] Link: reconnection attempt 1 in 18 seconds
Oct 1 02:07:13 chestnut mpd5: [L2] PPPoE connection timeout after 9 seconds
Oct 1 02:07:13 chestnut mpd5: [L2] Link: DOWN event
Oct 1 02:07:13 chestnut mpd5: [L2] LCP: Down event
Oct 1 02:07:13 chestnut mpd5: [L2] Link: reconnection attempt 7 in 15 seconds

Any ideas? By doing research online, other people are experiencing the same issue where MLPPP multilink is not working when using mpd5 if the ISP is using Cisco equipment.

Discussion

1 2 > >> (Page 1 of 2)

  • Serge Cormier

    Serge Cormier - 2018-10-01

    FYI, as soon as I change "set link enable multilink" to "set link disable multilink" in mpd.conf, I am able to connect without any issues but without MLPPP enabled obviously.

     

  • Eugene Grosbein

    Eugene Grosbein - 2018-10-01

    Please show your mpd.conf

     

  • Serge Cormier

    Serge Cormier - 2018-10-01

    As requested, my mpd.conf is as follows:

    startup:

#these lines are optional, they give you a web interface to check the status of the connection
#desired username and password to login
        set user admin passwd admin
        set web self 192.168.1.1 5006
        set web open

default:
        load dslinternet
        load pptp_server
        log +lcp +lcp2 +echo +auth +bund +bund2 +iface +iface2 +link +ecp

dslinternet:
        create bundle static B1
        set iface route default
        set ipcp ranges 0.0.0.0/0 0.0.0.0/0
        set ipcp disable req-pri-dns
        set ipcp disable req-sec-dns
        set ipcp disable vjcomp
        set bundle disable round-robin
        set bundle disable bw-manage
        set bundle disable ipv6cp
        set bundle links L1 L2
        set iface mtu 1500
        set iface enable nat
        set iface disable on-demand
        set iface disable tcpmssfix
        set iface up-script /usr/local/etc/mpd5/mpd.linkup

        create link static L1 pppoe
        set auth authname nrg@ispdomain.com
        set auth password isppasswd
        set link max-redial 0
        set link redial-delay 15
        set link keep-alive 15 60
        set pppoe iface dc0
        set link enable multilink
        set link disable shortseq
        set link disable incoming
        set link enable originate
        set link accept pap chap
        set link disable protocomp
        set link mrru 1524
        set link mru 1492
        set link mtu 1492
        set link bandwidth 53888000
        set link action bundle B1
        open

        create link static L2 pppoe
        set auth authname nrg@ispdomain.com
        set auth password isppasswd
        set link max-redial 0
        set link redial-delay 15
        set link keep-alive 15 60
        set pppoe iface dc1
        set link enable multilink
        set link disable shortseq
        set link disable incoming
        set link enable originate
        set link accept pap chap
        set link disable protocomp
        set link mrru 1524
        set link mru 1492
        set link mtu 1492
        set link bandwidth 53888000
        set link action bundle B1
        open

pptp_server:
        # We want to allow a client to connect to 1.2.3.4 from out on the Internet
        # via PPTP.  We will assign that client the address 192.168.1.50 and proxy-ARP
        # for that address, so the virtual PPP link will be numbered 192.168.1.1 local
        # and 192.168.1.50 remote.  From the client machine's perspective, it will
        # appear as if it is actually on the 192.168.1.0/24 network, even though in
        # reality it is somewhere far away out on the Internet.
        #
        # Our DNS server is at 192.168.1.3 and our NBNS (WINS server) is at 192.168.1.4.
        # If you don't have an NBNS server, leave that line out.
        #
        # Define dynamic IP address pool.
        set ippool add pool1 192.168.1.80 192.168.1.99
        # Create clonable bundle template named VPN
        create bundle template VPN
        # Iterface configuration
        # On demand is only useful when we want to make an outgoing
        # connection
        # set iface disable on-demand
        # set iface idle 0
        set iface enable proxy-arp
        set iface idle 1800
        # set iface mtu 1362
        set iface mtu 1318
        set iface enable tcpmssfix
        set ipcp yes vjcomp
        # Specify IP address pool for dynamic assigment.
        set ipcp ranges 192.168.1.1/32 ippool pool1
        set ipcp dns 192.168.1.6
        set ipcp nbns 192.168.1.7
        # The five lines below enable Microsoft Point-to-Point encryption
        # (MPPE) using the ng_mppc(8) netgraph node type.
        set bundle enable compression
        set bundle enable crypt-reqd
        set ccp yes mppc
        set mppc yes e40
        set mppc yes e128
        set mppc yes stateless

        # Create clonable link template named VPNLINK
        create link template VPNLINK pptp
        set link action bundle VPN
        set link no acfcomp protocomp
        set link enable chap
        # We can use use RADIUS authentication/accounting by including
        # another config section with label 'radius'.
        #       load radius
        set link keep-alive 30 300
        set link mtu 1318
        set link mru 1318
     

    Last edit: Serge Cormier 2018-10-01

  • Serge Cormier

    Serge Cormier - 2018-10-01

    And in case you need it, my mpd.linkup is as follows:

    1
2
3
    #!/bin/sh
# Allow to accept calls
set link enable incoming
     

    • Eugene Grosbein

      Eugene Grosbein - 2018-10-02

      I'm afraid you mis-pasted contents of mpd.linkup as it does not look like meaningless shell script. Please double-check this.

      Also please re-read documentation: http://mpd.sourceforge.net/doc5/mpd28.html#28 that states: "If up-script exit status is not 0, mpd will kill respective protocol."

      Make sure your mpd.linkup script is terminated with "exit 0" command or else you may get exactly same effect as the log show if last command of the script returns non-zero exit status that shell propagates to calling process (mpd5).

       

  • Serge Cormier

    Serge Cormier - 2018-10-02

    I tried adding "exit 0" at the end of my mpd.linkup script but the same issue still takes place. I also tried without having the script enabled and the same issue remains so this has nothing to do with mpd.linkup. The linkup script also has ifconfig alias commands to set my static IP's to ng0 which i did not paste here.

    As previously stated, as soon as i change "set link enable multilink" to "set link disable multilink", I am able to connect so this is an issue with mpd5 not being able to support multilink when connecting to Cisco equipment.

     

    Last edit: Serge Cormier 2018-10-02

    • Eugene Grosbein

      Eugene Grosbein - 2018-10-02

      What FreeBSD version do you use?
      Take a look to the output of "dmesg -a" shell command, does if show something unusual, some errors?

      And this it not full log of mpd5 from its start. Please clear it, restart mpd5 and post full log from the beginning until the first line saying "Link: reconnection attempt". Use commands like those:

      echo -n > /var/log/mpd.log; killall -1 syslogd; service mpd5 restart

      Use right log file name for "echo -n".

       

  • Serge Cormier

    Serge Cormier - 2018-10-09

    I'm using FreeBSD 10.3-RELEASE-p29 FreeBSD 10.3-RELEASE-p29 #0:
    Current mpd5 version is:
    mpd5 -v
    Version 5.8 (root@104i386-default-job-02 19:10 13-Sep-2018)

    Nothing unusual comes up with the output of dmesg -a. As this is a production server, I can only get a new log during the off peak 2:00am to 6:00am maintenance window so I will try to do it overnight tonight. However, i am 100% confident that there are NO errors or other messages of interest in the parts of the log that I snipped.

    If you do reasearch online, you will find other discussion forums such as
    https://forum.netgate.com/topic/33810/2-0rc-mlppp-doesn-t-work-with-my-provider/27
    where other people are experiencing the same issue where mpd5 refuses to connect with multilink enabled when connecting to Cisco equipment while other PPPoE dialers have no issues such as the one used in MikroTik equipment.

     

    • Eugene Grosbein

      Eugene Grosbein - 2018-10-09

      It would also great help if you post some PPP logs of your another router that successfully negotiates MLPPP with same provider. More detailed this log, easier to dig the root of the problem and fix it.

       

      • Eugene Grosbein

        Eugene Grosbein - 2018-10-09

        Will you be able to apply a patch, rebuild mpd5 binary/package to test? If not, I can build patched mpd5 for you but tell if you use 32 bit version or 64 bit.

         

        • Serge Cormier

          Serge Cormier - 2018-10-10

          Using packages for most software running on this production server right now but I can apply a patch and compile a test version from source if required. Alternatively, you may build a patched 32 bit test version of mpd5 which would enable me to try it out more quickly.

           

  • Eugene Grosbein

    Eugene Grosbein - 2018-10-10

    This patch should make mpd5 to re-negotiate MRRU option with PAC despite LNS has rejected it previously for a link.

    Update 11.10.2018: I've replaced the patch with more correct version, please update it if you downloaded old version before.

     

    Last edit: Eugene Grosbein 2018-10-10
    patch-lcp.c

  • Eugene Grosbein

    Eugene Grosbein - 2018-10-10

    I just realized I have only FreeBSD10/amd64 and FreeBSD 11/i386 (and amd64) but no FreeBSD 10/i386 to build the package. You'll have to use Ports and put the patch to /usr/ports/net/mpd5/files/patch-lcp.c then rebuilt the port.

     

  • Eugene Grosbein

    Eugene Grosbein - 2018-10-10

    And I still need logs mentioned above, in case the patch does not help.

     

  • Serge Cormier

    Serge Cormier - 2018-10-17

    mpd.log with latest patch (i.e. 11.10.2018 version) applied and port rebuilt:

    Oct 17 02:12:31 bigben mpd5: Multi-link PPP daemon for FreeBSD
Oct 17 02:12:31 bigben mpd5:
Oct 17 02:12:31 bigben mpd5: process 9124 started, version 5.8 (root@bigben.skychoice.ca 02:03 17-Oct-2018)
Oct 17 02:12:31 bigben mpd5: web: listening on 192.168.128.100 5006
Oct 17 02:12:31 bigben mpd5: [B1] Bundle: Interface ng0 created
Oct 17 02:12:31 bigben mpd5: [L1] Bandwidth truncated to 10000000 bit/s
Oct 17 02:12:31 bigben mpd5: [L2] Bandwidth truncated to 10000000 bit/s
Oct 17 02:12:31 bigben mpd5: bind: Can't assign requested address
Oct 17 02:12:31 bigben mpd5: PPTP: waiting for connection on 206.248.138.168 1723
Oct 17 02:12:31 bigben mpd5: [L1] Link: OPEN event
Oct 17 02:12:31 bigben mpd5: [L1] LCP: Open event
Oct 17 02:12:31 bigben mpd5: [L1] LCP: state change Initial --> Starting
Oct 17 02:12:31 bigben mpd5: [L1] LCP: LayerStart
Oct 17 02:12:31 bigben mpd5: [L2] Link: OPEN event
Oct 17 02:12:31 bigben mpd5: [L2] LCP: Open event
Oct 17 02:12:31 bigben mpd5: [L2] LCP: state change Initial --> Starting
Oct 17 02:12:31 bigben mpd5: [L2] LCP: LayerStart
Oct 17 02:12:31 bigben mpd5: [L1] PPPoE: Connecting to 'skychoice'
Oct 17 02:12:31 bigben mpd5: [L2] PPPoE: Connecting to 'skychoice'
Oct 17 02:12:40 bigben mpd5: [L1] PPPoE connection timeout after 9 seconds
Oct 17 02:12:40 bigben mpd5: [L1] Link: DOWN event
Oct 17 02:12:40 bigben mpd5: [L2] LCP: Down event
Oct 17 02:12:40 bigben mpd5: [L2] Link: reconnection attempt 1 in 11 seconds
Oct 17 02:12:41 bigben mpd5: bind: Can't assign requested address
Oct 17 02:12:51 bigben mpd5: [L2] Link: reconnection attempt 1
Oct 17 02:12:51 bigben mpd5: [L2] PPPoE: Connecting to 'skychoice'
Oct 17 02:12:53 bigben mpd5: [L1] Link: reconnection attempt 1
Oct 17 02:12:53 bigben mpd5: [L1] PPPoE: Connecting to 'skychoice'
Oct 17 02:13:00 bigben mpd5: [L2] PPPoE connection timeout after 9 seconds
Oct 17 02:13:00 bigben mpd5: [L2] Link: DOWN event
Oct 17 02:13:00 bigben mpd5: [L2] LCP: Down event
Oct 17 02:13:00 bigben mpd5: [L2] Link: reconnection attempt 2 in 12 seconds
Oct 17 02:13:01 bigben mpd5: bind: Can't assign requested address
Oct 17 02:13:01 bigben mpd5: bind: Can't assign requested address
Oct 17 02:13:02 bigben mpd5: [L1] PPPoE connection timeout after 9 seconds
Oct 17 02:13:02 bigben mpd5: [L1] Link: DOWN event
Oct 17 02:13:02 bigben mpd5: [L1] LCP: Down event
Oct 17 02:13:02 bigben mpd5: [L1] Link: reconnection attempt 2 in 11 seconds
Oct 17 02:13:11 bigben mpd5: bind: Can't assign requested address
Oct 17 02:13:12 bigben mpd5: [L2] Link: reconnection attempt 2
Oct 17 02:13:12 bigben mpd5: [L2] PPPoE: Connecting to 'skychoice'
Oct 17 02:13:13 bigben mpd5: [L1] Link: reconnection attempt 2
Oct 17 02:13:13 bigben mpd5: [L1] PPPoE: Connecting to 'skychoice'
Oct 17 02:13:21 bigben mpd5: bind: Can't assign requested address
Oct 17 02:13:21 bigben mpd5: [L2] PPPoE connection timeout after 9 seconds
Oct 17 02:13:21 bigben mpd5: [L2] Link: DOWN event
Oct 17 02:13:21 bigben mpd5: [L2] LCP: Down event
Oct 17 02:13:21 bigben mpd5: [L2] Link: reconnection attempt 3 in 12 seconds
Oct 17 02:13:22 bigben mpd5: [L1] PPPoE connection timeout after 9 seconds
Oct 17 02:13:22 bigben mpd5: [L1] Link: DOWN event
Oct 17 02:13:22 bigben mpd5: [L1] LCP: Down event
Oct 17 02:13:22 bigben mpd5: [L1] Link: reconnection attempt 3 in 12 seconds
Oct 17 02:13:31 bigben mpd5: bind: Can't assign requested address
Oct 17 02:13:33 bigben mpd5: [L2] Link: reconnection attempt 3
Oct 17 02:13:33 bigben mpd5: [L2] PPPoE: Connecting to 'skychoice'
Oct 17 02:13:34 bigben mpd5: [L1] Link: reconnection attempt 3
Oct 17 02:13:34 bigben mpd5: [L1] PPPoE: Connecting to 'skychoice'
Oct 17 02:13:41 bigben mpd5: bind: Can't assign requested address
Oct 17 02:13:42 bigben mpd5: [L2] PPPoE connection timeout after 9 seconds
Oct 17 02:13:42 bigben mpd5: [L2] Link: DOWN event
Oct 17 02:13:42 bigben mpd5: [L2] LCP: Down event
Oct 17 02:13:42 bigben mpd5: [L2] Link: reconnection attempt 4 in 10 seconds
Oct 17 02:13:43 bigben mpd5: [L1] PPPoE connection timeout after 9 seconds
Oct 17 02:13:43 bigben mpd5: [L1] Link: DOWN event
Oct 17 02:13:43 bigben mpd5: [L1] LCP: Down event
Oct 17 02:13:43 bigben mpd5: [L1] Link: reconnection attempt 4 in 12 seconds
Oct 17 02:13:51 bigben mpd5: bind: Can't assign requested address
Oct 17 02:13:52 bigben mpd5: [L2] Link: reconnection attempt 4
Oct 17 02:13:52 bigben mpd5: [L2] PPPoE: Connecting to 'skychoice'
Oct 17 02:13:55 bigben mpd5: [L1] Link: reconnection attempt 4
Oct 17 02:13:55 bigben mpd5: [L1] PPPoE: Connecting to 'skychoice'
Oct 17 02:14:01 bigben mpd5: bind: Can't assign requested address
Oct 17 02:14:01 bigben mpd5: [L2] PPPoE connection timeout after 9 seconds
Oct 17 02:14:01 bigben mpd5: [L2] Link: DOWN event
Oct 17 02:14:01 bigben mpd5: [L2] LCP: Down event
Oct 17 02:14:01 bigben mpd5: [L2] Link: reconnection attempt 5 in 10 seconds
Oct 17 02:14:04 bigben mpd5: [L1] PPPoE connection timeout after 9 seconds
Oct 17 02:14:04 bigben mpd5: [L1] Link: DOWN event
Oct 17 02:14:04 bigben mpd5: [L1] LCP: Down event
Oct 17 02:14:04 bigben mpd5: [L1] Link: reconnection attempt 5 in 10 seconds
Oct 17 02:14:11 bigben mpd5: bind: Can't assign requested address
Oct 17 02:14:11 bigben mpd5: [L2] Link: reconnection attempt 5
Oct 17 02:14:11 bigben mpd5: [L2] PPPoE: Connecting to 'skychoice'
Oct 17 02:14:14 bigben mpd5: [L1] Link: reconnection attempt 5
Oct 17 02:14:14 bigben mpd5: [L1] PPPoE: Connecting to 'skychoice'
Oct 17 02:14:14 bigben mpd5: PPPoE: rec'd ACNAME "MLTNON2526W"
Oct 17 02:14:14 bigben mpd5: [L1] PPPoE: connection successful
Oct 17 02:14:14 bigben mpd5: [L1] Link: UP event
Oct 17 02:14:14 bigben mpd5: [L1] LCP: Up event
Oct 17 02:14:14 bigben mpd5: [L1] LCP: state change Starting --> Req-Sent
Oct 17 02:14:14 bigben mpd5: [L1] LCP: phase shift DEAD --> ESTABLISH
Oct 17 02:14:14 bigben mpd5: [L1] LCP: SendConfigReq #1
Oct 17 02:14:14 bigben mpd5: [L1]   MRU 1492
Oct 17 02:14:14 bigben mpd5: [L1]   MAGICNUM 0x2e296b94
Oct 17 02:14:14 bigben mpd5: [L1]   MP MRRU 1524
Oct 17 02:14:14 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 17 02:14:14 bigben mpd5: [L1] LCP: rec'd Configure Request #36 (Req-Sent)
Oct 17 02:14:14 bigben mpd5: [L1]   MRU 1492
Oct 17 02:14:14 bigben mpd5: [L1]   AUTHPROTO PAP
Oct 17 02:14:14 bigben mpd5: [L1]   MAGICNUM 0x40eb353f
Oct 17 02:14:14 bigben mpd5: [L1] LCP: SendConfigAck #36
Oct 17 02:14:14 bigben mpd5: [L1]   MRU 1492
Oct 17 02:14:14 bigben mpd5: [L1]   AUTHPROTO PAP
Oct 17 02:14:14 bigben mpd5: [L1]   MAGICNUM 0x40eb353f
Oct 17 02:14:14 bigben mpd5: [L1] LCP: state change Req-Sent --> Ack-Sent
Oct 17 02:14:14 bigben mpd5: [L1] LCP: rec'd Configure Reject #1 (Ack-Sent)
Oct 17 02:14:14 bigben mpd5: [L1]   MP MRRU 1524
Oct 17 02:14:14 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 17 02:14:14 bigben mpd5: [L1] LCP: SendConfigReq #2
Oct 17 02:14:14 bigben mpd5: [L1]   MRU 1492
Oct 17 02:14:14 bigben mpd5: [L1]   MAGICNUM 0x2e296b94
Oct 17 02:14:14 bigben mpd5: [L1] LCP: rec'd Configure Ack #2 (Ack-Sent)
Oct 17 02:14:14 bigben mpd5: [L1]   MRU 1492
Oct 17 02:14:14 bigben mpd5: [L1]   MAGICNUM 0x2e296b94
Oct 17 02:14:14 bigben mpd5: [L1] LCP: state change Ack-Sent --> Opened
Oct 17 02:14:14 bigben mpd5: [L1] LCP: phase shift ESTABLISH --> AUTHENTICATE
Oct 17 02:14:14 bigben mpd5: [L1] LCP: auth: peer wants PAP, I want nothing
Oct 17 02:14:14 bigben mpd5: [L1] PAP: using authname "user@ispdomain.com"
Oct 17 02:14:14 bigben mpd5: [L1] PAP: sending REQUEST #1 len: 42
Oct 17 02:14:14 bigben mpd5: [L1] LCP: LayerUp
Oct 17 02:14:15 bigben mpd5: [L1] LCP: rec'd Configure Request #1 (Opened)
Oct 17 02:14:15 bigben mpd5: [L1]   AUTHPROTO PAP
Oct 17 02:14:15 bigben mpd5: [L1]   MAGICNUM 0xdd2c7ebf
Oct 17 02:14:15 bigben mpd5: [L1]   MP MRRU 1524
Oct 17 02:14:15 bigben mpd5: [L1]   ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 17 02:14:15 bigben mpd5: [L1] LCP: LayerDown
Oct 17 02:14:15 bigben mpd5: [L1] LCP: SendConfigReq #3
Oct 17 02:14:15 bigben mpd5: [L1]   MRU 1492
Oct 17 02:14:15 bigben mpd5: [L1]   MAGICNUM 0x2e296b94
Oct 17 02:14:15 bigben mpd5: [L1] LCP: SendConfigAck #1
Oct 17 02:14:15 bigben mpd5: [L1]   AUTHPROTO PAP
Oct 17 02:14:15 bigben mpd5: [L1]   MAGICNUM 0xdd2c7ebf
Oct 17 02:14:15 bigben mpd5: [L1]   MP MRRU 1524
Oct 17 02:14:15 bigben mpd5: [L1]   ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 17 02:14:15 bigben mpd5: [L1] LCP: state change Opened --> Ack-Sent
Oct 17 02:14:15 bigben mpd5: [L1] LCP: phase shift AUTHENTICATE --> ESTABLISH
Oct 17 02:14:15 bigben mpd5: [L1] LCP: rec'd Configure Ack #3 (Ack-Sent)
Oct 17 02:14:15 bigben mpd5: [L1]   MRU 1492
Oct 17 02:14:15 bigben mpd5: [L1]   MAGICNUM 0x2e296b94
Oct 17 02:14:15 bigben mpd5: [L1] LCP: state change Ack-Sent --> Opened
Oct 17 02:14:15 bigben mpd5: [L1] LCP: phase shift ESTABLISH --> AUTHENTICATE
Oct 17 02:14:15 bigben mpd5: [L1] LCP: auth: peer wants PAP, I want nothing
Oct 17 02:14:15 bigben mpd5: [L1] PAP: using authname "user@ispdomain.com"
Oct 17 02:14:15 bigben mpd5: [L1] PAP: sending REQUEST #1 len: 42
Oct 17 02:14:15 bigben mpd5: [L1] LCP: LayerUp
Oct 17 02:14:15 bigben mpd5: [L1] PAP: rec'd ACK #1 len: 5
Oct 17 02:14:15 bigben mpd5: [L1] LCP: authorization successful
Oct 17 02:14:15 bigben mpd5: [L1] LCP: phase shift AUTHENTICATE --> NETWORK
Oct 17 02:14:15 bigben mpd5: [L1] Link: Matched action 'bundle "B1" ""'
Oct 17 02:14:15 bigben mpd5: [L1] Link: Join bundle "B1"
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: setting ng0 MTU to 1500 bytes
Oct 17 02:14:15 bigben mpd5: [B1] Bundle: Status update: up 1 link, total bandwidth 10000000 bps
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: Open event
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: state change Initial --> Starting
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: LayerStart
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: Up event
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: state change Starting --> Req-Sent
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: SendConfigReq #1
Oct 17 02:14:15 bigben mpd5: [B1]   IPADDR 0.0.0.0
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: rec'd Configure Request #1 (Req-Sent)
Oct 17 02:14:15 bigben mpd5: [B1]   IPADDR 96.125.1.1
Oct 17 02:14:15 bigben mpd5: [B1]     96.125.1.1 is OK
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: SendConfigAck #1
Oct 17 02:14:15 bigben mpd5: [B1]   IPADDR 96.125.1.1
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: state change Req-Sent --> Ack-Sent
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: rec'd Configure Nak #1 (Ack-Sent)
Oct 17 02:14:15 bigben mpd5: [B1]   IPADDR 45.2.1.1
Oct 17 02:14:15 bigben mpd5: [B1]     45.2.1.1 is OK
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: SendConfigReq #2
Oct 17 02:14:15 bigben mpd5: [B1]   IPADDR 45.2.1.1
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: rec'd Configure Ack #2 (Ack-Sent)
Oct 17 02:14:15 bigben mpd5: [B1]   IPADDR 45.2.1.1
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: state change Ack-Sent --> Opened
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: LayerUp
Oct 17 02:14:15 bigben mpd5: [B1]   45.2.1.1 -> 96.125.1.1
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: Connecting NAT
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: Connecting tcpmssfix
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: Configuring ng_tcpmss mpd9124-B1-mss: 1460
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: Add address 45.2.1.1/32->96.125.1.1 to ng0
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: Add route 0.0.0.0/0 96.125.130.138
Oct 17 02:14:15 bigben mpd5: [B1] system: /usr/local/etc/mpd5/mpd.linkup ng0 inet 45.2.1.1/32 96.125.1.1 '-' '' '' '00:00:00:00:00:$
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: Up event
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: Change interface ng0 flags: -0 +1
Oct 17 02:14:15 bigben mpd5: [L1] PPPoE: connection closed
Oct 17 02:14:15 bigben mpd5: [L1] Link: DOWN event
Oct 17 02:14:15 bigben mpd5: [L1] LCP: Down event
Oct 17 02:14:15 bigben mpd5: [L1] LCP: state change Opened --> Starting
Oct 17 02:14:15 bigben mpd5: [L1] LCP: phase shift NETWORK --> DEAD
Oct 17 02:14:15 bigben mpd5: [L1] Link: Leave bundle "B1"
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: setting ng0 MTU to 1500 bytes
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: Configuring ng_tcpmss mpd9124-B1-mss: 1460
Oct 17 02:14:15 bigben mpd5: [B1] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: Close event
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: state change Opened --> Closing
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: SendTerminateReq #3
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: LayerDown
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: Delete route 0.0.0.0/0 96.125.130.138
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: Remove address 45.2.250.156/32->96.125.130.138 from ng0
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: Down event
Oct 17 02:14:15 bigben mpd5: [B1] IFACE: Change interface ng0 flags: -4097 +0
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: Down event
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: LayerFinish
Oct 17 02:14:15 bigben mpd5: [B1] Bundle: No NCPs left. Closing links...
Oct 17 02:14:15 bigben mpd5: [B1] IPCP: state change Closing --> Initial
Oct 17 02:14:15 bigben mpd5: [B1] Bundle: Last link has gone, reopening in 4 seconds
Oct 17 02:14:15 bigben mpd5: [L1] LCP: LayerDown
Oct 17 02:14:15 bigben mpd5: [L1] Link: reconnection attempt 1 in 13 seconds
Oct 17 02:14:19 bigben mpd5: [B1] Bundle: Last link has gone, reopening...
Oct 17 02:14:19 bigben mpd5: [B1] Bund: Link "L1" is busy
Oct 17 02:14:20 bigben mpd5: [L2] PPPoE connection timeout after 9 seconds
Oct 17 02:14:20 bigben mpd5: [L2] Link: DOWN event
Oct 17 02:14:20 bigben mpd5: [L2] LCP: Down event
Oct 17 02:14:20 bigben mpd5: [L2] Link: reconnection attempt 6 in 12 seconds
Oct 17 02:14:21 bigben mpd5: bind: Can't assign requested address
Oct 17 02:14:28 bigben mpd5: [L1] Link: reconnection attempt 1
Oct 17 02:14:28 bigben mpd5: [L1] PPPoE: Connecting to 'skychoice'
Oct 17 02:14:31 bigben mpd5: bind: Can't assign requested address
Oct 17 02:14:32 bigben mpd5: [L2] Link: reconnection attempt 6
Oct 17 02:14:32 bigben mpd5: [L2] PPPoE: Connecting to 'skychoice'
Oct 17 02:14:37 bigben mpd5: [L1] PPPoE connection timeout after 9 seconds
Oct 17 02:14:37 bigben mpd5: [L1] Link: DOWN event

    As you can see, there were no behaviour changes when using the latest version from a newly updated FreeBSD ports tree.

    Please feel free to send me additional patch revisions to test out.

    Thanks,

     

    Last edit: Serge Cormier 2018-10-17

    • Eugene Grosbein

      Eugene Grosbein - 2018-10-17

      Try this new patch instead of previous one and collect same logs and post it. If it does not work still, you'll need to apply both of patches and repeat again and post logs.

       
      patch-2-lcp.c

      • Serge Cormier

        Serge Cormier - 2018-10-18

        As requested, log after adding latest patch can be found below. Behavior is still the same where none of the 2 lines ever manage to get connected until I disable multilink in the mpd5 config.

        Oct 18 00:19:35 bigben mpd5: Multi-link PPP daemon for FreeBSD
Oct 18 00:19:35 bigben mpd5:
Oct 18 00:19:35 bigben mpd5: process 16448 started, version 5.8 (root@bigben.skychoice.ca 00:08 18-Oct-2018)
Oct 18 00:19:35 bigben mpd5: web: listening on 192.168.128.100 5006
Oct 18 00:19:35 bigben mpd5: [B1] Bundle: Interface ng0 created
Oct 18 00:19:35 bigben mpd5: [L1] Bandwidth truncated to 10000000 bit/s
Oct 18 00:19:35 bigben mpd5: [L2] Bandwidth truncated to 10000000 bit/s
Oct 18 00:19:35 bigben mpd5: bind: Can't assign requested address
Oct 18 00:19:35 bigben mpd5: PPTP: waiting for connection on 206.248.138.168 1723
Oct 18 00:19:35 bigben mpd5: [L1] Link: OPEN event
Oct 18 00:19:35 bigben mpd5: [L1] LCP: Open event
Oct 18 00:19:35 bigben mpd5: [L1] LCP: state change Initial --> Starting
Oct 18 00:19:35 bigben mpd5: [L1] LCP: LayerStart
Oct 18 00:19:35 bigben mpd5: [L2] Link: OPEN event
Oct 18 00:19:35 bigben mpd5: [L2] LCP: Open event
Oct 18 00:19:35 bigben mpd5: [L2] LCP: state change Initial --> Starting
Oct 18 00:19:35 bigben mpd5: [L2] LCP: LayerStart
Oct 18 00:19:35 bigben mpd5: [L1] PPPoE: Connecting to 'skychoice'
Oct 18 00:19:35 bigben mpd5: [L2] PPPoE: Connecting to 'skychoice'
Oct 18 00:19:35 bigben mpd5: PPPoE: rec'd ACNAME "MLTNON2526W"
Oct 18 00:19:35 bigben mpd5: [L1] rec'd proto LCP while dead
Oct 18 00:19:35 bigben mpd5: [L1] PPPoE: connection successful
Oct 18 00:19:35 bigben mpd5: [L1] Link: UP event
Oct 18 00:19:35 bigben mpd5: [L1] LCP: Up event
Oct 18 00:19:35 bigben mpd5: [L1] LCP: state change Starting --> Req-Sent
Oct 18 00:19:35 bigben mpd5: [L1] LCP: phase shift DEAD --> ESTABLISH
Oct 18 00:19:35 bigben mpd5: [L1] LCP: SendConfigReq #1
Oct 18 00:19:35 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:35 bigben mpd5: [L1]   MAGICNUM 0x845d7cc0
Oct 18 00:19:35 bigben mpd5: [L1]   MP MRRU 1524
Oct 18 00:19:35 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:35 bigben mpd5: PPPoE: rec'd ACNAME "MLTNON2526W"
Oct 18 00:19:35 bigben mpd5: [L1] LCP: rec'd Configure Reject #1 (Req-Sent)
Oct 18 00:19:35 bigben mpd5: [L1]   MP MRRU 1524
Oct 18 00:19:35 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:35 bigben mpd5: [L1] LCP: SendConfigReq #2
Oct 18 00:19:35 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:35 bigben mpd5: [L1]   MAGICNUM 0x845d7cc0
Oct 18 00:19:35 bigben mpd5: [L1] LCP: rec'd Configure Ack #2 (Req-Sent)
Oct 18 00:19:35 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:35 bigben mpd5: [L1]   MAGICNUM 0x845d7cc0
Oct 18 00:19:35 bigben mpd5: [L1] LCP: state change Req-Sent --> Ack-Rcvd
Oct 18 00:19:35 bigben mpd5: [L2] PPPoE: connection successful
Oct 18 00:19:35 bigben mpd5: [L2] Link: UP event
Oct 18 00:19:35 bigben mpd5: [L2] LCP: Up event
Oct 18 00:19:35 bigben mpd5: [L2] LCP: state change Starting --> Req-Sent
Oct 18 00:19:35 bigben mpd5: [L2] LCP: phase shift DEAD --> ESTABLISH
Oct 18 00:19:35 bigben mpd5: [L2] LCP: SendConfigReq #1
Oct 18 00:19:35 bigben mpd5: [L2]   MRU 1492
Oct 18 00:19:35 bigben mpd5: [L2]   MAGICNUM 0x9c92da00
Oct 18 00:19:35 bigben mpd5: [L2]   MP MRRU 1524
Oct 18 00:19:35 bigben mpd5: [L2]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:35 bigben mpd5: [L2] LCP: rec'd Configure Request #24 (Req-Sent)
Oct 18 00:19:35 bigben mpd5: [L2]   MRU 1492
Oct 18 00:19:35 bigben mpd5: [L2]   AUTHPROTO PAP
Oct 18 00:19:35 bigben mpd5: [L2]   MAGICNUM 0x65516060
Oct 18 00:19:35 bigben mpd5: [L2] LCP: SendConfigAck #24
Oct 18 00:19:35 bigben mpd5: [L2]   MRU 1492
Oct 18 00:19:35 bigben mpd5: [L2]   AUTHPROTO PAP
Oct 18 00:19:35 bigben mpd5: [L2]   MAGICNUM 0x65516060
Oct 18 00:19:35 bigben mpd5: [L2] LCP: state change Req-Sent --> Ack-Sent
Oct 18 00:19:35 bigben mpd5: [L2] LCP: rec'd Configure Reject #1 (Ack-Sent)
Oct 18 00:19:35 bigben mpd5: [L2]   MP MRRU 1524
Oct 18 00:19:35 bigben mpd5: [L2]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:35 bigben mpd5: [L2] LCP: SendConfigReq #2
Oct 18 00:19:35 bigben mpd5: [L2]   MRU 1492
Oct 18 00:19:35 bigben mpd5: [L2]   MAGICNUM 0x9c92da00
Oct 18 00:19:36 bigben mpd5: [L2] LCP: rec'd Configure Ack #2 (Ack-Sent)
Oct 18 00:19:36 bigben mpd5: [L2]   MRU 1492
Oct 18 00:19:36 bigben mpd5: [L2]   MAGICNUM 0x9c92da00
Oct 18 00:19:36 bigben mpd5: [L2] LCP: state change Ack-Sent --> Opened
Oct 18 00:19:36 bigben mpd5: [L2] LCP: phase shift ESTABLISH --> AUTHENTICATE
Oct 18 00:19:36 bigben mpd5: [L2] LCP: auth: peer wants PAP, I want nothing
Oct 18 00:19:36 bigben mpd5: [L2] PAP: using authname "user@ispdomain.com"
Oct 18 00:19:36 bigben mpd5: [L2] PAP: sending REQUEST #1 len: 42
Oct 18 00:19:36 bigben mpd5: [L2] LCP: LayerUp
Oct 18 00:19:36 bigben mpd5: [L2] LCP: rec'd Configure Request #1 (Opened)
Oct 18 00:19:36 bigben mpd5: [L2]   AUTHPROTO PAP
Oct 18 00:19:36 bigben mpd5: [L2]   MAGICNUM 0xe1eaaa47
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 1524
Oct 18 00:19:36 bigben mpd5: [L2]   ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 18 00:19:36 bigben mpd5: [L2] LCP: LayerDown
Oct 18 00:19:36 bigben mpd5: [L2] LCP: SendConfigReq #3
Oct 18 00:19:36 bigben mpd5: [L2]   MRU 1492
Oct 18 00:19:36 bigben mpd5: [L2]   MAGICNUM 0x9c92da00
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 0
Oct 18 00:19:36 bigben mpd5: [L2]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:36 bigben mpd5: [L2] LCP: SendConfigAck #1
Oct 18 00:19:36 bigben mpd5: [L2]   AUTHPROTO PAP
Oct 18 00:19:36 bigben mpd5: [L2]   MAGICNUM 0xe1eaaa47
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 1524
Oct 18 00:19:36 bigben mpd5: [L2]   ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 18 00:19:36 bigben mpd5: [L2] LCP: state change Opened --> Ack-Sent
Oct 18 00:19:36 bigben mpd5: [L2] LCP: phase shift AUTHENTICATE --> ESTABLISH
Oct 18 00:19:36 bigben mpd5: [L2] LCP: rec'd Configure Nak #3 (Ack-Sent)
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 128
Oct 18 00:19:36 bigben mpd5: [L2] LCP: SendConfigReq #4
Oct 18 00:19:36 bigben mpd5: [L2]   MRU 1492
Oct 18 00:19:36 bigben mpd5: [L2]   MAGICNUM 0x9c92da00
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 0
Oct 18 00:19:36 bigben mpd5: [L2]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:36 bigben mpd5: [L2] LCP: rec'd Configure Nak #4 (Ack-Sent)
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 128
Oct 18 00:19:36 bigben mpd5: [L2] LCP: SendConfigReq #5
Oct 18 00:19:36 bigben mpd5: [L2]   MRU 1492
Oct 18 00:19:36 bigben mpd5: [L2]   MAGICNUM 0x9c92da00
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 0
Oct 18 00:19:36 bigben mpd5: [L2]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:36 bigben mpd5: [L2] LCP: rec'd Configure Nak #5 (Ack-Sent)
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 128
Oct 18 00:19:36 bigben mpd5: [L2] LCP: SendConfigReq #6
Oct 18 00:19:36 bigben mpd5: [L2]   MRU 1492
Oct 18 00:19:36 bigben mpd5: [L2]   MAGICNUM 0x9c92da00
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 0
Oct 18 00:19:36 bigben mpd5: [L2]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:36 bigben mpd5: [L2] LCP: rec'd Configure Nak #6 (Ack-Sent)
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 128
Oct 18 00:19:36 bigben mpd5: [L2] LCP: SendConfigReq #7
Oct 18 00:19:36 bigben mpd5: [L2]   MRU 1492
Oct 18 00:19:36 bigben mpd5: [L2]   MAGICNUM 0x9c92da00
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 0
Oct 18 00:19:36 bigben mpd5: [L2]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:36 bigben mpd5: [L2] LCP: rec'd Configure Nak #7 (Ack-Sent)
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 128
Oct 18 00:19:36 bigben mpd5: [L2] LCP: SendConfigReq #8
Oct 18 00:19:36 bigben mpd5: [L2]   MRU 1492
Oct 18 00:19:36 bigben mpd5: [L2]   MAGICNUM 0x9c92da00
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 0
Oct 18 00:19:36 bigben mpd5: [L2]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:36 bigben mpd5: [L2] LCP: rec'd Configure Reject #8 (Ack-Sent)
Oct 18 00:19:36 bigben mpd5: [L2]   MP MRRU 0
Oct 18 00:19:36 bigben mpd5: [L2] LCP: SendConfigReq #9
Oct 18 00:19:36 bigben mpd5: [L2]   MRU 1492
Oct 18 00:19:36 bigben mpd5: [L2]   MAGICNUM 0x9c92da00
Oct 18 00:19:36 bigben mpd5: [L2] LCP: rec'd Configure Ack #9 (Ack-Sent)
Oct 18 00:19:36 bigben mpd5: [L2]   MRU 1492
Oct 18 00:19:36 bigben mpd5: [L2]   MAGICNUM 0x9c92da00
Oct 18 00:19:36 bigben mpd5: [L2] LCP: state change Ack-Sent --> Opened
Oct 18 00:19:36 bigben mpd5: [L2] LCP: phase shift ESTABLISH --> AUTHENTICATE
Oct 18 00:19:36 bigben mpd5: [L2] LCP: auth: peer wants PAP, I want nothing
Oct 18 00:19:36 bigben mpd5: [L2] PAP: using authname "user@ispdomain.net"
Oct 18 00:19:36 bigben mpd5: [L2] PAP: sending REQUEST #1 len: 42
Oct 18 00:19:36 bigben mpd5: [L2] LCP: LayerUp
Oct 18 00:19:36 bigben mpd5: [L2] PAP: rec'd ACK #1 len: 5
Oct 18 00:19:36 bigben mpd5: [L2] LCP: authorization successful
Oct 18 00:19:36 bigben mpd5: [L2] LCP: phase shift AUTHENTICATE --> NETWORK
Oct 18 00:19:36 bigben mpd5: [L2] Link: Matched action 'bundle "B1" ""'
Oct 18 00:19:36 bigben mpd5: [L2] Link: Join bundle "B1"
Oct 18 00:19:36 bigben mpd5: [B1] IFACE: setting ng0 MTU to 1500 bytes
Oct 18 00:19:36 bigben mpd5: [B1] Bundle: Status update: up 1 link, total bandwidth 10000000 bps
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: Open event
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: state change Initial --> Starting
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: LayerStart
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: Up event
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: state change Starting --> Req-Sent
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: SendConfigReq #1
Oct 18 00:19:36 bigben mpd5: [B1]   IPADDR 0.0.0.0
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: rec'd Configure Request #1 (Req-Sent)
Oct 18 00:19:36 bigben mpd5: [B1]   IPADDR 96.125.1.1
Oct 18 00:19:36 bigben mpd5: [B1]     96.125.1.1 is OK
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: SendConfigAck #1
Oct 18 00:19:36 bigben mpd5: [B1]   IPADDR 96.125.1.1
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: state change Req-Sent --> Ack-Sent
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: rec'd Configure Nak #1 (Ack-Sent)
Oct 18 00:19:36 bigben mpd5: [B1]   IPADDR 45.2.2.1
Oct 18 00:19:36 bigben mpd5: [B1]     45.2.2.1 is OK
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: SendConfigReq #2
Oct 18 00:19:36 bigben mpd5: [B1]   IPADDR 45.2.2.1
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: rec'd Configure Ack #2 (Ack-Sent)
Oct 18 00:19:36 bigben mpd5: [B1]   IPADDR 45.2.2.1
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: state change Ack-Sent --> Opened
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: LayerUp
Oct 18 00:19:36 bigben mpd5: [B1]   45.2.2.1 -> 96.125.1.1
Oct 18 00:19:36 bigben mpd5: [B1] IFACE: Add address 45.2.2.1/32->96.125.1.1 to ng0
Oct 18 00:19:36 bigben mpd5: [B1] IFACE: Add route 0.0.0.0/0 96.125.1.1
Oct 18 00:19:36 bigben mpd5: [B1] system: /usr/local/etc/mpd5/mpd.linkup ng0 inet 45.2.2.1/32 96.125.1.1 '-' '' '' '00:00:00:00:00:00' '-'
Oct 18 00:19:36 bigben mpd5: [B1] IFACE: Up event
Oct 18 00:19:36 bigben mpd5: [B1] IFACE: Change interface ng0 flags: -0 +1
Oct 18 00:19:36 bigben mpd5: [L2] PPPoE: connection closed
Oct 18 00:19:36 bigben mpd5: [L2] Link: DOWN event
Oct 18 00:19:36 bigben mpd5: [L2] LCP: Down event
Oct 18 00:19:36 bigben mpd5: [L2] LCP: state change Opened --> Starting
Oct 18 00:19:36 bigben mpd5: [L2] LCP: phase shift NETWORK --> DEAD
Oct 18 00:19:36 bigben mpd5: [L2] Link: Leave bundle "B1"
Oct 18 00:19:36 bigben mpd5: [B1] IFACE: setting ng0 MTU to 1500 bytes
Oct 18 00:19:36 bigben mpd5: [B1] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: Close event
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: state change Opened --> Closing
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: SendTerminateReq #3
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: LayerDown
Oct 18 00:19:36 bigben mpd5: [B1] IFACE: Delete route 0.0.0.0/0 96.125.1.1
Oct 18 00:19:36 bigben mpd5: [B1] IFACE: Remove address 45.2.2.1/32->96.125.1.1 from ng0
Oct 18 00:19:36 bigben mpd5: [B1] IFACE: Down event
Oct 18 00:19:36 bigben mpd5: [B1] IFACE: Change interface ng0 flags: -4097 +0
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: Down event
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: LayerFinish
Oct 18 00:19:36 bigben mpd5: [B1] Bundle: No NCPs left. Closing links...
Oct 18 00:19:36 bigben mpd5: [B1] IPCP: state change Closing --> Initial
Oct 18 00:19:36 bigben mpd5: [B1] Bundle: Last link has gone, reopening in 4 seconds
Oct 18 00:19:36 bigben mpd5: [L2] LCP: LayerDown

Oct 18 00:19:36 bigben mpd5: [L2] Link: reconnection attempt 1 in 11 seconds

Oct 18 00:19:37 bigben mpd5: [L1] LCP: state change Ack-Rcvd --> Req-Sent
Oct 18 00:19:37 bigben mpd5: [L1] LCP: SendConfigReq #3
Oct 18 00:19:37 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:37 bigben mpd5: [L1]   MAGICNUM 0x845d7cc0
Oct 18 00:19:37 bigben mpd5: [L1] LCP: rec'd Configure Ack #3 (Req-Sent)
Oct 18 00:19:37 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:37 bigben mpd5: [L1]   MAGICNUM 0x845d7cc0
Oct 18 00:19:37 bigben mpd5: [L1] LCP: state change Req-Sent --> Ack-Rcvd
Oct 18 00:19:38 bigben mpd5: [L1] LCP: rec'd Configure Request #32 (Ack-Rcvd)
Oct 18 00:19:38 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:38 bigben mpd5: [L1]   AUTHPROTO PAP
Oct 18 00:19:38 bigben mpd5: [L1]   MAGICNUM 0x3925ac82
Oct 18 00:19:38 bigben mpd5: [L1] LCP: SendConfigAck #32
Oct 18 00:19:38 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:38 bigben mpd5: [L1]   AUTHPROTO PAP
Oct 18 00:19:38 bigben mpd5: [L1]   MAGICNUM 0x3925ac82
Oct 18 00:19:38 bigben mpd5: [L1] LCP: state change Ack-Rcvd --> Opened
Oct 18 00:19:38 bigben mpd5: [L1] LCP: phase shift ESTABLISH --> AUTHENTICATE
Oct 18 00:19:38 bigben mpd5: [L1] LCP: auth: peer wants PAP, I want nothing
Oct 18 00:19:38 bigben mpd5: [L1] PAP: using authname "user@ispdomain.com"
Oct 18 00:19:38 bigben mpd5: [L1] PAP: sending REQUEST #1 len: 42
Oct 18 00:19:38 bigben mpd5: [L1] LCP: LayerUp
Oct 18 00:19:38 bigben mpd5: [L1] LCP: rec'd Configure Request #1 (Opened)
Oct 18 00:19:38 bigben mpd5: [L1]   AUTHPROTO PAP
Oct 18 00:19:38 bigben mpd5: [L1]   MAGICNUM 0x6a4c5ab0
Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 1524
Oct 18 00:19:38 bigben mpd5: [L1]   ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 18 00:19:38 bigben mpd5: [L1] LCP: LayerDown
Oct 18 00:19:38 bigben mpd5: [L1] LCP: SendConfigReq #4
Oct 18 00:19:38 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:38 bigben mpd5: [L1]   MAGICNUM 0x845d7cc0
Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 0
Oct 18 00:19:38 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:38 bigben mpd5: [L1] LCP: SendConfigAck #1
Oct 18 00:19:38 bigben mpd5: [L1]   AUTHPROTO PAP
Oct 18 00:19:38 bigben mpd5: [L1]   MAGICNUM 0x6a4c5ab0
Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 1524
Oct 18 00:19:38 bigben mpd5: [L1]   ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 18 00:19:38 bigben mpd5: [L1] LCP: state change Opened --> Ack-Sent
Oct 18 00:19:38 bigben mpd5: [L1] LCP: phase shift AUTHENTICATE --> ESTABLISH
Oct 18 00:19:38 bigben mpd5: [L1] LCP: rec'd Configure Nak #4 (Ack-Sent)
Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 128
Oct 18 00:19:38 bigben mpd5: [L1] LCP: SendConfigReq #5
Oct 18 00:19:38 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:38 bigben mpd5: [L1]   MAGICNUM 0x845d7cc0
Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 0
Oct 18 00:19:38 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:38 bigben mpd5: [L1] LCP: rec'd Configure Nak #5 (Ack-Sent)
Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 128
Oct 18 00:19:38 bigben mpd5: [L1] LCP: SendConfigReq #6
Oct 18 00:19:38 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:38 bigben mpd5: [L1]   MAGICNUM 0x845d7cc0
Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 0
Oct 18 00:19:38 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:38 bigben mpd5: [L1] LCP: rec'd Configure Nak #6 (Ack-Sent)

Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 128
Oct 18 00:19:38 bigben mpd5: [L1] LCP: SendConfigReq #7
Oct 18 00:19:38 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:38 bigben mpd5: [L1]   MAGICNUM 0x845d7cc0
Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 0
Oct 18 00:19:38 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:38 bigben mpd5: [L1] LCP: rec'd Configure Nak #7 (Ack-Sent)
Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 128
Oct 18 00:19:38 bigben mpd5: [L1] LCP: SendConfigReq #8
Oct 18 00:19:38 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:38 bigben mpd5: [L1]   MAGICNUM 0x845d7cc0
Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 0
Oct 18 00:19:38 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:38 bigben mpd5: [L1] LCP: rec'd Configure Nak #8 (Ack-Sent)
Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 128
Oct 18 00:19:38 bigben mpd5: [L1] LCP: SendConfigReq #9
Oct 18 00:19:38 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:38 bigben mpd5: [L1]   MAGICNUM 0x845d7cc0
Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 0
Oct 18 00:19:38 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 18 00:19:38 bigben mpd5: [L1] LCP: rec'd Configure Reject #9 (Ack-Sent)
Oct 18 00:19:38 bigben mpd5: [L1]   MP MRRU 0
Oct 18 00:19:38 bigben mpd5: [L1] LCP: SendConfigReq #10
Oct 18 00:19:38 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:38 bigben mpd5: [L1]   MAGICNUM 0x845d7cc0
Oct 18 00:19:38 bigben mpd5: [L1] LCP: rec'd Configure Ack #10 (Ack-Sent)
Oct 18 00:19:38 bigben mpd5: [L1]   MRU 1492
Oct 18 00:19:38 bigben mpd5: [L1]   MAGICNUM 0x845d7cc0
Oct 18 00:19:38 bigben mpd5: [L1] LCP: state change Ack-Sent --> Opened
Oct 18 00:19:38 bigben mpd5: [L1] LCP: phase shift ESTABLISH --> AUTHENTICATE
Oct 18 00:19:38 bigben mpd5: [L1] LCP: auth: peer wants PAP, I want nothing
Oct 18 00:19:38 bigben mpd5: [L1] PAP: using authname "info@ispdomain.com"
Oct 18 00:19:38 bigben mpd5: [L1] PAP: sending REQUEST #1 len: 42
Oct 18 00:19:38 bigben mpd5: [L1] LCP: LayerUp
Oct 18 00:19:38 bigben mpd5: [L1] PAP: rec'd ACK #1 len: 5
Oct 18 00:19:38 bigben mpd5: [L1] LCP: authorization successful
Oct 18 00:19:38 bigben mpd5: [L1] LCP: phase shift AUTHENTICATE --> NETWORK
Oct 18 00:19:38 bigben mpd5: [L1] Link: Join bundle "B1"
Oct 18 00:19:38 bigben mpd5: [B1] IFACE: setting ng0 MTU to 1500 bytes
Oct 18 00:19:38 bigben mpd5: [B1] Bundle: Status update: up 1 link, total bandwidth 10000000 bps
Oct 18 00:19:38 bigben mpd5: [B1] IPCP: Open event
Oct 18 00:19:38 bigben mpd5: [B1] IPCP: state change Initial --> Starting
Oct 18 00:19:38 bigben mpd5: [B1] IPCP: LayerStart
Oct 18 00:19:38 bigben mpd5: [B1] IPCP: Up event
Oct 18 00:19:38 bigben mpd5: [B1] IPCP: state change Starting --> Req-Sent
Oct 18 00:19:38 bigben mpd5: [B1] IPCP: SendConfigReq #4
Oct 18 00:19:38 bigben mpd5: [B1]   IPADDR 0.0.0.0
Oct 18 00:19:38 bigben mpd5: [B1] IPCP: rec'd Configure Request #1 (Req-Sent)
Oct 18 00:19:38 bigben mpd5: [B1]   IPADDR 96.125.1.2
Oct 18 00:19:38 bigben mpd5: [B1]     96.125.1.2 is OK
Oct 18 00:19:38 bigben mpd5: [B1] IPCP: SendConfigAck #1
Oct 18 00:19:38 bigben mpd5: [B1]   IPADDR 96.125.1.2
Oct 18 00:19:38 bigben mpd5: [B1] IPCP: state change Req-Sent --> Ack-Sent
Oct 18 00:19:38 bigben mpd5: [B1] IPCP: rec'd Configure Nak #4 (Ack-Sent)
Oct 18 00:19:38 bigben mpd5: [B1]   IPADDR 45.2.2.1
Oct 18 00:19:38 bigben mpd5: [B1]     45.2.2.1 is OK
Oct 18 00:19:38 bigben mpd5: [B1] IPCP: SendConfigReq #5
Oct 18 00:19:38 bigben mpd5: [B1]   IPADDR 45.2.2.1
Oct 18 00:19:38 bigben mpd5: [B1] IPCP: rec'd Configure Ack #5 (Ack-Sent)
Oct 18 00:19:39 bigben mpd5: [B1]   IPADDR 45.2.2.1
Oct 18 00:19:39 bigben mpd5: [B1] IPCP: state change Ack-Sent --> Opened
Oct 18 00:19:39 bigben mpd5: [B1] IPCP: LayerUp
Oct 18 00:19:39 bigben mpd5: [B1]   45.2.2.1 -> 96.125.1.2
Oct 18 00:19:39 bigben mpd5: [B1] IFACE: Add address 45.2.2.1/32->96.125.1.2 to ng0
Oct 18 00:19:39 bigben mpd5: [B1] IFACE: Add route 0.0.0.0/0 96.125.1.2
Oct 18 00:19:39 bigben mpd5: [B1] system: /usr/local/etc/mpd5/mpd.linkup ng0 inet 45.2.2.1/32 96.125.1.2 '-' '' '' '00:00:00:00:00:00' '-'
Oct 18 00:19:39 bigben mpd5: [B1] IFACE: Up event
Oct 18 00:19:39 bigben mpd5: [B1] IFACE: Change interface ng0 flags: -0 +1
Oct 18 00:19:39 bigben mpd5: [L1] PPPoE: connection closed
Oct 18 00:19:39 bigben mpd5: [L1] Link: DOWN event
Oct 18 00:19:39 bigben mpd5: [L1] LCP: Down event
Oct 18 00:19:39 bigben mpd5: [L1] LCP: state change Opened --> Starting
Oct 18 00:19:39 bigben mpd5: [L1] LCP: phase shift NETWORK --> DEAD
Oct 18 00:19:39 bigben mpd5: [L1] Link: Leave bundle "B1"
Oct 18 00:19:39 bigben mpd5: [B1] IFACE: setting ng0 MTU to 1500 bytes
Oct 18 00:19:39 bigben mpd5: [B1] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Oct 18 00:19:39 bigben mpd5: [B1] IPCP: Close event
Oct 18 00:19:39 bigben mpd5: [B1] IPCP: state change Opened --> Closing
Oct 18 00:19:39 bigben mpd5: [B1] IPCP: SendTerminateReq #6
Oct 18 00:19:39 bigben mpd5: [B1] IPCP: LayerDown
Oct 18 00:19:39 bigben mpd5: [B1] IFACE: Delete route 0.0.0.0/0 96.125.1.2
Oct 18 00:19:39 bigben mpd5: [B1] IFACE: Remove address 45.2.2.1/32->96.125.1.2 from ng0
Oct 18 00:19:39 bigben mpd5: [B1] IFACE: Down event
Oct 18 00:19:39 bigben mpd5: [B1] IFACE: Change interface ng0 flags: -4097 +0
Oct 18 00:19:39 bigben mpd5: [B1] IPCP: Down event
Oct 18 00:19:39 bigben mpd5: [B1] IPCP: LayerFinish
Oct 18 00:19:39 bigben mpd5: [B1] Bundle: No NCPs left. Closing links...
Oct 18 00:19:39 bigben mpd5: [B1] IPCP: state change Closing --> Initial
Oct 18 00:19:39 bigben mpd5: [B1] Bundle: Last link has gone, reopening in 4 seconds
Oct 18 00:19:39 bigben mpd5: [L1] LCP: LayerDown
Oct 18 00:19:39 bigben mpd5: [L1] Link: reconnection attempt 1 in 10 seconds
Oct 18 00:19:43 bigben mpd5: [B1] Bundle: Last link has gone, reopening...
Oct 18 00:19:43 bigben mpd5: [B1] Bund: Link "L1" is busy
Oct 18 00:19:45 bigben mpd5: bind: Can't assign requested address
Oct 18 00:19:47 bigben mpd5: [L2] Link: reconnection attempt 1
         

        • Eugene Grosbein

          Eugene Grosbein - 2018-10-18

          Please use this patch instead of patch-2-lcp.c and retry.

           

          Last edit: Eugene Grosbein 2018-10-18
          patch-3-lcp.c

    • Eugene Grosbein

      Eugene Grosbein - 2018-10-17

      I hope you will be able to show PPP logs for some another router that uses MLPPP just fine with same provider in case these patches won't work still.

       

      • Serge Cormier

        Serge Cormier - 2018-10-18

        I know it works fine when using other clients such as rp-pppoe (i.e. Roaring penguin). Will try to setup another router soon or temporarily use rp-pppoe on our FreeBSD server..

         

        • Eugene Grosbein

          Eugene Grosbein - 2018-10-18

          Please try latest patch first. There is good change it will work as your last log shows some good progress.

           

  • Eugene Grosbein

    Eugene Grosbein - 2018-10-18
    • status: open --> pending
    • assigned_to: Eugene Grosbein
    • Group: -->
     

  • Serge Cormier

    Serge Cormier - 2018-10-23

    Wow finally, it works and both lines get bonded! thanks :)

    Still have to do some fine tuning as for some reason, I only get 70% to 80% of the total upload or download bandwidth from both links combined. Current log is as follows:

    Oct 23 00:19:43 bigben mpd5: process 91388 started, version 5.8 (root@bigben.skychoice.ca 23:15 22-Oct-2018)
Oct 23 00:19:43 bigben mpd5: Label 'startup' not found
Oct 23 00:19:44 bigben mpd5: [B1] Bundle: Interface ng0 created
Oct 23 00:19:44 bigben mpd5: [L1] Bandwidth truncated to 10000000 bit/s
Oct 23 00:19:44 bigben mpd5: [L2] Bandwidth truncated to 10000000 bit/s
Oct 23 00:19:44 bigben mpd5: bind: Can't assign requested address
Oct 23 00:19:44 bigben mpd5: PPTP: waiting for connection on 45.2.2.1 1723
Oct 23 00:19:44 bigben mpd5: [L1] Link: OPEN event
Oct 23 00:19:44 bigben mpd5: [L1] LCP: Open event
Oct 23 00:19:44 bigben mpd5: [L1] LCP: state change Initial --> Starting
Oct 23 00:19:44 bigben mpd5: [L1] LCP: LayerStart
Oct 23 00:19:44 bigben mpd5: [L2] Link: OPEN event
Oct 23 00:19:44 bigben mpd5: [L2] LCP: Open event
Oct 23 00:19:44 bigben mpd5: [L2] LCP: state change Initial --> Starting
Oct 23 00:19:44 bigben mpd5: [L2] LCP: LayerStart
Oct 23 00:19:44 bigben mpd5: [L1] PPPoE: Connecting to 'ispname'
Oct 23 00:19:44 bigben mpd5: [L2] PPPoE: Connecting to 'ispname'
Oct 23 00:19:44 bigben mpd5: PPPoE: rec'd ACNAME "MLTNON2526W"
Oct 23 00:19:44 bigben mpd5: [L1] rec'd proto LCP while dead
Oct 23 00:19:44 bigben mpd5: [L1] PPPoE: connection successful
Oct 23 00:19:44 bigben mpd5: [L1] Link: UP event
Oct 23 00:19:44 bigben mpd5: [L1] LCP: Up event
Oct 23 00:19:44 bigben mpd5: [L1] LCP: state change Starting --> Req-Sent
Oct 23 00:19:44 bigben mpd5: [L1] LCP: phase shift DEAD --> ESTABLISH
Oct 23 00:19:44 bigben mpd5: [L1] LCP: SendConfigReq #1
Oct 23 00:19:44 bigben mpd5: [L1]   MRU 1492
Oct 23 00:19:44 bigben mpd5: [L1]   MAGICNUM 0xf03946cc
Oct 23 00:19:44 bigben mpd5: [L1]   MP MRRU 1524
Oct 23 00:19:44 bigben mpd5: [L1]   MP SHORTSEQ
Oct 23 00:19:44 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 23 00:19:44 bigben mpd5: [L2] rec'd proto LCP while dead
Oct 23 00:19:44 bigben mpd5: [L1] LCP: rec'd Configure Reject #1 (Req-Sent)
Oct 23 00:19:44 bigben mpd5: [L1]   MP MRRU 1524
Oct 23 00:19:44 bigben mpd5: [L1]   MP SHORTSEQ
Oct 23 00:19:44 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 23 00:19:44 bigben mpd5: [L1] LCP: SendConfigReq #2
Oct 23 00:19:44 bigben mpd5: [L1]   MRU 1492
Oct 23 00:19:44 bigben mpd5: [L1]   MAGICNUM 0xf03946cc
Oct 23 00:19:44 bigben mpd5: PPPoE: rec'd ACNAME "MLTNON2526W"
Oct 23 00:19:44 bigben mpd5: [L2] PPPoE: connection successful
Oct 23 00:19:44 bigben mpd5: [L2] Link: UP event
Oct 23 00:19:44 bigben mpd5: [L2] LCP: Up event
Oct 23 00:19:44 bigben mpd5: [L2] LCP: state change Starting --> Req-Sent
Oct 23 00:19:44 bigben mpd5: [L2] LCP: phase shift DEAD --> ESTABLISH
Oct 23 00:19:44 bigben mpd5: [L2] LCP: SendConfigReq #1
Oct 23 00:19:44 bigben mpd5: [L2]   MRU 1492
Oct 23 00:19:44 bigben mpd5: [L2]   MAGICNUM 0x9be5d100
Oct 23 00:19:44 bigben mpd5: [L2]   MP MRRU 1524
Oct 23 00:19:44 bigben mpd5: [L2]   MP SHORTSEQ
Oct 23 00:19:44 bigben mpd5: [L2]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 23 00:19:44 bigben mpd5: [L1] LCP: rec'd Configure Ack #2 (Req-Sent)
Oct 23 00:19:44 bigben mpd5: [L1]   MRU 1492
Oct 23 00:19:44 bigben mpd5: [L1]   MAGICNUM 0xf03946cc
Oct 23 00:19:44 bigben mpd5: [L1] LCP: state change Req-Sent --> Ack-Rcvd
Oct 23 00:19:44 bigben mpd5: [L2] LCP: rec'd Configure Reject #1 (Req-Sent)
Oct 23 00:19:44 bigben mpd5: [L2]   MP MRRU 1524
Oct 23 00:19:44 bigben mpd5: [L2]   MP SHORTSEQ
Oct 23 00:19:44 bigben mpd5: [L2]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 23 00:19:44 bigben mpd5: [L2] LCP: SendConfigReq #2
Oct 23 00:19:44 bigben mpd5: [L2]   MRU 1492
Oct 23 00:19:44 bigben mpd5: [L2]   MAGICNUM 0x9be5d100
Oct 23 00:19:44 bigben mpd5: [L2] LCP: rec'd Configure Ack #2 (Req-Sent)
Oct 23 00:19:44 bigben mpd5: [L2]   MRU 1492
Oct 23 00:19:44 bigben mpd5: [L2]   MAGICNUM 0x9be5d100
Oct 23 00:19:44 bigben mpd5: [L2] LCP: state change Req-Sent --> Ack-Rcvd
Oct 23 00:19:46 bigben mpd5: [L1] LCP: state change Ack-Rcvd --> Req-Sent
Oct 23 00:19:46 bigben mpd5: [L1] LCP: SendConfigReq #3
Oct 23 00:19:46 bigben mpd5: [L1]   MRU 1492
Oct 23 00:19:46 bigben mpd5: [L1]   MAGICNUM 0xf03946cc
Oct 23 00:19:46 bigben mpd5: [L1] LCP: rec'd Configure Ack #3 (Req-Sent)
Oct 23 00:19:46 bigben mpd5: [L1]   MRU 1492
Oct 23 00:19:46 bigben mpd5: [L1]   MAGICNUM 0xf03946cc
Oct 23 00:19:46 bigben mpd5: [L1] LCP: state change Req-Sent --> Ack-Rcvd
Oct 23 00:19:46 bigben mpd5: [L2] LCP: state change Ack-Rcvd --> Req-Sent
Oct 23 00:19:46 bigben mpd5: [L2] LCP: SendConfigReq #3
Oct 23 00:19:46 bigben mpd5: [L2]   MRU 1492
Oct 23 00:19:46 bigben mpd5: [L2]   MAGICNUM 0x9be5d100
Oct 23 00:19:46 bigben mpd5: [L2] LCP: rec'd Configure Ack #3 (Req-Sent)
Oct 23 00:19:46 bigben mpd5: [L2]   MRU 1492
Oct 23 00:19:46 bigben mpd5: [L2]   MAGICNUM 0x9be5d100
Oct 23 00:19:46 bigben mpd5: [L2] LCP: state change Req-Sent --> Ack-Rcvd
Oct 23 00:19:46 bigben mpd5: [L2] LCP: rec'd Configure Request #231 (Ack-Rcvd)
Oct 23 00:19:46 bigben mpd5: [L2]   MRU 1492
Oct 23 00:19:46 bigben mpd5: [L2]   AUTHPROTO PAP
Oct 23 00:19:46 bigben mpd5: [L2]   MAGICNUM 0x6c7033e8
Oct 23 00:19:46 bigben mpd5: [L2] LCP: SendConfigAck #231
Oct 23 00:19:46 bigben mpd5: [L2]   MRU 1492
Oct 23 00:19:46 bigben mpd5: [L2]   AUTHPROTO PAP
Oct 23 00:19:46 bigben mpd5: [L2]   MAGICNUM 0x6c7033e8
Oct 23 00:19:46 bigben mpd5: [L2] LCP: state change Ack-Rcvd --> Opened
Oct 23 00:19:46 bigben mpd5: [L2] LCP: phase shift ESTABLISH --> AUTHENTICATE
Oct 23 00:19:46 bigben mpd5: [L2] LCP: auth: peer wants PAP, I want nothing
Oct 23 00:19:46 bigben mpd5: [L2] PAP: using authname "user@ispdomain.net"
Oct 23 00:19:46 bigben mpd5: [L2] PAP: sending REQUEST #1 len: 42
Oct 23 00:19:46 bigben mpd5: [L2] LCP: LayerUp
Oct 23 00:19:47 bigben mpd5: [L1] LCP: rec'd Configure Request #179 (Ack-Rcvd)
Oct 23 00:19:47 bigben mpd5: [L1]   MRU 1492
Oct 23 00:19:47 bigben mpd5: [L1]   AUTHPROTO PAP
Oct 23 00:19:47 bigben mpd5: [L1]   MAGICNUM 0x282d8250
Oct 23 00:19:47 bigben mpd5: [L1] LCP: SendConfigAck #179
Oct 23 00:19:47 bigben mpd5: [L1]   MRU 1492
Oct 23 00:19:47 bigben mpd5: [L1]   AUTHPROTO PAP
Oct 23 00:19:47 bigben mpd5: [L1]   MAGICNUM 0x282d8250
Oct 23 00:19:47 bigben mpd5: [L1] LCP: state change Ack-Rcvd --> Opened
Oct 23 00:19:47 bigben mpd5: [L1] LCP: phase shift ESTABLISH --> AUTHENTICATE
Oct 23 00:19:47 bigben mpd5: [L1] LCP: auth: peer wants PAP, I want nothing
Oct 23 00:19:47 bigben mpd5: [L1] PAP: using authname "user@ispdomain.net"
Oct 23 00:19:47 bigben mpd5: [L1] PAP: sending REQUEST #1 len: 42
Oct 23 00:19:47 bigben mpd5: [L1] LCP: LayerUp
Oct 23 00:19:47 bigben mpd5: [L2] LCP: rec'd Configure Request #1 (Opened)
Oct 23 00:19:47 bigben mpd5: [L2]   AUTHPROTO PAP
Oct 23 00:19:47 bigben mpd5: [L2]   MAGICNUM 0x8410b574
Oct 23 00:19:47 bigben mpd5: [L2]   MP MRRU 1524
Oct 23 00:19:47 bigben mpd5: [L2]   ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 23 00:19:47 bigben mpd5: [L2] LCP: LayerDown
Oct 23 00:19:47 bigben mpd5: [L2] LCP: SendConfigReq #4
Oct 23 00:19:47 bigben mpd5: [L2]   MRU 1492
Oct 23 00:19:47 bigben mpd5: [L2]   MAGICNUM 0x9be5d100
Oct 23 00:19:47 bigben mpd5: [L2]   MP MRRU 1524
Oct 23 00:19:47 bigben mpd5: [L2]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 23 00:19:47 bigben mpd5: [L2] LCP: SendConfigAck #1
Oct 23 00:19:47 bigben mpd5: [L2]   AUTHPROTO PAP
Oct 23 00:19:47 bigben mpd5: [L2]   MAGICNUM 0x8410b574
Oct 23 00:19:47 bigben mpd5: [L2]   MP MRRU 1524
Oct 23 00:19:47 bigben mpd5: [L2]   ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 23 00:19:47 bigben mpd5: [L2] LCP: state change Opened --> Ack-Sent
Oct 23 00:19:47 bigben mpd5: [L2] LCP: phase shift AUTHENTICATE --> ESTABLISH
Oct 23 00:19:47 bigben mpd5: [L2] LCP: rec'd Configure Ack #4 (Ack-Sent)
Oct 23 00:19:47 bigben mpd5: [L2]   MRU 1492
Oct 23 00:19:47 bigben mpd5: [L2]   MAGICNUM 0x9be5d100
Oct 23 00:19:47 bigben mpd5: [L2]   MP MRRU 1524
Oct 23 00:19:47 bigben mpd5: [L2]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 23 00:19:47 bigben mpd5: [L2] LCP: state change Ack-Sent --> Opened
Oct 23 00:19:47 bigben mpd5: [L2] LCP: phase shift ESTABLISH --> AUTHENTICATE
Oct 23 00:19:47 bigben mpd5: [L2] LCP: auth: peer wants PAP, I want nothing
Oct 23 00:19:47 bigben mpd5: [L2] PAP: using authname "user@ispdomain.net"
Oct 23 00:19:47 bigben mpd5: [L2] PAP: sending REQUEST #1 len: 42
Oct 23 00:19:47 bigben mpd5: [L2] LCP: LayerUp
Oct 23 00:19:47 bigben mpd5: [L1] LCP: rec'd Configure Request #1 (Opened)
Oct 23 00:19:47 bigben mpd5: [L1]   AUTHPROTO PAP
Oct 23 00:19:47 bigben mpd5: [L1]   MAGICNUM 0x8410b5be
Oct 23 00:19:47 bigben mpd5: [L1]   MP MRRU 1524
Oct 23 00:19:47 bigben mpd5: [L1]   ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 23 00:19:47 bigben mpd5: [L1] LCP: LayerDown
Oct 23 00:19:47 bigben mpd5: [L1] LCP: SendConfigReq #4
Oct 23 00:19:47 bigben mpd5: [L1]   MRU 1492
Oct 23 00:19:47 bigben mpd5: [L1]   MAGICNUM 0xf03946cc
Oct 23 00:19:47 bigben mpd5: [L1]   MP MRRU 1524
Oct 23 00:19:47 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 23 00:19:47 bigben mpd5: [L1] LCP: SendConfigAck #1
Oct 23 00:19:47 bigben mpd5: [L1]   AUTHPROTO PAP
Oct 23 00:19:47 bigben mpd5: [L1]   MAGICNUM 0x8410b5be
Oct 23 00:19:47 bigben mpd5: [L1]   MP MRRU 1524
Oct 23 00:19:47 bigben mpd5: [L1]   ENDPOINTDISC [LOCAL] 73 74 61 63 6b 74 68 65 6c 6e 73
Oct 23 00:19:47 bigben mpd5: [L1] LCP: state change Opened --> Ack-Sent
Oct 23 00:19:47 bigben mpd5: [L1] LCP: phase shift AUTHENTICATE --> ESTABLISH
Oct 23 00:19:47 bigben mpd5: [L1] LCP: rec'd Configure Ack #4 (Ack-Sent)
Oct 23 00:19:47 bigben mpd5: [L1]   MRU 1492
Oct 23 00:19:47 bigben mpd5: [L1]   MAGICNUM 0xf03946cc
Oct 23 00:19:47 bigben mpd5: [L1]   MP MRRU 1524
Oct 23 00:19:47 bigben mpd5: [L1]   ENDPOINTDISC [802.1] 00 0e 0c 07 5a 74
Oct 23 00:19:47 bigben mpd5: [L1] LCP: state change Ack-Sent --> Opened
Oct 23 00:19:47 bigben mpd5: [L1] LCP: phase shift ESTABLISH --> AUTHENTICATE
Oct 23 00:19:47 bigben mpd5: [L1] LCP: auth: peer wants PAP, I want nothing
Oct 23 00:19:47 bigben mpd5: [L1] PAP: using authname "user@ispdomain.net"
Oct 23 00:19:47 bigben mpd5: [L1] PAP: sending REQUEST #1 len: 42
Oct 23 00:19:47 bigben mpd5: [L1] LCP: LayerUp
Oct 23 00:19:47 bigben mpd5: [L1] PAP: rec'd ACK #1 len: 5
Oct 23 00:19:47 bigben mpd5: [L1] LCP: authorization successful
Oct 23 00:19:47 bigben mpd5: [L1] LCP: phase shift AUTHENTICATE --> NETWORK
Oct 23 00:19:47 bigben mpd5: [L1] Link: Matched action 'bundle "B1" ""'
Oct 23 00:19:47 bigben mpd5: [L1] Link: Join bundle "B1"
Oct 23 00:19:47 bigben mpd5: [B1] IFACE: setting ng0 MTU to 1500 bytes
Oct 23 00:19:47 bigben mpd5: [B1] Bundle: Status update: up 1 link, total bandwidth 10000000 bps
Oct 23 00:19:47 bigben mpd5: [B1] IPCP: Open event
Oct 23 00:19:47 bigben mpd5: [B1] IPCP: state change Initial --> Starting
Oct 23 00:19:47 bigben mpd5: [B1] IPCP: LayerStart
Oct 23 00:19:47 bigben mpd5: [B1] IPCP: Up event
Oct 23 00:19:47 bigben mpd5: [B1] IPCP: state change Starting --> Req-Sent
Oct 23 00:19:47 bigben mpd5: [B1] IPCP: SendConfigReq #1
Oct 23 00:19:47 bigben mpd5: [B1]   IPADDR 0.0.0.0
Oct 23 00:19:47 bigben mpd5: [L2] PAP: rec'd ACK #1 len: 5
Oct 23 00:19:47 bigben mpd5: [L2] LCP: authorization successful
Oct 23 00:19:47 bigben mpd5: [L2] LCP: phase shift AUTHENTICATE --> NETWORK
Oct 23 00:19:47 bigben mpd5: [L2] Link: Join bundle "B1"
Oct 23 00:19:47 bigben mpd5: [B1] IFACE: setting ng0 MTU to 1500 bytes
Oct 23 00:19:47 bigben mpd5: [B1] Bundle: Status update: up 2 links, total bandwidth 20000000 bps
Oct 23 00:19:49 bigben mpd5: [B1] IPCP: SendConfigReq #2
Oct 23 00:19:49 bigben mpd5: [B1]   IPADDR 0.0.0.0
Oct 23 00:19:49 bigben mpd5: [B1] IPCP: rec'd Configure Request #2 (Req-Sent)
Oct 23 00:19:49 bigben mpd5: [B1]   IPADDR 96.125.1.1
Oct 23 00:19:49 bigben mpd5: [B1]     96.125.1.1 is OK
Oct 23 00:19:49 bigben mpd5: [B1] IPCP: SendConfigAck #2
Oct 23 00:19:49 bigben mpd5: [B1]   IPADDR 96.125.1.1
Oct 23 00:19:49 bigben mpd5: [B1] IPCP: state change Req-Sent --> Ack-Sent
Oct 23 00:19:50 bigben mpd5: [B1] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Oct 23 00:19:50 bigben mpd5: [B1]   IPADDR 45.2.2.1
Oct 23 00:19:50 bigben mpd5: [B1]     45.2.2.1 is OK
Oct 23 00:19:50 bigben mpd5: [B1] IPCP: SendConfigReq #3
Oct 23 00:19:50 bigben mpd5: [B1]   IPADDR 45.2.2.1
Oct 23 00:19:50 bigben mpd5: [B1] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Oct 23 00:19:50 bigben mpd5: [B1]   IPADDR 45.2.2.1
Oct 23 00:19:50 bigben mpd5: [B1] IPCP: state change Ack-Sent --> Opened
Oct 23 00:19:50 bigben mpd5: [B1] IPCP: LayerUp
Oct 23 00:19:50 bigben mpd5: [B1]   45.2.2.1 -> 96.125.1.1
Oct 23 00:19:50 bigben mpd5: [B1] IFACE: Connecting tcpmssfix
Oct 23 00:19:50 bigben mpd5: [B1] IFACE: Configuring ng_tcpmss mpd91388-B1-mss: 1460
Oct 23 00:19:50 bigben mpd5: [B1] IFACE: Add address 45.2.2.1/32->96.125.1.1 to ng0
Oct 23 00:19:50 bigben mpd5: [B1] IFACE: Add route 0.0.0.0/0 96.125.1.1
Oct 23 00:19:50 bigben mpd5: [B1] system: /usr/local/etc/mpd5/mpd.linkup ng0 inet 45.2.2.1/32 96.125.1.1 '-' '' '' '00:00:00:00:00:$
Oct 23 00:19:50 bigben mpd5: [B1] IFACE: Up event
Oct 23 00:19:50 bigben mpd5: [B1] IFACE: Change interface ng0 flags: -0 +1
Oct 23 00:19:57 bigben mpd5: [L1] LCP: rec'd Echo Request #1 (Opened)
Oct 23 00:19:57 bigben mpd5: [L1] LCP: SendEchoRep #1
Oct 23 00:19:57 bigben mpd5: [L2] LCP: rec'd Echo Request #1 (Opened)
Oct 23 00:19:57 bigben mpd5: [L2] LCP: SendEchoRep #1
Oct 23 00:20:07 bigben mpd5: [L1] LCP: rec'd Echo Request #2 (Opened)
Oct 23 00:20:07 bigben mpd5: [L1] LCP: SendEchoRep #2
Oct 23 00:20:07 bigben mpd5: [L2] LCP: rec'd Echo Request #2 (Opened)
     

    Last edit: Serge Cormier 2018-10-23

    • Eugene Grosbein

      Eugene Grosbein - 2018-10-23

      Have it started to work with single patch-3-lcp.c applied only?

       

    • Eugene Grosbein

      Eugene Grosbein - 2018-10-23

      Please try this patch instead of any previous patches to see if it helps with your bandwidth problem.

       

      Last edit: Eugene Grosbein 2018-10-24
      patch-4-lcp.c
1 2 > >> (Page 1 of 2)

Log in to post a comment.

Want the latest updates on software, tech news, and AI?
Get latest updates about software, tech news, and AI from SourceForge directly in your inbox once a month.