MPD: FreeBSD PPP daemon / Bugs / #63 Conectivity issues after #62

There are more information I should bring here.

If I force MTU and MRU to be 1480, using the following config it works:

startup:
    # configure the console
    set console close
    # configure the web server
    set web close
    set log +iface +iface2

default:
pppoeclient:
    create bundle static wan
    set bundle enable ipv6cp
    set iface name pppoe0
    set iface disable on-demand
    set iface idle 0
    set iface enable tcpmssfix
    set iface up-script /usr/local/sbin/ppp-linkup
    set iface down-script /usr/local/sbin/ppp-linkdown
    set ipcp ranges 0.0.0.0/0 0.0.0.0/0
    #log -bund -ccp -chat -iface -ipcp -lcp -link

    create link static wan_link0 pppoe
    set link action bundle wan
    set link disable multilink
    set link keep-alive 10 60
    set link max-redial 0
    set link disable chap pap
    set link accept chap pap eap
    set link disable incoming
    set link mtu 1480
    set link mru 1480
    set auth authname "renato.couto"
    set auth password renatocouto
    set pppoe service ""
    set pppoe iface igb1
    open

Aug  1 07:55:18 pfgarga ppp: [wan] IFACE: Close event
Aug  1 07:55:18 pfgarga ppp: [wan] IPCP: Close event
Aug  1 07:55:18 pfgarga ppp: [wan] IPCP: state change Opened --> Closing
Aug  1 07:55:18 pfgarga ppp: [wan] IPCP: SendTerminateReq #4
Aug  1 07:55:18 pfgarga ppp: [wan] IPCP: LayerDown
Aug  1 07:55:18 pfgarga ppp: [wan] IFACE: Down event
Aug  1 07:55:18 pfgarga ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0
Aug  1 07:55:18 pfgarga ppp: [wan] IPV6CP: Close event
Aug  1 07:55:18 pfgarga ppp: [wan] IPV6CP: state change Stopped --> Closed
Aug  1 07:55:18 pfgarga ppp: [wan] IPCP: rec'd Terminate Ack #4 (Closing)
Aug  1 07:55:18 pfgarga ppp: [wan] IPCP: state change Closing --> Closed
Aug  1 07:55:18 pfgarga ppp: [wan] IPCP: LayerFinish
Aug  1 07:55:18 pfgarga ppp: [wan] Bundle: No NCPs left. Closing links...
Aug  1 07:55:18 pfgarga ppp: [wan] Bundle: closing link "wan_link0"...
Aug  1 07:55:18 pfgarga ppp: [wan_link0] Link: CLOSE event
Aug  1 07:55:18 pfgarga ppp: [wan_link0] LCP: Close event
Aug  1 07:55:18 pfgarga ppp: [wan_link0] LCP: state change Opened --> Closing
Aug  1 07:55:18 pfgarga ppp: [wan_link0] Link: Leave bundle "wan"
Aug  1 07:55:18 pfgarga ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Aug  1 07:55:18 pfgarga ppp: [wan] IPCP: Close event
Aug  1 07:55:18 pfgarga ppp: [wan] IPV6CP: Close event
Aug  1 07:55:18 pfgarga ppp: [wan] IPCP: Down event
Aug  1 07:55:18 pfgarga ppp: [wan] IPCP: state change Closed --> Initial
Aug  1 07:55:18 pfgarga ppp: [wan] IPV6CP: Down event
Aug  1 07:55:18 pfgarga ppp: [wan] IPV6CP: state change Closed --> Initial
Aug  1 07:55:18 pfgarga ppp: [wan_link0] LCP: SendTerminateReq #4
Aug  1 07:55:18 pfgarga ppp: [wan_link0] LCP: LayerDown
Aug  1 07:55:18 pfgarga ppp: [wan_link0] LCP: rec'd Terminate Ack #4 (Closing)
Aug  1 07:55:18 pfgarga ppp: [wan_link0] LCP: state change Closing --> Closed
Aug  1 07:55:18 pfgarga ppp: [wan_link0] LCP: LayerFinish
Aug  1 07:55:18 pfgarga ppp: [wan_link0] Link: DOWN event
Aug  1 07:55:18 pfgarga ppp: [wan_link0] LCP: Down event
Aug  1 07:55:18 pfgarga ppp: [wan_link0] LCP: state change Closed --> Initial
Aug  1 07:55:20 pfgarga ppp: [wan] Bundle: Shutdown
Aug  1 07:55:20 pfgarga ppp: [wan_link0] Link: Shutdown
Aug  1 07:55:21 pfgarga ppp: [wan] Bundle: Interface ng0 created
Aug  1 07:55:21 pfgarga ppp: [wan_link0] Link: OPEN event
Aug  1 07:55:21 pfgarga ppp: [wan_link0] LCP: Open event
Aug  1 07:55:21 pfgarga ppp: [wan_link0] LCP: state change Initial --> Starting
Aug  1 07:55:21 pfgarga ppp: [wan_link0] LCP: LayerStart
Aug  1 07:55:21 pfgarga ppp: [wan_link0] PPPoE: Connecting to ''
Aug  1 07:55:25 pfgarga ppp: [wan] IFACE: Close event
Aug  1 07:55:25 pfgarga ppp: [wan] IPCP: Close event
Aug  1 07:55:25 pfgarga ppp: [wan] IPV6CP: Close event
Aug  1 07:55:27 pfgarga ppp: [wan_link0] PPPoE: connection successful
Aug  1 07:55:27 pfgarga ppp: [wan_link0] Link: UP event
Aug  1 07:55:27 pfgarga ppp: [wan_link0] LCP: Up event
Aug  1 07:55:27 pfgarga ppp: [wan_link0] LCP: state change Starting --> Req-Sent
Aug  1 07:55:27 pfgarga ppp: [wan_link0] LCP: SendConfigReq #1
Aug  1 07:55:27 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 07:55:27 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 07:55:27 pfgarga ppp: [wan_link0]   MAGICNUM 0xf48e0214
Aug  1 07:55:27 pfgarga ppp: [wan] Bundle: Shutdown
Aug  1 07:55:27 pfgarga ppp: [wan_link0] Link: Shutdown
Aug  1 07:55:28 pfgarga ppp: [wan] Bundle: Interface ng0 created
Aug  1 07:55:28 pfgarga ppp: [wan_link0] Link: OPEN event
Aug  1 07:55:28 pfgarga ppp: [wan_link0] LCP: Open event
Aug  1 07:55:28 pfgarga ppp: [wan_link0] LCP: state change Initial --> Starting
Aug  1 07:55:28 pfgarga ppp: [wan_link0] LCP: LayerStart
Aug  1 07:55:28 pfgarga ppp: [wan_link0] PPPoE: Connecting to ''
Aug  1 07:55:28 pfgarga ppp: [wan_link0] PPPoE: connection successful
Aug  1 07:55:28 pfgarga ppp: [wan_link0] Link: UP event
Aug  1 07:55:28 pfgarga ppp: [wan_link0] LCP: Up event
Aug  1 07:55:28 pfgarga ppp: [wan_link0] LCP: state change Starting --> Req-Sent
Aug  1 07:55:28 pfgarga ppp: [wan_link0] LCP: SendConfigReq #1
Aug  1 07:55:28 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 07:55:28 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 07:55:28 pfgarga ppp: [wan_link0]   MAGICNUM 0xe0c52730
Aug  1 07:55:29 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Aug  1 07:55:29 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 07:55:29 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 07:55:29 pfgarga ppp: [wan_link0]   MAGICNUM 0xd68e5e67
Aug  1 07:55:29 pfgarga ppp: [wan_link0]   MP MRRU 1600
Aug  1 07:55:29 pfgarga ppp: [wan_link0] LCP: SendConfigRej #1
Aug  1 07:55:29 pfgarga ppp: [wan_link0]   MP MRRU 1600
Aug  1 07:55:29 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #2 (Req-Sent)
Aug  1 07:55:29 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 07:55:29 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 07:55:29 pfgarga ppp: [wan_link0]   MAGICNUM 0xd68e5e67
Aug  1 07:55:29 pfgarga ppp: [wan_link0] LCP: SendConfigAck #2
Aug  1 07:55:29 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 07:55:29 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 07:55:29 pfgarga ppp: [wan_link0]   MAGICNUM 0xd68e5e67
Aug  1 07:55:29 pfgarga ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Aug  1 07:55:30 pfgarga ppp: [wan_link0] LCP: SendConfigReq #2
Aug  1 07:55:30 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 07:55:30 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 07:55:30 pfgarga ppp: [wan_link0]   MAGICNUM 0xe0c52730
Aug  1 07:55:30 pfgarga ppp: [wan_link0] LCP: rec'd Configure Reject #2 (Ack-Sent)
Aug  1 07:55:30 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 07:55:30 pfgarga ppp: [wan_link0] LCP: SendConfigReq #3
Aug  1 07:55:30 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 07:55:30 pfgarga ppp: [wan_link0]   MAGICNUM 0xe0c52730
Aug  1 07:55:30 pfgarga ppp: [wan_link0] LCP: rec'd Configure Ack #3 (Ack-Sent)
Aug  1 07:55:30 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 07:55:30 pfgarga ppp: [wan_link0]   MAGICNUM 0xe0c52730
Aug  1 07:55:30 pfgarga ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
Aug  1 07:55:30 pfgarga ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Aug  1 07:55:30 pfgarga ppp: [wan_link0] LCP: LayerUp
Aug  1 07:55:30 pfgarga ppp: [wan_link0] CHAP: rec'd CHALLENGE #1 len: 56
Aug  1 07:55:30 pfgarga ppp: [wan_link0]   Name: "BRAS Iracemapolis - Telecom IntePe^S"
Aug  1 07:55:30 pfgarga ppp: [wan_link0] CHAP: Using authname "renato.couto"
Aug  1 07:55:30 pfgarga ppp: [wan_link0] CHAP: sending RESPONSE #1 len: 33
Aug  1 07:55:30 pfgarga ppp: [wan_link0] CHAP: rec'd SUCCESS #1 len: 12
Aug  1 07:55:30 pfgarga ppp: [wan_link0]   MESG: Welcome.
Aug  1 07:55:30 pfgarga ppp: [wan_link0] LCP: authorization successful
Aug  1 07:55:30 pfgarga ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Aug  1 07:55:30 pfgarga ppp: [wan_link0] Link: Join bundle "wan"
Aug  1 07:55:30 pfgarga ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: Open event
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: state change Initial --> Starting
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: LayerStart
Aug  1 07:55:30 pfgarga ppp: [wan] IPV6CP: Open event
Aug  1 07:55:30 pfgarga ppp: [wan] IPV6CP: state change Initial --> Starting
Aug  1 07:55:30 pfgarga ppp: [wan] IPV6CP: LayerStart
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: Up event
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: state change Starting --> Req-Sent
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: SendConfigReq #1
Aug  1 07:55:30 pfgarga ppp: [wan]   IPADDR 0.0.0.0
Aug  1 07:55:30 pfgarga ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug  1 07:55:30 pfgarga ppp: [wan] IPV6CP: Up event
Aug  1 07:55:30 pfgarga ppp: [wan] IPV6CP: state change Starting --> Req-Sent
Aug  1 07:55:30 pfgarga ppp: [wan] IPV6CP: SendConfigReq #1
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: rec'd Configure Request #1 (Req-Sent)
Aug  1 07:55:30 pfgarga ppp: [wan]   IPADDR 177.53.80.8
Aug  1 07:55:30 pfgarga ppp: [wan]     177.53.80.8 is OK
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: SendConfigAck #1
Aug  1 07:55:30 pfgarga ppp: [wan]   IPADDR 177.53.80.8
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: state change Req-Sent --> Ack-Sent
Aug  1 07:55:30 pfgarga ppp: [wan_link0] rec'd unexpected protocol MPLS Control Protocol (RFC 3032), rejecting
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Aug  1 07:55:30 pfgarga ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: SendConfigReq #2
Aug  1 07:55:30 pfgarga ppp: [wan]   IPADDR 0.0.0.0
Aug  1 07:55:30 pfgarga ppp: [wan_link0] LCP: rec'd Protocol Reject #3 (Opened)
Aug  1 07:55:30 pfgarga ppp: [wan_link0] LCP: protocol IPV6CP was rejected
Aug  1 07:55:30 pfgarga ppp: [wan] IPV6CP: protocol was rejected by peer
Aug  1 07:55:30 pfgarga ppp: [wan] IPV6CP: state change Req-Sent --> Stopped
Aug  1 07:55:30 pfgarga ppp: [wan] IPV6CP: LayerFinish
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Aug  1 07:55:30 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 07:55:30 pfgarga ppp: [wan]     177.53.86.172 is OK
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: SendConfigReq #3
Aug  1 07:55:30 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Aug  1 07:55:30 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: state change Ack-Sent --> Opened
Aug  1 07:55:30 pfgarga ppp: [wan] IPCP: LayerUp
Aug  1 07:55:30 pfgarga ppp: [wan]   177.53.86.172 -> 177.53.80.8
Aug  1 07:55:31 pfgarga ppp: [wan] IFACE: Up event
Aug  1 07:55:31 pfgarga ppp: [wan] IFACE: Rename interface ng0 to pppoe0

# ifconfig pppoe0
pppoe0: flags=88d1<UP,POINTOPOINT,RUNNING,NOARP,SIMPLEX,MULTICAST> metric 0 mtu 1480
    inet 177.53.86.172 --> 177.53.80.8 netmask 0xffffffff
    inet6 fe80::208:a2ff:fe09:5b54%pppoe0 prefixlen 64 scopeid 0xc
    nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>

# netstat -nrWf inet
Routing tables

Internet:
Destination        Gateway            Flags       Use    Mtu      Netif Expire
default            177.53.80.8        UGS         204   1480     pppoe0
10.250.250.0/24    link#11            U             0   1500   igb0.666
10.250.250.1       link#11            UHS           0  16384        lo0
127.0.0.1          link#8             UH         7883  16384        lo0
172.21.4.0/24      link#1             U       9998445   1500       igb0
172.21.4.1         link#1             UHS           0  16384        lo0
177.53.80.4        pppoe0             UHS         148   1480     pppoe0
177.53.80.8        link#12            UH            0   1480     pppoe0
177.53.86.172      link#12            UHS           0  16384        lo0
201.77.127.155     link#13            UHS           0  16384        lo0
216.66.22.2        177.53.80.8        UGHS        257   1480     pppoe0

But if I comment out both MTU and MRU lines the problem is back

startup:
    # configure the console
    set console close
    # configure the web server
    set web close
    set log +iface +iface2

default:
pppoeclient:
    create bundle static wan
    set bundle enable ipv6cp
    set iface name pppoe0
    set iface disable on-demand
    set iface idle 0
    set iface enable tcpmssfix
    set iface up-script /usr/local/sbin/ppp-linkup
    set iface down-script /usr/local/sbin/ppp-linkdown
    set ipcp ranges 0.0.0.0/0 0.0.0.0/0
    #log -bund -ccp -chat -iface -ipcp -lcp -link

    create link static wan_link0 pppoe
    set link action bundle wan
    set link disable multilink
    set link keep-alive 10 60
    set link max-redial 0
    set link disable chap pap
    set link accept chap pap eap
    set link disable incoming
    #set link mtu 1480
    #set link mru 1480
    set auth authname "renato.couto"
    set auth password renatocouto
    set pppoe service ""
    set pppoe iface igb1
    open

Aug  1 07:58:10 pfgarga ppp: [wan] IFACE: Close event
Aug  1 07:58:10 pfgarga ppp: [wan] IPCP: Close event
Aug  1 07:58:10 pfgarga ppp: [wan] IPCP: state change Opened --> Closing
Aug  1 07:58:10 pfgarga ppp: [wan] IPCP: SendTerminateReq #4
Aug  1 07:58:10 pfgarga ppp: [wan] IPCP: LayerDown
Aug  1 07:58:11 pfgarga ppp: [wan] IFACE: Down event
Aug  1 07:58:11 pfgarga ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0
Aug  1 07:58:11 pfgarga ppp: [wan] IPV6CP: Close event
Aug  1 07:58:11 pfgarga ppp: [wan] IPV6CP: state change Stopped --> Closed
Aug  1 07:58:11 pfgarga ppp: [wan] IPCP: rec'd Terminate Ack #4 (Closing)
Aug  1 07:58:11 pfgarga ppp: [wan] IPCP: state change Closing --> Closed
Aug  1 07:58:11 pfgarga ppp: [wan] IPCP: LayerFinish
Aug  1 07:58:11 pfgarga ppp: [wan] Bundle: No NCPs left. Closing links...
Aug  1 07:58:11 pfgarga ppp: [wan] Bundle: closing link "wan_link0"...
Aug  1 07:58:11 pfgarga ppp: [wan_link0] Link: CLOSE event
Aug  1 07:58:11 pfgarga ppp: [wan_link0] LCP: Close event
Aug  1 07:58:11 pfgarga ppp: [wan_link0] LCP: state change Opened --> Closing
Aug  1 07:58:11 pfgarga ppp: [wan_link0] Link: Leave bundle "wan"
Aug  1 07:58:11 pfgarga ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Aug  1 07:58:11 pfgarga ppp: [wan] IPCP: Close event
Aug  1 07:58:11 pfgarga ppp: [wan] IPV6CP: Close event
Aug  1 07:58:11 pfgarga ppp: [wan] IPCP: Down event
Aug  1 07:58:11 pfgarga ppp: [wan] IPCP: state change Closed --> Initial
Aug  1 07:58:11 pfgarga ppp: [wan] IPV6CP: Down event
Aug  1 07:58:11 pfgarga ppp: [wan] IPV6CP: state change Closed --> Initial
Aug  1 07:58:11 pfgarga ppp: [wan_link0] LCP: SendTerminateReq #4
Aug  1 07:58:11 pfgarga ppp: [wan_link0] LCP: LayerDown
Aug  1 07:58:11 pfgarga ppp: [wan_link0] LCP: rec'd Terminate Ack #4 (Closing)
Aug  1 07:58:11 pfgarga ppp: [wan_link0] LCP: state change Closing --> Closed
Aug  1 07:58:11 pfgarga ppp: [wan_link0] LCP: LayerFinish
Aug  1 07:58:11 pfgarga ppp: [wan_link0] Link: DOWN event
Aug  1 07:58:11 pfgarga ppp: [wan_link0] LCP: Down event
Aug  1 07:58:11 pfgarga ppp: [wan_link0] LCP: state change Closed --> Initial
Aug  1 07:58:13 pfgarga ppp: [wan] Bundle: Shutdown
Aug  1 07:58:13 pfgarga ppp: [wan_link0] Link: Shutdown
Aug  1 07:58:14 pfgarga ppp: [wan] Bundle: Interface ng0 created
Aug  1 07:58:14 pfgarga ppp: [wan_link0] Link: OPEN event
Aug  1 07:58:14 pfgarga ppp: [wan_link0] LCP: Open event
Aug  1 07:58:14 pfgarga ppp: [wan_link0] LCP: state change Initial --> Starting
Aug  1 07:58:14 pfgarga ppp: [wan_link0] LCP: LayerStart
Aug  1 07:58:14 pfgarga ppp: [wan_link0] PPPoE: Connecting to ''
Aug  1 07:58:14 pfgarga ppp: [wan_link0] PPPoE: connection successful
Aug  1 07:58:14 pfgarga ppp: [wan_link0] Link: UP event
Aug  1 07:58:14 pfgarga ppp: [wan_link0] LCP: Up event
Aug  1 07:58:14 pfgarga ppp: [wan_link0] LCP: state change Starting --> Req-Sent
Aug  1 07:58:14 pfgarga ppp: [wan_link0] LCP: SendConfigReq #1
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   MRU 1492
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   MAGICNUM 0xd997b874
Aug  1 07:58:14 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   MAGICNUM 0x9ea9226f
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   MP MRRU 1600
Aug  1 07:58:14 pfgarga ppp: [wan_link0] LCP: SendConfigRej #1
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   MP MRRU 1600
Aug  1 07:58:14 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #2 (Req-Sent)
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   MAGICNUM 0x9ea9226f
Aug  1 07:58:14 pfgarga ppp: [wan_link0] LCP: SendConfigAck #2
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 07:58:14 pfgarga ppp: [wan_link0]   MAGICNUM 0x9ea9226f
Aug  1 07:58:14 pfgarga ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Aug  1 07:58:15 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #3 (Ack-Sent)
Aug  1 07:58:15 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 07:58:15 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 07:58:15 pfgarga ppp: [wan_link0]   MAGICNUM 0x9ea9226f
Aug  1 07:58:15 pfgarga ppp: [wan_link0] LCP: SendConfigAck #3
Aug  1 07:58:15 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 07:58:15 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 07:58:15 pfgarga ppp: [wan_link0]   MAGICNUM 0x9ea9226f
Aug  1 07:58:16 pfgarga ppp: [wan_link0] LCP: SendConfigReq #2
Aug  1 07:58:16 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 07:58:16 pfgarga ppp: [wan_link0]   MRU 1492
Aug  1 07:58:16 pfgarga ppp: [wan_link0]   MAGICNUM 0xd997b874
Aug  1 07:58:16 pfgarga ppp: [wan_link0] LCP: rec'd Configure Reject #2 (Ack-Sent)
Aug  1 07:58:16 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 07:58:16 pfgarga ppp: [wan_link0] LCP: SendConfigReq #3
Aug  1 07:58:16 pfgarga ppp: [wan_link0]   MRU 1492
Aug  1 07:58:16 pfgarga ppp: [wan_link0]   MAGICNUM 0xd997b874
Aug  1 07:58:16 pfgarga ppp: [wan_link0] LCP: rec'd Configure Ack #3 (Ack-Sent)
Aug  1 07:58:16 pfgarga ppp: [wan_link0]   MRU 1492
Aug  1 07:58:16 pfgarga ppp: [wan_link0]   MAGICNUM 0xd997b874
Aug  1 07:58:16 pfgarga ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
Aug  1 07:58:16 pfgarga ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Aug  1 07:58:16 pfgarga ppp: [wan_link0] LCP: LayerUp
Aug  1 07:58:16 pfgarga ppp: [wan_link0] CHAP: rec'd CHALLENGE #1 len: 56
Aug  1 07:58:16 pfgarga ppp: [wan_link0]   Name: "BRAS Iracemapolis - Telecom Inte h^Q"
Aug  1 07:58:16 pfgarga ppp: [wan_link0] CHAP: Using authname "renato.couto"
Aug  1 07:58:16 pfgarga ppp: [wan_link0] CHAP: sending RESPONSE #1 len: 33
Aug  1 07:58:16 pfgarga ppp: [wan_link0] CHAP: rec'd SUCCESS #1 len: 12
Aug  1 07:58:16 pfgarga ppp: [wan_link0]   MESG: Welcome.
Aug  1 07:58:16 pfgarga ppp: [wan_link0] LCP: authorization successful
Aug  1 07:58:16 pfgarga ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Aug  1 07:58:16 pfgarga ppp: [wan_link0] Link: Join bundle "wan"
Aug  1 07:58:16 pfgarga ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: Open event
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: state change Initial --> Starting
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: LayerStart
Aug  1 07:58:16 pfgarga ppp: [wan] IPV6CP: Open event
Aug  1 07:58:16 pfgarga ppp: [wan] IPV6CP: state change Initial --> Starting
Aug  1 07:58:16 pfgarga ppp: [wan] IPV6CP: LayerStart
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: Up event
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: state change Starting --> Req-Sent
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: SendConfigReq #1
Aug  1 07:58:16 pfgarga ppp: [wan]   IPADDR 0.0.0.0
Aug  1 07:58:16 pfgarga ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug  1 07:58:16 pfgarga ppp: [wan] IPV6CP: Up event
Aug  1 07:58:16 pfgarga ppp: [wan] IPV6CP: state change Starting --> Req-Sent
Aug  1 07:58:16 pfgarga ppp: [wan] IPV6CP: SendConfigReq #1
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: rec'd Configure Request #1 (Req-Sent)
Aug  1 07:58:16 pfgarga ppp: [wan]   IPADDR 177.53.80.8
Aug  1 07:58:16 pfgarga ppp: [wan]     177.53.80.8 is OK
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: SendConfigAck #1
Aug  1 07:58:16 pfgarga ppp: [wan]   IPADDR 177.53.80.8
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: state change Req-Sent --> Ack-Sent
Aug  1 07:58:16 pfgarga ppp: [wan_link0] rec'd unexpected protocol MPLS Control Protocol (RFC 3032), rejecting
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Aug  1 07:58:16 pfgarga ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: SendConfigReq #2
Aug  1 07:58:16 pfgarga ppp: [wan]   IPADDR 0.0.0.0
Aug  1 07:58:16 pfgarga ppp: [wan_link0] LCP: rec'd Protocol Reject #4 (Opened)
Aug  1 07:58:16 pfgarga ppp: [wan_link0] LCP: protocol IPV6CP was rejected
Aug  1 07:58:16 pfgarga ppp: [wan] IPV6CP: protocol was rejected by peer
Aug  1 07:58:16 pfgarga ppp: [wan] IPV6CP: state change Req-Sent --> Stopped
Aug  1 07:58:16 pfgarga ppp: [wan] IPV6CP: LayerFinish
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Aug  1 07:58:16 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 07:58:16 pfgarga ppp: [wan]     177.53.86.172 is OK
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: SendConfigReq #3
Aug  1 07:58:16 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Aug  1 07:58:16 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: state change Ack-Sent --> Opened
Aug  1 07:58:16 pfgarga ppp: [wan] IPCP: LayerUp
Aug  1 07:58:16 pfgarga ppp: [wan]   177.53.86.172 -> 177.53.80.8
Aug  1 07:58:16 pfgarga ppp: [wan] IFACE: Up event
Aug  1 07:58:16 pfgarga ppp: [wan] IFACE: Rename interface ng0 to pppoe0

# ifconfig pppoe0
pppoe0: flags=88d1<UP,POINTOPOINT,RUNNING,NOARP,SIMPLEX,MULTICAST> metric 0 mtu 1492
    inet 177.53.86.172 --> 177.53.80.8 netmask 0xffffffff
    inet6 fe80::208:a2ff:fe09:5b54%pppoe0 prefixlen 64 scopeid 0xc
    nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>

# netstat -nrWf inet
Routing tables

Internet:
Destination        Gateway            Flags       Use    Mtu      Netif Expire
default            177.53.80.8        UGS         226   1492     pppoe0
10.250.250.0/24    link#11            U             0   1500   igb0.666
10.250.250.1       link#11            UHS           0  16384        lo0
127.0.0.1          link#8             UH         7890  16384        lo0
172.21.4.0/24      link#1             U      10015585   1500       igb0
172.21.4.1         link#1             UHS           0  16384        lo0
177.53.80.4        pppoe0             UHS         135   1492     pppoe0
177.53.80.8        link#12            UH            0   1492     pppoe0
177.53.86.172      link#12            UHS           0  16384        lo0
201.77.127.155     link#13            UHS           0  16384        lo0
216.66.22.2        177.53.80.8        UGHS        224   1492     pppoe0

Eugene Grosbein - 2018-08-01

The command "set log ..." is wrong, it should be "log +iface +iface2" without "set". Because of this, there is not enough details in the log. Please correct the configuration and repeat the test using new version (no need to re-test using old one).

Last edit: Eugene Grosbein 2018-08-01

If you would like to refer to this comment somewhere else in this project, copy and paste the following link:

Sure, follow you can see the one that fails

startup:
    # configure the console
    set console close
    # configure the web server
    set web close
    log +iface +iface2

default:
pppoeclient:
    create bundle static wan
    set bundle enable ipv6cp
    set iface name pppoe0
    set iface disable on-demand
    set iface idle 0
    set iface enable tcpmssfix
    set iface up-script /usr/local/sbin/ppp-linkup
    set iface down-script /usr/local/sbin/ppp-linkdown
    set ipcp ranges 0.0.0.0/0 0.0.0.0/0
    #log -bund -ccp -chat -iface -ipcp -lcp -link

    create link static wan_link0 pppoe
    set link action bundle wan
    set link disable multilink
    set link keep-alive 10 60
    set link max-redial 0
    set link disable chap pap
    set link accept chap pap eap
    set link disable incoming
    #set link mtu 1480
    #set link mru 1480
    set auth authname "renato.couto"
    set auth password renatocouto
    set pppoe service ""
    set pppoe iface igb1
    open

Aug  1 08:28:52 pfgarga ppp: [wan] IFACE: Close event
Aug  1 08:28:52 pfgarga ppp: [wan] IPCP: Close event
Aug  1 08:28:52 pfgarga ppp: [wan] IPCP: state change Opened --> Closing
Aug  1 08:28:52 pfgarga ppp: [wan] IPCP: SendTerminateReq #4
Aug  1 08:28:52 pfgarga ppp: [wan] IPCP: LayerDown
Aug  1 08:28:52 pfgarga ppp: [wan] IFACE: Down event
Aug  1 08:28:52 pfgarga ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0
Aug  1 08:28:52 pfgarga ppp: [wan] IPV6CP: Close event
Aug  1 08:28:52 pfgarga ppp: [wan] IPV6CP: state change Stopped --> Closed
Aug  1 08:28:52 pfgarga ppp: [wan] IPCP: rec'd Terminate Ack #4 (Closing)
Aug  1 08:28:52 pfgarga ppp: [wan] IPCP: state change Closing --> Closed
Aug  1 08:28:52 pfgarga ppp: [wan] IPCP: LayerFinish
Aug  1 08:28:52 pfgarga ppp: [wan] Bundle: No NCPs left. Closing links...
Aug  1 08:28:52 pfgarga ppp: [wan] Bundle: closing link "wan_link0"...
Aug  1 08:28:52 pfgarga ppp: [wan_link0] Link: CLOSE event
Aug  1 08:28:52 pfgarga ppp: [wan_link0] LCP: Close event
Aug  1 08:28:52 pfgarga ppp: [wan_link0] LCP: state change Opened --> Closing
Aug  1 08:28:52 pfgarga ppp: [wan_link0] Link: Leave bundle "wan"
Aug  1 08:28:52 pfgarga ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Aug  1 08:28:52 pfgarga ppp: [wan] IPCP: Close event
Aug  1 08:28:52 pfgarga ppp: [wan] IPV6CP: Close event
Aug  1 08:28:52 pfgarga ppp: [wan] IPCP: Down event
Aug  1 08:28:52 pfgarga ppp: [wan] IPCP: state change Closed --> Initial
Aug  1 08:28:52 pfgarga ppp: [wan] IPV6CP: Down event
Aug  1 08:28:52 pfgarga ppp: [wan] IPV6CP: state change Closed --> Initial
Aug  1 08:28:52 pfgarga ppp: [wan_link0] LCP: SendTerminateReq #3
Aug  1 08:28:52 pfgarga ppp: [wan_link0] LCP: LayerDown
Aug  1 08:28:52 pfgarga ppp: [wan_link0] LCP: rec'd Terminate Ack #3 (Closing)
Aug  1 08:28:52 pfgarga ppp: [wan_link0] LCP: state change Closing --> Closed
Aug  1 08:28:52 pfgarga ppp: [wan_link0] LCP: LayerFinish
Aug  1 08:28:52 pfgarga ppp: [wan_link0] Link: DOWN event
Aug  1 08:28:52 pfgarga ppp: [wan_link0] LCP: Down event
Aug  1 08:28:52 pfgarga ppp: [wan_link0] LCP: state change Closed --> Initial
Aug  1 08:28:54 pfgarga ppp: [wan] Bundle: Shutdown
Aug  1 08:28:54 pfgarga ppp: [wan_link0] Link: Shutdown
Aug  1 08:28:55 pfgarga ppp: [wan] Bundle: Interface ng0 created
Aug  1 08:28:55 pfgarga ppp: [wan] IFACE: setting "ng0" name to "pppoe0"
Aug  1 08:28:55 pfgarga ppp: [wan_link0] Link: OPEN event
Aug  1 08:28:55 pfgarga ppp: [wan_link0] LCP: Open event
Aug  1 08:28:55 pfgarga ppp: [wan_link0] LCP: state change Initial --> Starting
Aug  1 08:28:55 pfgarga ppp: [wan_link0] LCP: LayerStart
Aug  1 08:28:55 pfgarga ppp: [wan_link0] PPPoE: Connecting to ''
Aug  1 08:28:55 pfgarga ppp: [wan_link0] PPPoE: connection successful
Aug  1 08:28:55 pfgarga ppp: [wan_link0] Link: UP event
Aug  1 08:28:55 pfgarga ppp: [wan_link0] LCP: Up event
Aug  1 08:28:55 pfgarga ppp: [wan_link0] LCP: state change Starting --> Req-Sent
Aug  1 08:28:55 pfgarga ppp: [wan_link0] LCP: SendConfigReq #1
Aug  1 08:28:55 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 08:28:55 pfgarga ppp: [wan_link0]   MRU 1492
Aug  1 08:28:55 pfgarga ppp: [wan_link0]   MAGICNUM 0xa8790a74
Aug  1 08:28:56 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Aug  1 08:28:56 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 08:28:56 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 08:28:56 pfgarga ppp: [wan_link0]   MAGICNUM 0x9468ac2b
Aug  1 08:28:56 pfgarga ppp: [wan_link0]   MP MRRU 1600
Aug  1 08:28:56 pfgarga ppp: [wan_link0] LCP: SendConfigRej #1
Aug  1 08:28:56 pfgarga ppp: [wan_link0]   MP MRRU 1600
Aug  1 08:28:56 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #2 (Req-Sent)
Aug  1 08:28:56 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 08:28:56 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 08:28:56 pfgarga ppp: [wan_link0]   MAGICNUM 0x9468ac2b
Aug  1 08:28:56 pfgarga ppp: [wan_link0] LCP: SendConfigAck #2
Aug  1 08:28:56 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 08:28:56 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 08:28:56 pfgarga ppp: [wan_link0]   MAGICNUM 0x9468ac2b
Aug  1 08:28:56 pfgarga ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Aug  1 08:28:57 pfgarga ppp: [wan_link0] LCP: SendConfigReq #2
Aug  1 08:28:57 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 08:28:57 pfgarga ppp: [wan_link0]   MRU 1492
Aug  1 08:28:57 pfgarga ppp: [wan_link0]   MAGICNUM 0xa8790a74
Aug  1 08:28:57 pfgarga ppp: [wan_link0] LCP: rec'd Configure Reject #2 (Ack-Sent)
Aug  1 08:28:57 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 08:28:57 pfgarga ppp: [wan_link0] LCP: SendConfigReq #3
Aug  1 08:28:57 pfgarga ppp: [wan_link0]   MRU 1492
Aug  1 08:28:57 pfgarga ppp: [wan_link0]   MAGICNUM 0xa8790a74
Aug  1 08:28:57 pfgarga ppp: [wan_link0] LCP: rec'd Configure Ack #3 (Ack-Sent)
Aug  1 08:28:57 pfgarga ppp: [wan_link0]   MRU 1492
Aug  1 08:28:57 pfgarga ppp: [wan_link0]   MAGICNUM 0xa8790a74
Aug  1 08:28:57 pfgarga ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
Aug  1 08:28:57 pfgarga ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Aug  1 08:28:57 pfgarga ppp: [wan_link0] LCP: LayerUp
Aug  1 08:28:57 pfgarga ppp: [wan_link0] CHAP: rec'd CHALLENGE #1 len: 56
Aug  1 08:28:57 pfgarga ppp: [wan_link0]   Name: "BRAS Iracemapolis - Telecom Inte�{^U"
Aug  1 08:28:57 pfgarga ppp: [wan_link0] CHAP: Using authname "renato.couto"
Aug  1 08:28:57 pfgarga ppp: [wan_link0] CHAP: sending RESPONSE #1 len: 33
Aug  1 08:28:57 pfgarga ppp: [wan_link0] CHAP: rec'd SUCCESS #1 len: 12
Aug  1 08:28:57 pfgarga ppp: [wan_link0]   MESG: Welcome.
Aug  1 08:28:57 pfgarga ppp: [wan_link0] LCP: authorization successful
Aug  1 08:28:57 pfgarga ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Aug  1 08:28:57 pfgarga ppp: [wan_link0] Link: Join bundle "wan"
Aug  1 08:28:57 pfgarga ppp: [wan] IFACE: setting pppoe0 MTU to 1492 bytes
Aug  1 08:28:57 pfgarga ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: Open event
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: state change Initial --> Starting
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: LayerStart
Aug  1 08:28:57 pfgarga ppp: [wan] IPV6CP: Open event
Aug  1 08:28:57 pfgarga ppp: [wan] IPV6CP: state change Initial --> Starting
Aug  1 08:28:57 pfgarga ppp: [wan] IPV6CP: LayerStart
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: Up event
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: state change Starting --> Req-Sent
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: SendConfigReq #1
Aug  1 08:28:57 pfgarga ppp: [wan]   IPADDR 0.0.0.0
Aug  1 08:28:57 pfgarga ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug  1 08:28:57 pfgarga ppp: [wan] IPV6CP: Up event
Aug  1 08:28:57 pfgarga ppp: [wan] IPV6CP: state change Starting --> Req-Sent
Aug  1 08:28:57 pfgarga ppp: [wan] IPV6CP: SendConfigReq #1
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: rec'd Configure Request #1 (Req-Sent)
Aug  1 08:28:57 pfgarga ppp: [wan]   IPADDR 177.53.80.8
Aug  1 08:28:57 pfgarga ppp: [wan]     177.53.80.8 is OK
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: SendConfigAck #1
Aug  1 08:28:57 pfgarga ppp: [wan]   IPADDR 177.53.80.8
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: state change Req-Sent --> Ack-Sent
Aug  1 08:28:57 pfgarga ppp: [wan_link0] rec'd unexpected protocol MPLS Control Protocol (RFC 3032), rejecting
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Aug  1 08:28:57 pfgarga ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: SendConfigReq #2
Aug  1 08:28:57 pfgarga ppp: [wan]   IPADDR 0.0.0.0
Aug  1 08:28:57 pfgarga ppp: [wan_link0] LCP: rec'd Protocol Reject #3 (Opened)
Aug  1 08:28:57 pfgarga ppp: [wan_link0] LCP: protocol IPV6CP was rejected
Aug  1 08:28:57 pfgarga ppp: [wan] IPV6CP: protocol was rejected by peer
Aug  1 08:28:57 pfgarga ppp: [wan] IPV6CP: state change Req-Sent --> Stopped
Aug  1 08:28:57 pfgarga ppp: [wan] IPV6CP: LayerFinish
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Aug  1 08:28:57 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 08:28:57 pfgarga ppp: [wan]     177.53.86.172 is OK
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: SendConfigReq #3
Aug  1 08:28:57 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Aug  1 08:28:57 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: state change Ack-Sent --> Opened
Aug  1 08:28:57 pfgarga ppp: [wan] IPCP: LayerUp
Aug  1 08:28:57 pfgarga ppp: [wan]   177.53.86.172 -> 177.53.80.8
Aug  1 08:28:57 pfgarga ppp: [wan] IFACE: Connecting tcpmssfix
Aug  1 08:28:57 pfgarga ppp: [wan] IFACE: Add address 177.53.86.172/32->177.53.80.8 to pppoe0
Aug  1 08:28:57 pfgarga ppp: [wan] system: /usr/local/sbin/ppp-linkup pppoe0 inet 177.53.86.172/32 177.53.80.8 '-' '' '' '00:00:00:00:00:00' '-'
Aug  1 08:28:57 pfgarga ppp: [wan] IFACE: Up event
Aug  1 08:28:57 pfgarga ppp: [wan] IFACE: Rename interface ng0 to pppoe0
Aug  1 08:28:57 pfgarga ppp: [wan] IFACE: Change interface pppoe0 flags: -0 +1

# ifconfig pppoe0
pppoe0: flags=88d1<UP,POINTOPOINT,RUNNING,NOARP,SIMPLEX,MULTICAST> metric 0 mtu 1492
    inet 177.53.86.172 --> 177.53.80.8 netmask 0xffffffff
    inet6 fe80::208:a2ff:fe09:5b54%pppoe0 prefixlen 64 scopeid 0xc
    nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>

Last edit: Renato Botelho 2018-08-01

And here the one that works:

startup:
    # configure the console
    set console close
    # configure the web server
    set web close
    log +iface +iface2

default:
pppoeclient:
    create bundle static wan
    set bundle enable ipv6cp
    set iface name pppoe0
    set iface disable on-demand
    set iface idle 0
    set iface enable tcpmssfix
    set iface up-script /usr/local/sbin/ppp-linkup
    set iface down-script /usr/local/sbin/ppp-linkdown
    set ipcp ranges 0.0.0.0/0 0.0.0.0/0
    #log -bund -ccp -chat -iface -ipcp -lcp -link

    create link static wan_link0 pppoe
    set link action bundle wan
    set link disable multilink
    set link keep-alive 10 60
    set link max-redial 0
    set link disable chap pap
    set link accept chap pap eap
    set link disable incoming
    set link mtu 1480
    set link mru 1480
    set auth authname "renato.couto"
    set auth password renatocouto
    set pppoe service ""
    set pppoe iface igb1
    open

Aug  1 08:31:45 pfgarga ppp: [wan] IFACE: Close event
Aug  1 08:31:45 pfgarga ppp: [wan] IPCP: Close event
Aug  1 08:31:45 pfgarga ppp: [wan] IPCP: state change Opened --> Closing
Aug  1 08:31:45 pfgarga ppp: [wan] IPCP: SendTerminateReq #4
Aug  1 08:31:45 pfgarga ppp: [wan] IPCP: LayerDown
Aug  1 08:31:45 pfgarga ppp: [wan] system: /usr/local/sbin/ppp-linkdown pppoe0 inet 177.53.86.172/32 177.53.80.8 '-' '00:00:00:00:00:00' '-'
Aug  1 08:31:45 pfgarga ppp: [wan] IFACE: Remove address 177.53.86.172/32->177.53.80.8 from pppoe0
Aug  1 08:31:45 pfgarga ppp: [wan] IFACE: Down event
Aug  1 08:31:45 pfgarga ppp: [wan] IFACE: Change interface pppoe0 flags: -4097 +0
Aug  1 08:31:45 pfgarga ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0
Aug  1 08:31:45 pfgarga ppp: [wan] IPV6CP: Close event
Aug  1 08:31:45 pfgarga ppp: [wan] IPV6CP: state change Stopped --> Closed
Aug  1 08:31:45 pfgarga ppp: [wan] IPCP: rec'd Terminate Ack #4 (Closing)
Aug  1 08:31:45 pfgarga ppp: [wan] IPCP: state change Closing --> Closed
Aug  1 08:31:45 pfgarga ppp: [wan] IPCP: LayerFinish
Aug  1 08:31:45 pfgarga ppp: [wan] Bundle: No NCPs left. Closing links...
Aug  1 08:31:45 pfgarga ppp: [wan] Bundle: closing link "wan_link0"...
Aug  1 08:31:45 pfgarga ppp: [wan_link0] Link: CLOSE event
Aug  1 08:31:45 pfgarga ppp: [wan_link0] LCP: Close event
Aug  1 08:31:45 pfgarga ppp: [wan_link0] LCP: state change Opened --> Closing
Aug  1 08:31:45 pfgarga ppp: [wan_link0] Link: Leave bundle "wan"
Aug  1 08:31:45 pfgarga ppp: [wan] IFACE: setting pppoe0 MTU to 1500 bytes
Aug  1 08:31:45 pfgarga ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Aug  1 08:31:45 pfgarga ppp: [wan] IPCP: Close event
Aug  1 08:31:45 pfgarga ppp: [wan] IPV6CP: Close event
Aug  1 08:31:45 pfgarga ppp: [wan] IPCP: Down event
Aug  1 08:31:45 pfgarga ppp: [wan] IPCP: state change Closed --> Initial
Aug  1 08:31:45 pfgarga ppp: [wan] IPV6CP: Down event
Aug  1 08:31:45 pfgarga ppp: [wan] IPV6CP: state change Closed --> Initial
Aug  1 08:31:45 pfgarga ppp: [wan_link0] LCP: SendTerminateReq #4
Aug  1 08:31:45 pfgarga ppp: [wan_link0] LCP: LayerDown
Aug  1 08:31:45 pfgarga ppp: [wan_link0] LCP: rec'd Terminate Ack #4 (Closing)
Aug  1 08:31:45 pfgarga ppp: [wan_link0] LCP: state change Closing --> Closed
Aug  1 08:31:45 pfgarga ppp: [wan_link0] LCP: LayerFinish
Aug  1 08:31:45 pfgarga ppp: [wan_link0] Link: DOWN event
Aug  1 08:31:45 pfgarga ppp: [wan_link0] LCP: Down event
Aug  1 08:31:45 pfgarga ppp: [wan_link0] LCP: state change Closed --> Initial
Aug  1 08:31:48 pfgarga ppp: [wan] Bundle: Shutdown
Aug  1 08:31:48 pfgarga ppp: [wan_link0] Link: Shutdown
Aug  1 08:31:48 pfgarga ppp: [wan] Bundle: Interface ng0 created
Aug  1 08:31:48 pfgarga ppp: [wan] IFACE: setting "ng0" name to "pppoe0"
Aug  1 08:31:48 pfgarga ppp: [wan_link0] Link: OPEN event
Aug  1 08:31:48 pfgarga ppp: [wan_link0] LCP: Open event
Aug  1 08:31:48 pfgarga ppp: [wan_link0] LCP: state change Initial --> Starting
Aug  1 08:31:48 pfgarga ppp: [wan_link0] LCP: LayerStart
Aug  1 08:31:48 pfgarga ppp: [wan_link0] PPPoE: Connecting to ''
Aug  1 08:31:48 pfgarga ppp: [wan_link0] PPPoE: connection successful
Aug  1 08:31:48 pfgarga ppp: [wan_link0] Link: UP event
Aug  1 08:31:48 pfgarga ppp: [wan_link0] LCP: Up event
Aug  1 08:31:48 pfgarga ppp: [wan_link0] LCP: state change Starting --> Req-Sent
Aug  1 08:31:48 pfgarga ppp: [wan_link0] LCP: SendConfigReq #1
Aug  1 08:31:48 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 08:31:48 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 08:31:48 pfgarga ppp: [wan_link0]   MAGICNUM 0x5853e378
Aug  1 08:31:49 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Aug  1 08:31:49 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 08:31:49 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 08:31:49 pfgarga ppp: [wan_link0]   MAGICNUM 0xddfd012a
Aug  1 08:31:49 pfgarga ppp: [wan_link0]   MP MRRU 1600
Aug  1 08:31:49 pfgarga ppp: [wan_link0] LCP: SendConfigRej #1
Aug  1 08:31:49 pfgarga ppp: [wan_link0]   MP MRRU 1600
Aug  1 08:31:49 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #2 (Req-Sent)
Aug  1 08:31:49 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 08:31:49 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 08:31:49 pfgarga ppp: [wan_link0]   MAGICNUM 0xddfd012a
Aug  1 08:31:49 pfgarga ppp: [wan_link0] LCP: SendConfigAck #2
Aug  1 08:31:49 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 08:31:49 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 08:31:49 pfgarga ppp: [wan_link0]   MAGICNUM 0xddfd012a
Aug  1 08:31:49 pfgarga ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Aug  1 08:31:50 pfgarga ppp: [wan_link0] LCP: SendConfigReq #2
Aug  1 08:31:50 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 08:31:50 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 08:31:50 pfgarga ppp: [wan_link0]   MAGICNUM 0x5853e378
Aug  1 08:31:50 pfgarga ppp: [wan_link0] LCP: rec'd Configure Reject #2 (Ack-Sent)
Aug  1 08:31:50 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 08:31:50 pfgarga ppp: [wan_link0] LCP: SendConfigReq #3
Aug  1 08:31:50 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 08:31:50 pfgarga ppp: [wan_link0]   MAGICNUM 0x5853e378
Aug  1 08:31:50 pfgarga ppp: [wan_link0] LCP: rec'd Configure Ack #3 (Ack-Sent)
Aug  1 08:31:50 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 08:31:50 pfgarga ppp: [wan_link0]   MAGICNUM 0x5853e378
Aug  1 08:31:50 pfgarga ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
Aug  1 08:31:50 pfgarga ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Aug  1 08:31:50 pfgarga ppp: [wan_link0] LCP: LayerUp
Aug  1 08:31:50 pfgarga ppp: [wan_link0] CHAP: rec'd CHALLENGE #1 len: 56
Aug  1 08:31:50 pfgarga ppp: [wan_link0]   Name: "BRAS Iracemapolis - Telecom InteH^A^\"
Aug  1 08:31:50 pfgarga ppp: [wan_link0] CHAP: Using authname "renato.couto"
Aug  1 08:31:50 pfgarga ppp: [wan_link0] CHAP: sending RESPONSE #1 len: 33
Aug  1 08:31:50 pfgarga ppp: [wan_link0] CHAP: rec'd SUCCESS #1 len: 12
Aug  1 08:31:50 pfgarga ppp: [wan_link0]   MESG: Welcome.
Aug  1 08:31:50 pfgarga ppp: [wan_link0] LCP: authorization successful
Aug  1 08:31:50 pfgarga ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Aug  1 08:31:50 pfgarga ppp: [wan_link0] Link: Join bundle "wan"
Aug  1 08:31:50 pfgarga ppp: [wan] IFACE: setting pppoe0 MTU to 1480 bytes
Aug  1 08:31:50 pfgarga ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: Open event
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: state change Initial --> Starting
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: LayerStart
Aug  1 08:31:50 pfgarga ppp: [wan] IPV6CP: Open event
Aug  1 08:31:50 pfgarga ppp: [wan] IPV6CP: state change Initial --> Starting
Aug  1 08:31:50 pfgarga ppp: [wan] IPV6CP: LayerStart
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: Up event
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: state change Starting --> Req-Sent
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: SendConfigReq #1
Aug  1 08:31:50 pfgarga ppp: [wan]   IPADDR 0.0.0.0
Aug  1 08:31:50 pfgarga ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug  1 08:31:50 pfgarga ppp: [wan] IPV6CP: Up event
Aug  1 08:31:50 pfgarga ppp: [wan] IPV6CP: state change Starting --> Req-Sent
Aug  1 08:31:50 pfgarga ppp: [wan] IPV6CP: SendConfigReq #1
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: rec'd Configure Request #1 (Req-Sent)
Aug  1 08:31:50 pfgarga ppp: [wan]   IPADDR 177.53.80.8
Aug  1 08:31:50 pfgarga ppp: [wan]     177.53.80.8 is OK
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: SendConfigAck #1
Aug  1 08:31:50 pfgarga ppp: [wan]   IPADDR 177.53.80.8
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: state change Req-Sent --> Ack-Sent
Aug  1 08:31:50 pfgarga ppp: [wan_link0] rec'd unexpected protocol MPLS Control Protocol (RFC 3032), rejecting
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Aug  1 08:31:50 pfgarga ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: SendConfigReq #2
Aug  1 08:31:50 pfgarga ppp: [wan]   IPADDR 0.0.0.0
Aug  1 08:31:50 pfgarga ppp: [wan_link0] LCP: rec'd Protocol Reject #3 (Opened)
Aug  1 08:31:50 pfgarga ppp: [wan_link0] LCP: protocol IPV6CP was rejected
Aug  1 08:31:50 pfgarga ppp: [wan] IPV6CP: protocol was rejected by peer
Aug  1 08:31:50 pfgarga ppp: [wan] IPV6CP: state change Req-Sent --> Stopped
Aug  1 08:31:50 pfgarga ppp: [wan] IPV6CP: LayerFinish
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Aug  1 08:31:50 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 08:31:50 pfgarga ppp: [wan]     177.53.86.172 is OK
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: SendConfigReq #3
Aug  1 08:31:50 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Aug  1 08:31:50 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: state change Ack-Sent --> Opened
Aug  1 08:31:50 pfgarga ppp: [wan] IPCP: LayerUp
Aug  1 08:31:50 pfgarga ppp: [wan]   177.53.86.172 -> 177.53.80.8
Aug  1 08:31:50 pfgarga ppp: [wan] IFACE: Connecting tcpmssfix
Aug  1 08:31:50 pfgarga ppp: [wan] IFACE: Add address 177.53.86.172/32->177.53.80.8 to pppoe0
Aug  1 08:31:50 pfgarga ppp: [wan] system: /usr/local/sbin/ppp-linkup pppoe0 inet 177.53.86.172/32 177.53.80.8 '-' '' '' '00:00:00:00:00:00' '-'
Aug  1 08:31:50 pfgarga ppp: [wan] IFACE: Up event
Aug  1 08:31:50 pfgarga ppp: [wan] IFACE: Rename interface ng0 to pppoe0
Aug  1 08:31:50 pfgarga ppp: [wan] IFACE: Change interface pppoe0 flags: -0 +1

# ifconfig pppoe0
pppoe0: flags=88d1<UP,POINTOPOINT,RUNNING,NOARP,SIMPLEX,MULTICAST> metric 0 mtu 1480
    inet 177.53.86.172 --> 177.53.80.8 netmask 0xffffffff
    inet6 fe80::208:a2ff:fe09:5b54%pppoe0 prefixlen 64 scopeid 0xc
    nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>

Eugene Grosbein - 2018-08-01

It is possible that your build environment for net/mpd5 port is unexpected and the binary is built without HAVE_NG_TCPMSS / USE_NG_TCPMSS support and that would be bad. Please show output of "ngctl ls" command for the system running PPPoE client connection that uses new version and has problems. Also, show messages produced by mpd5 port building process starting from the line "===> Configuring for mpd5-5.8_6" and until the line "===> Building for mpd5-5.8_6"

If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
- Renato Botelho - 2018-08-01
  
  Sure. I can see -DUSE_NG_TCPMSS all around during build phase
  
  =======================<phase: configure >============================ ===> Configuring for mpd5-5.8_6 Looking for ng_bpf ... found. Looking for ng_car ... found. Looking for ng_deflate ... found. Looking for ng_ipacct ... not found. Looking for ng_mppc ... found. Looking for ng_nat ... found. Looking for ng_netflow ... found. Looking for ng_pred1 ... found. Looking for ng_tcpmss ... found. Looking for ng_vjc ... found. Looking for ipfw ... found. Looking for rad_bind_to() ... found. Looking for rad_cvt_addr6() ... found. Looking for ether_ntoa_r() ... found. =========================================================================== =======================<phase: build >============================```
  
  At this moment it's working because I set MTU and MRU to 1480 on mpd.conf. Let me know if you need me to force the error before collect ngctl ls output
  
```
$ ngctl ls
There are 25 total nodes:
Name: igb1 Type: ether ID: 00000002 Num hooks: 1
Name: igb2 Type: ether ID: 00000003 Num hooks: 1
Name: <unnamed> Type: socket ID: 00000009 Num hooks: 0
Name: igb0_666 Type: ether ID: 0000000c Num hooks: 0
Name: mpd11854-lso Type: socket ID: 0000028f Num hooks: 1
Name: mpd11854-cso Type: socket ID: 00000290 Num hooks: 0
Name: mpd11854-eso Type: socket ID: 00000291 Num hooks: 0
Name: ng0 Type: iface ID: 00000292 Num hooks: 1
Name: mpd11854-wan Type: ppp ID: 00000293 Num hooks: 3
Name: mpd11854-wan_link0-lt Type: tee ID: 00000294 Num hooks: 2
Name: <unnamed> Type: socket ID: 00000295 Num hooks: 0
Name: mpd11854-stats Type: socket ID: 00000296 Num hooks: 0
Name: mpd11854-wan-mss Type: tcpmss ID: 00000297 Num hooks: 2
Name: <unnamed> Type: pppoe ID: 00000017 Num hooks: 2
Name: <unnamed> Type: pppoe ID: 00000022 Num hooks: 2
Name: ngctl80863 Type: socket ID: 000002a9 Num hooks: 0
Name: mpd57847-lso Type: socket ID: 00000270 Num hooks: 1
Name: mpd57847-cso Type: socket ID: 00000271 Num hooks: 0
Name: mpd57847-eso Type: socket ID: 00000272 Num hooks: 0
Name: ng1 Type: iface ID: 00000273 Num hooks: 1
Name: mpd57847-opt1 Type: ppp ID: 00000274 Num hooks: 3
Name: mpd57847-opt1_link0-lt Type: tee ID: 00000275 Num hooks: 2
Name: <unnamed> Type: socket ID: 00000276 Num hooks: 0
Name: mpd57847-stats Type: socket ID: 00000277 Num hooks: 0
Name: mpd57847-opt1-mss Type: tcpmss ID: 00000278 Num hooks: 2</unnamed></unnamed></unnamed></unnamed></unnamed>

I alsso note this on logs:

Aug 1 08:28:55 pfgarga ppp: [wan_link0] LCP: SendConfigReq #1
Aug 1 08:28:55 pfgarga ppp: [wan_link0] PROTOCOMP
Aug 1 08:28:55 pfgarga ppp: [wan_link0] MRU 1492
Aug 1 08:28:55 pfgarga ppp: [wan_link0] MAGICNUM 0xa8790a74
Aug 1 08:28:56 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Aug 1 08:28:56 pfgarga ppp: [wan_link0] AUTHPROTO CHAP MD5
Aug 1 08:28:56 pfgarga ppp: [wan_link0] MRU 1480
Aug 1 08:28:56 pfgarga ppp: [wan_link0] MAGICNUM 0x9468ac2b
Aug 1 08:28:56 pfgarga ppp: [wan_link0] MP MRRU 1600
```
  
  If I'm reading it right ISP is proposing me to use 1480, no? If it's correct, why it ends up configured as 1492?
  
  Last edit: Renato Botelho 2018-08-01
  
  If you would like to refer to this comment somewhere else in this project, copy and paste the following link:

It seems the binary builds just fine.

The interface ends up configured with mtu=1492 because ISP later agrees with this value by "LCP: rec'd Configure Ack #3" message to mpd's proposal.

Please put attached file to /usr/ports/net/mpd5 directory and rebuild latest version of mpd5, restart it and post the log it produces. No need to to force the error but use latest version.

patch-tcpmss1

Aug  1 14:01:19 pfgarga ppp: [wan] IFACE: Close event
Aug  1 14:01:19 pfgarga ppp: [wan] IPCP: Close event
Aug  1 14:01:19 pfgarga ppp: [wan] IPCP: state change Opened --> Closing
Aug  1 14:01:19 pfgarga ppp: [wan] IPCP: SendTerminateReq #4
Aug  1 14:01:19 pfgarga ppp: [wan] IPCP: LayerDown
Aug  1 14:01:19 pfgarga ppp: [wan] system: /usr/local/sbin/ppp-linkdown pppoe0 inet 177.53.86.172/32 177.53.80.8 '-' '00:00:00:00:00:00' '-'
Aug  1 14:01:19 pfgarga ppp: [wan] IFACE: Remove address 177.53.86.172/32->177.53.80.8 from pppoe0
Aug  1 14:01:19 pfgarga ppp: [wan] IFACE: Down event
Aug  1 14:01:19 pfgarga ppp: [wan] IFACE: Change interface pppoe0 flags: -4097 +0
Aug  1 14:01:19 pfgarga ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0
Aug  1 14:01:19 pfgarga ppp: [wan] IPV6CP: Close event
Aug  1 14:01:19 pfgarga ppp: [wan] IPV6CP: state change Stopped --> Closed
Aug  1 14:01:19 pfgarga ppp: [wan] IPCP: rec'd Terminate Ack #4 (Closing)
Aug  1 14:01:19 pfgarga ppp: [wan] IPCP: state change Closing --> Closed
Aug  1 14:01:19 pfgarga ppp: [wan] IPCP: LayerFinish
Aug  1 14:01:19 pfgarga ppp: [wan] Bundle: No NCPs left. Closing links...
Aug  1 14:01:19 pfgarga ppp: [wan] Bundle: closing link "wan_link0"...
Aug  1 14:01:19 pfgarga ppp: [wan_link0] Link: CLOSE event
Aug  1 14:01:19 pfgarga ppp: [wan_link0] LCP: Close event
Aug  1 14:01:19 pfgarga ppp: [wan_link0] LCP: state change Opened --> Closing
Aug  1 14:01:19 pfgarga ppp: [wan_link0] Link: Leave bundle "wan"
Aug  1 14:01:19 pfgarga ppp: [wan] IFACE: setting pppoe0 MTU to 1500 bytes
Aug  1 14:01:19 pfgarga ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Aug  1 14:01:19 pfgarga ppp: [wan] IPCP: Close event
Aug  1 14:01:19 pfgarga ppp: [wan] IPV6CP: Close event
Aug  1 14:01:19 pfgarga ppp: [wan] IPCP: Down event
Aug  1 14:01:19 pfgarga ppp: [wan] IPCP: state change Closed --> Initial
Aug  1 14:01:19 pfgarga ppp: [wan] IPV6CP: Down event
Aug  1 14:01:19 pfgarga ppp: [wan] IPV6CP: state change Closed --> Initial
Aug  1 14:01:19 pfgarga ppp: [wan_link0] LCP: SendTerminateReq #4
Aug  1 14:01:19 pfgarga ppp: [wan_link0] LCP: LayerDown
Aug  1 14:01:19 pfgarga ppp: [wan_link0] LCP: rec'd Terminate Ack #4 (Closing)
Aug  1 14:01:19 pfgarga ppp: [wan_link0] LCP: state change Closing --> Closed
Aug  1 14:01:19 pfgarga ppp: [wan_link0] LCP: LayerFinish
Aug  1 14:01:19 pfgarga ppp: [wan_link0] Link: DOWN event
Aug  1 14:01:19 pfgarga ppp: [wan_link0] LCP: Down event
Aug  1 14:01:19 pfgarga ppp: [wan_link0] LCP: state change Closed --> Initial
Aug  1 14:01:21 pfgarga ppp: [wan] Bundle: Shutdown
Aug  1 14:01:21 pfgarga ppp: [wan_link0] Link: Shutdown
Aug  1 14:01:22 pfgarga ppp: [wan] Bundle: Interface ng0 created
Aug  1 14:01:22 pfgarga ppp: [wan] IFACE: setting "ng0" name to "pppoe0"
Aug  1 14:01:22 pfgarga ppp: [wan_link0] Link: OPEN event
Aug  1 14:01:22 pfgarga ppp: [wan_link0] LCP: Open event
Aug  1 14:01:22 pfgarga ppp: [wan_link0] LCP: state change Initial --> Starting
Aug  1 14:01:22 pfgarga ppp: [wan_link0] LCP: LayerStart
Aug  1 14:01:22 pfgarga ppp: [wan_link0] PPPoE: Connecting to ''
Aug  1 14:01:22 pfgarga ppp: [wan_link0] PPPoE: connection successful
Aug  1 14:01:22 pfgarga ppp: [wan_link0] Link: UP event
Aug  1 14:01:22 pfgarga ppp: [wan_link0] LCP: Up event
Aug  1 14:01:22 pfgarga ppp: [wan_link0] LCP: state change Starting --> Req-Sent
Aug  1 14:01:22 pfgarga ppp: [wan_link0] LCP: SendConfigReq #1
Aug  1 14:01:22 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 14:01:22 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 14:01:22 pfgarga ppp: [wan_link0]   MAGICNUM 0x34b33b94
Aug  1 14:01:23 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Aug  1 14:01:23 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 14:01:23 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 14:01:23 pfgarga ppp: [wan_link0]   MAGICNUM 0x55d28769
Aug  1 14:01:23 pfgarga ppp: [wan_link0]   MP MRRU 1600
Aug  1 14:01:23 pfgarga ppp: [wan_link0] LCP: SendConfigRej #1
Aug  1 14:01:23 pfgarga ppp: [wan_link0]   MP MRRU 1600
Aug  1 14:01:23 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #2 (Req-Sent)
Aug  1 14:01:23 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 14:01:23 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 14:01:23 pfgarga ppp: [wan_link0]   MAGICNUM 0x55d28769
Aug  1 14:01:23 pfgarga ppp: [wan_link0] LCP: SendConfigAck #2
Aug  1 14:01:23 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 14:01:23 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 14:01:23 pfgarga ppp: [wan_link0]   MAGICNUM 0x55d28769
Aug  1 14:01:23 pfgarga ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Aug  1 14:01:24 pfgarga ppp: [wan_link0] LCP: SendConfigReq #2
Aug  1 14:01:24 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 14:01:24 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 14:01:24 pfgarga ppp: [wan_link0]   MAGICNUM 0x34b33b94
Aug  1 14:01:24 pfgarga ppp: [wan_link0] LCP: rec'd Configure Reject #2 (Ack-Sent)
Aug  1 14:01:24 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 14:01:24 pfgarga ppp: [wan_link0] LCP: SendConfigReq #3
Aug  1 14:01:24 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 14:01:24 pfgarga ppp: [wan_link0]   MAGICNUM 0x34b33b94
Aug  1 14:01:24 pfgarga ppp: [wan_link0] LCP: rec'd Configure Ack #3 (Ack-Sent)
Aug  1 14:01:24 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 14:01:24 pfgarga ppp: [wan_link0]   MAGICNUM 0x34b33b94
Aug  1 14:01:24 pfgarga ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
Aug  1 14:01:24 pfgarga ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Aug  1 14:01:24 pfgarga ppp: [wan_link0] LCP: LayerUp
Aug  1 14:01:24 pfgarga ppp: [wan_link0] CHAP: rec'd CHALLENGE #1 len: 56
Aug  1 14:01:24 pfgarga ppp: [wan_link0]   Name: "BRAS Iracemapolis - Telecom InteH^A^\"
Aug  1 14:01:24 pfgarga ppp: [wan_link0] CHAP: Using authname "renato.couto"
Aug  1 14:01:24 pfgarga ppp: [wan_link0] CHAP: sending RESPONSE #1 len: 33
Aug  1 14:01:24 pfgarga ppp: [wan_link0] CHAP: rec'd SUCCESS #1 len: 12
Aug  1 14:01:24 pfgarga ppp: [wan_link0]   MESG: Welcome.
Aug  1 14:01:24 pfgarga ppp: [wan_link0] LCP: authorization successful
Aug  1 14:01:24 pfgarga ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Aug  1 14:01:24 pfgarga ppp: [wan_link0] Link: Join bundle "wan"
Aug  1 14:01:24 pfgarga ppp: [wan] IFACE: setting pppoe0 MTU to 1480 bytes
Aug  1 14:01:24 pfgarga ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: Open event
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: state change Initial --> Starting
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: LayerStart
Aug  1 14:01:24 pfgarga ppp: [wan] IPV6CP: Open event
Aug  1 14:01:24 pfgarga ppp: [wan] IPV6CP: state change Initial --> Starting
Aug  1 14:01:24 pfgarga ppp: [wan] IPV6CP: LayerStart
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: Up event
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: state change Starting --> Req-Sent
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: SendConfigReq #1
Aug  1 14:01:24 pfgarga ppp: [wan]   IPADDR 0.0.0.0
Aug  1 14:01:24 pfgarga ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug  1 14:01:24 pfgarga ppp: [wan] IPV6CP: Up event
Aug  1 14:01:24 pfgarga ppp: [wan] IPV6CP: state change Starting --> Req-Sent
Aug  1 14:01:24 pfgarga ppp: [wan] IPV6CP: SendConfigReq #1
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: rec'd Configure Request #1 (Req-Sent)
Aug  1 14:01:24 pfgarga ppp: [wan]   IPADDR 177.53.80.8
Aug  1 14:01:24 pfgarga ppp: [wan]     177.53.80.8 is OK
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: SendConfigAck #1
Aug  1 14:01:24 pfgarga ppp: [wan]   IPADDR 177.53.80.8
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: state change Req-Sent --> Ack-Sent
Aug  1 14:01:24 pfgarga ppp: [wan_link0] rec'd unexpected protocol MPLS Control Protocol (RFC 3032), rejecting
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Aug  1 14:01:24 pfgarga ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: SendConfigReq #2
Aug  1 14:01:24 pfgarga ppp: [wan]   IPADDR 0.0.0.0
Aug  1 14:01:24 pfgarga ppp: [wan_link0] LCP: rec'd Protocol Reject #3 (Opened)
Aug  1 14:01:24 pfgarga ppp: [wan_link0] LCP: protocol IPV6CP was rejected
Aug  1 14:01:24 pfgarga ppp: [wan] IPV6CP: protocol was rejected by peer
Aug  1 14:01:24 pfgarga ppp: [wan] IPV6CP: state change Req-Sent --> Stopped
Aug  1 14:01:24 pfgarga ppp: [wan] IPV6CP: LayerFinish
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Aug  1 14:01:24 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 14:01:24 pfgarga ppp: [wan]     177.53.86.172 is OK
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: SendConfigReq #3
Aug  1 14:01:24 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Aug  1 14:01:24 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: state change Ack-Sent --> Opened
Aug  1 14:01:24 pfgarga ppp: [wan] IPCP: LayerUp
Aug  1 14:01:24 pfgarga ppp: [wan]   177.53.86.172 -> 177.53.80.8
Aug  1 14:01:24 pfgarga ppp: [wan] IFACE: Connecting tcpmssfix
Aug  1 14:01:24 pfgarga ppp: [wan] IFACE: Configuring tcpmssfix mpd30362-wan-mss:: 1440
Aug  1 14:01:24 pfgarga ppp: [wan] IFACE: Add address 177.53.86.172/32->177.53.80.8 to pppoe0
Aug  1 14:01:24 pfgarga ppp: [wan] system: /usr/local/sbin/ppp-linkup pppoe0 inet 177.53.86.172/32 177.53.80.8 '-' '' '' '00:00:00:00:00:00' '-'
Aug  1 14:01:24 pfgarga ppp: [wan] IFACE: Up event
Aug  1 14:01:24 pfgarga ppp: [wan] IFACE: Rename interface ng0 to pppoe0
Aug  1 14:01:24 pfgarga ppp: [wan] IFACE: Change interface pppoe0 flags: -0 +1

That's just fine and expected: "IFACE: Configuring tcpmssfix" line shows value lesser than interface MTU by exactly 40 bytes. Now please comment out all "mtu/mru" lines from the mpd.conf and re-establish PPPoE and post the log again and output of "ifconfig pppoe0" too.

Last edit: Eugene Grosbein 2018-08-01

Aug  1 14:14:40 pfgarga ppp: [wan] IFACE: Close event
Aug  1 14:14:40 pfgarga ppp: [wan] IPCP: Close event
Aug  1 14:14:40 pfgarga ppp: [wan] IPCP: state change Opened --> Closing
Aug  1 14:14:40 pfgarga ppp: [wan] IPCP: SendTerminateReq #4
Aug  1 14:14:40 pfgarga ppp: [wan] IPCP: LayerDown
Aug  1 14:14:40 pfgarga ppp: [wan] system: /usr/local/sbin/ppp-linkdown pppoe0 inet 177.53.86.172/32 177.53.80.8 '-' '00:00:00:00:00:00' '-'
Aug  1 14:14:41 pfgarga ppp: [wan] IFACE: Remove address 177.53.86.172/32->177.53.80.8 from pppoe0
Aug  1 14:14:41 pfgarga ppp: [wan] IFACE: Down event
Aug  1 14:14:41 pfgarga ppp: [wan] IFACE: Change interface pppoe0 flags: -4097 +0
Aug  1 14:14:41 pfgarga ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0
Aug  1 14:14:41 pfgarga ppp: [wan] IPV6CP: Close event
Aug  1 14:14:41 pfgarga ppp: [wan] IPV6CP: state change Stopped --> Closed
Aug  1 14:14:41 pfgarga ppp: [wan] IPCP: rec'd Terminate Ack #4 (Closing)
Aug  1 14:14:41 pfgarga ppp: [wan] IPCP: state change Closing --> Closed
Aug  1 14:14:41 pfgarga ppp: [wan] IPCP: LayerFinish
Aug  1 14:14:41 pfgarga ppp: [wan] Bundle: No NCPs left. Closing links...
Aug  1 14:14:41 pfgarga ppp: [wan] Bundle: closing link "wan_link0"...
Aug  1 14:14:41 pfgarga ppp: [wan_link0] Link: CLOSE event
Aug  1 14:14:41 pfgarga ppp: [wan_link0] LCP: Close event
Aug  1 14:14:41 pfgarga ppp: [wan_link0] LCP: state change Opened --> Closing
Aug  1 14:14:41 pfgarga ppp: [wan_link0] Link: Leave bundle "wan"
Aug  1 14:14:41 pfgarga ppp: [wan] IFACE: setting pppoe0 MTU to 1500 bytes
Aug  1 14:14:41 pfgarga ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Aug  1 14:14:41 pfgarga ppp: [wan] IPCP: Close event
Aug  1 14:14:41 pfgarga ppp: [wan] IPV6CP: Close event
Aug  1 14:14:41 pfgarga ppp: [wan] IPCP: Down event
Aug  1 14:14:41 pfgarga ppp: [wan] IPCP: state change Closed --> Initial
Aug  1 14:14:41 pfgarga ppp: [wan] IPV6CP: Down event
Aug  1 14:14:41 pfgarga ppp: [wan] IPV6CP: state change Closed --> Initial
Aug  1 14:14:41 pfgarga ppp: [wan_link0] LCP: SendTerminateReq #4
Aug  1 14:14:41 pfgarga ppp: [wan_link0] LCP: LayerDown
Aug  1 14:14:41 pfgarga ppp: [wan_link0] LCP: rec'd Terminate Ack #4 (Closing)
Aug  1 14:14:41 pfgarga ppp: [wan_link0] LCP: state change Closing --> Closed
Aug  1 14:14:41 pfgarga ppp: [wan_link0] LCP: LayerFinish
Aug  1 14:14:41 pfgarga ppp: [wan_link0] Link: DOWN event
Aug  1 14:14:41 pfgarga ppp: [wan_link0] LCP: Down event
Aug  1 14:14:41 pfgarga ppp: [wan_link0] LCP: state change Closed --> Initial
Aug  1 14:14:43 pfgarga ppp: [wan] Bundle: Shutdown
Aug  1 14:14:43 pfgarga ppp: [wan_link0] Link: Shutdown
Aug  1 14:14:43 pfgarga ppp: [wan] Bundle: Interface ng0 created
Aug  1 14:14:43 pfgarga ppp: [wan] IFACE: setting "ng0" name to "pppoe0"
Aug  1 14:14:43 pfgarga ppp: [wan_link0] Link: OPEN event
Aug  1 14:14:43 pfgarga ppp: [wan_link0] LCP: Open event
Aug  1 14:14:43 pfgarga ppp: [wan_link0] LCP: state change Initial --> Starting
Aug  1 14:14:43 pfgarga ppp: [wan_link0] LCP: LayerStart
Aug  1 14:14:43 pfgarga ppp: [wan_link0] PPPoE: Connecting to ''
Aug  1 14:14:43 pfgarga ppp: [wan_link0] PPPoE: connection successful
Aug  1 14:14:43 pfgarga ppp: [wan_link0] Link: UP event
Aug  1 14:14:43 pfgarga ppp: [wan_link0] LCP: Up event
Aug  1 14:14:43 pfgarga ppp: [wan_link0] LCP: state change Starting --> Req-Sent
Aug  1 14:14:43 pfgarga ppp: [wan_link0] LCP: SendConfigReq #1
Aug  1 14:14:43 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 14:14:43 pfgarga ppp: [wan_link0]   MRU 1492
Aug  1 14:14:43 pfgarga ppp: [wan_link0]   MAGICNUM 0x214257dc
Aug  1 14:14:44 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Aug  1 14:14:44 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 14:14:44 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 14:14:44 pfgarga ppp: [wan_link0]   MAGICNUM 0x2193f73f
Aug  1 14:14:44 pfgarga ppp: [wan_link0]   MP MRRU 1600
Aug  1 14:14:44 pfgarga ppp: [wan_link0] LCP: SendConfigRej #1
Aug  1 14:14:44 pfgarga ppp: [wan_link0]   MP MRRU 1600
Aug  1 14:14:44 pfgarga ppp: [wan_link0] LCP: rec'd Configure Request #2 (Req-Sent)
Aug  1 14:14:44 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 14:14:44 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 14:14:44 pfgarga ppp: [wan_link0]   MAGICNUM 0x2193f73f
Aug  1 14:14:44 pfgarga ppp: [wan_link0] LCP: SendConfigAck #2
Aug  1 14:14:44 pfgarga ppp: [wan_link0]   AUTHPROTO CHAP MD5
Aug  1 14:14:44 pfgarga ppp: [wan_link0]   MRU 1480
Aug  1 14:14:44 pfgarga ppp: [wan_link0]   MAGICNUM 0x2193f73f
Aug  1 14:14:44 pfgarga ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Aug  1 14:14:45 pfgarga ppp: [wan_link0] LCP: SendConfigReq #2
Aug  1 14:14:45 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 14:14:45 pfgarga ppp: [wan_link0]   MRU 1492
Aug  1 14:14:45 pfgarga ppp: [wan_link0]   MAGICNUM 0x214257dc
Aug  1 14:14:45 pfgarga ppp: [wan_link0] LCP: rec'd Configure Reject #2 (Ack-Sent)
Aug  1 14:14:45 pfgarga ppp: [wan_link0]   PROTOCOMP
Aug  1 14:14:45 pfgarga ppp: [wan_link0] LCP: SendConfigReq #3
Aug  1 14:14:45 pfgarga ppp: [wan_link0]   MRU 1492
Aug  1 14:14:45 pfgarga ppp: [wan_link0]   MAGICNUM 0x214257dc
Aug  1 14:14:45 pfgarga ppp: [wan_link0] LCP: rec'd Configure Ack #3 (Ack-Sent)
Aug  1 14:14:45 pfgarga ppp: [wan_link0]   MRU 1492
Aug  1 14:14:45 pfgarga ppp: [wan_link0]   MAGICNUM 0x214257dc
Aug  1 14:14:45 pfgarga ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
Aug  1 14:14:45 pfgarga ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Aug  1 14:14:45 pfgarga ppp: [wan_link0] LCP: LayerUp
Aug  1 14:14:45 pfgarga ppp: [wan_link0] CHAP: rec'd CHALLENGE #1 len: 56
Aug  1 14:14:45 pfgarga ppp: [wan_link0]   Name: "BRAS Iracemapolis - Telecom InteH^A^\"
Aug  1 14:14:45 pfgarga ppp: [wan_link0] CHAP: Using authname "renato.couto"
Aug  1 14:14:45 pfgarga ppp: [wan_link0] CHAP: sending RESPONSE #1 len: 33
Aug  1 14:14:45 pfgarga ppp: [wan_link0] CHAP: rec'd SUCCESS #1 len: 12
Aug  1 14:14:45 pfgarga ppp: [wan_link0]   MESG: Welcome.
Aug  1 14:14:45 pfgarga ppp: [wan_link0] LCP: authorization successful
Aug  1 14:14:45 pfgarga ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Aug  1 14:14:45 pfgarga ppp: [wan_link0] Link: Join bundle "wan"
Aug  1 14:14:45 pfgarga ppp: [wan] IFACE: setting pppoe0 MTU to 1492 bytes
Aug  1 14:14:45 pfgarga ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: Open event
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: state change Initial --> Starting
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: LayerStart
Aug  1 14:14:45 pfgarga ppp: [wan] IPV6CP: Open event
Aug  1 14:14:45 pfgarga ppp: [wan] IPV6CP: state change Initial --> Starting
Aug  1 14:14:45 pfgarga ppp: [wan] IPV6CP: LayerStart
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: Up event
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: state change Starting --> Req-Sent
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: SendConfigReq #1
Aug  1 14:14:45 pfgarga ppp: [wan]   IPADDR 0.0.0.0
Aug  1 14:14:45 pfgarga ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug  1 14:14:45 pfgarga ppp: [wan] IPV6CP: Up event
Aug  1 14:14:45 pfgarga ppp: [wan] IPV6CP: state change Starting --> Req-Sent
Aug  1 14:14:45 pfgarga ppp: [wan] IPV6CP: SendConfigReq #1
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: rec'd Configure Request #1 (Req-Sent)
Aug  1 14:14:45 pfgarga ppp: [wan]   IPADDR 177.53.80.8
Aug  1 14:14:45 pfgarga ppp: [wan]     177.53.80.8 is OK
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: SendConfigAck #1
Aug  1 14:14:45 pfgarga ppp: [wan]   IPADDR 177.53.80.8
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: state change Req-Sent --> Ack-Sent
Aug  1 14:14:45 pfgarga ppp: [wan_link0] rec'd unexpected protocol MPLS Control Protocol (RFC 3032), rejecting
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Aug  1 14:14:45 pfgarga ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: SendConfigReq #2
Aug  1 14:14:45 pfgarga ppp: [wan]   IPADDR 0.0.0.0
Aug  1 14:14:45 pfgarga ppp: [wan_link0] LCP: rec'd Protocol Reject #3 (Opened)
Aug  1 14:14:45 pfgarga ppp: [wan_link0] LCP: protocol IPV6CP was rejected
Aug  1 14:14:45 pfgarga ppp: [wan] IPV6CP: protocol was rejected by peer
Aug  1 14:14:45 pfgarga ppp: [wan] IPV6CP: state change Req-Sent --> Stopped
Aug  1 14:14:45 pfgarga ppp: [wan] IPV6CP: LayerFinish
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Aug  1 14:14:45 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 14:14:45 pfgarga ppp: [wan]     177.53.86.172 is OK
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: SendConfigReq #3
Aug  1 14:14:45 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Aug  1 14:14:45 pfgarga ppp: [wan]   IPADDR 177.53.86.172
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: state change Ack-Sent --> Opened
Aug  1 14:14:45 pfgarga ppp: [wan] IPCP: LayerUp
Aug  1 14:14:45 pfgarga ppp: [wan]   177.53.86.172 -> 177.53.80.8
Aug  1 14:14:45 pfgarga ppp: [wan] IFACE: Connecting tcpmssfix
Aug  1 14:14:45 pfgarga ppp: [wan] IFACE: Configuring tcpmssfix mpd4417-wan-mss:: 1452
Aug  1 14:14:45 pfgarga ppp: [wan] IFACE: Add address 177.53.86.172/32->177.53.80.8 to pppoe0
Aug  1 14:14:45 pfgarga ppp: [wan] system: /usr/local/sbin/ppp-linkup pppoe0 inet 177.53.86.172/32 177.53.80.8 '-' '' '' '00:00:00:00:00:00' '-'
Aug  1 14:14:45 pfgarga ppp: [wan] IFACE: Up event
Aug  1 14:14:45 pfgarga ppp: [wan] IFACE: Rename interface ng0 to pppoe0
Aug  1 14:14:45 pfgarga ppp: [wan] IFACE: Change interface pppoe0 flags: -0 +1

pppoe0: flags=88d1<UP,POINTOPOINT,RUNNING,NOARP,SIMPLEX,MULTICAST> metric 0 mtu 1492
    inet 177.53.86.172 --> 177.53.80.8 netmask 0xffffffff
    inet6 fe80::208:a2ff:fe09:5b54%pppoe0 prefixlen 64 scopeid 0xc
    nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>

Eugene Grosbein - 2018-08-01

This is fine too and no connectivity problems should occur. Does it work if you try to run the following command?

ping -D -s 1464 177.53.80.8

If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
- Renato Botelho - 2018-08-01
  
  ping works as expected.
  
  The connectivity problem I face in this case is not for all hosts I try to connect but for some specific ones. For example, if I try to access https://www.ubnt.com it fails like this until timeout:
  
  ❯ curl -v https://www.ubnt.com/ * Trying 52.41.83.111... * TCP_NODELAY set * Connected to www.ubnt.com (52.41.83.111) port 443 (#0) * ALPN, offering h2 * ALPN, offering http/1.1 * Cipher selection: ALL:!EXPORT:!EXPORT40:!EXPORT56:!aNULL:!LOW:!RC4:@STRENGTH * successfully set certificate verify locations: * CAfile: /etc/ssl/cert.pem CApath: none * TLSv1.2 (OUT), TLS handshake, Client hello (1):
  
  If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
  - Eugene Grosbein - 2018-08-01
    
    Please create an attachment with captured outgoing TCP traffic (pcap file) for such connection.
    
    If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
    - Renato Botelho - 2018-08-01
      
      I waited a few time and then pressed ctrl+C on curl command. Let me know if you want it to go until timeout
      
      test-ubnt.pcap
      
      If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
      - Eugene Grosbein - 2018-08-01
        
        That's enough. The very first TCP SYN segment has option MSS=1460 that is more than value in the line of the log "IFACE: Configuring tcpmssfix mpd4417-wan-mss:: 1452" and this is the reason of timeouts. It must not exceed 1452 for this configuration.
        
        MSS=1460 corresponds to MTU=1500 and/or non-functioning tcpmssfix but the log assures us that tcpmss is configured properly. And ping assures us that mpd5 configured PPPoE connection properly too.
        
        There should be something in your local setup making bad interference that breaks MSS fix. Do you have any kind of packet filtering or "sanitizing" like pf(4) configured? If so, could you please disable it completely for a test?
        
        And comment out "set iface up-script /usr/local/sbin/ppp-linkup" in the mpd.conf, if possible, too. If not, post its contents.
        
        I'll get some sleep and will back to the case several hours later.
        
        Last edit: Eugene Grosbein 2018-08-01
        
        If you would like to refer to this comment somewhere else in this project, copy and paste the following link:

Yes, I have pf running, it's a pfSense. I tried after do a pfctl -d to disable it and the problem persisted.

Here is ppp-linkup script:

#!/bin/sh
#
# ppp-linkup
#
# part of pfSense (https://www.pfsense.org)
# Copyright (c) 2004-2018 Rubicon Communications, LLC (Netgate)
# All rights reserved.
#
# Licensed under the Apache License, Version 2.0 (the "License");
# you may not use this file except in compliance with the License.
# You may obtain a copy of the License at
#
# http://www.apache.org/licenses/LICENSE-2.0
#
# Unless required by applicable law or agreed to in writing, software
# distributed under the License is distributed on an "AS IS" BASIS,
# WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
# See the License for the specific language governing permissions and
# limitations under the License.

export PATH=/bin:/usr/bin:/usr/local/bin:/sbin:/usr/sbin:/usr/local/sbin

DNSALLOWOVERRIDE=$(/usr/local/sbin/read_xml_tag.sh boolean system/dnsallowoverride)

IF="${1}"
PROTOCOL="${2}"
LOCAL_IP="${3}"
REMOTE_IP="${4}"
AUTH_NAME="${5}"
DNS1_RAW="${6}"
DNS2_RAW="${7}"

if [ "${PROTOCOL}" == "inet" ]; then

    OLD_ROUTER=`cat /tmp/${IF}_router`
    if [ -n "${OLD_ROUTER}" ]; then
        echo "Removing states to old router ${OLD_ROUTER}" | logger -t ppp-linkup
        pfctl -i ${IF} -k 0.0.0.0/0 -k ${OLD_ROUTER}/32
        pfctl -i ${IF} -k ${OLD_ROUTER}/32 -k 0.0.0.0/0
    fi

    # let the configuration system know that the ipv4 has changed.
    echo ${REMOTE_IP} > /tmp/${IF}_router
    echo ${LOCAL_IP} > /tmp/${IF}_ip
    touch /tmp/${IF}up

    if [ "${DNSALLOWOVERRIDE}" = "true" ]; then
        # write nameservers to file
        echo -n "" > /var/etc/nameserver_${IF}
        if echo "${DNS1_RAW}" | grep -q dns1; then
            DNS1=`echo "${DNS1_RAW}" | awk '{print $2}'`
            echo "${DNS1}" >> /var/etc/nameserver_${IF}
            route change "${DNS1}" ${REMOTE_IP} \
                || route add "${DNS1}" ${REMOTE_IP}
        fi
        if echo "${DNS2_RAW}" | grep -q dns2; then
            DNS2=`echo "${DNS2_RAW}" | awk '{print $2}'`
            echo "${DNS2}" >> /var/etc/nameserver_${IF}
            route change "${DNS2}" ${REMOTE_IP} \
                || route add "${DNS2}" ${REMOTE_IP}
        fi
        pfSctl -c 'service reload dns'
        sleep 1
    fi
    pfSctl -c "interface newip ${IF}"

elif [ "${PROTOCOL}" == "inet6" ]; then
    /usr/local/sbin/ppp-ipv6 ${IF} up
    # let the configuration system know that the ipv6 has changed.
    echo ${REMOTE_IP} |cut -d% -f1 > /tmp/${IF}_routerv6
    echo ${LOCAL_IP} |cut -d% -f1 > /tmp/${IF}_ipv6
    touch /tmp/${IF}upv6

    if [ "${DNSALLOWOVERRIDE}" = "true" ]; then
        # write nameservers to file
        echo -n "" > /var/etc/nameserver_v6${IF}
        if echo "${DNS1_RAW}" | grep -q dns1; then
            DNS1=`echo "${DNS1_RAW}" | awk '{print $2}'`
            echo "${DNS1}" >> /var/etc/nameserver_v6${IF}
            route change -inet6 "${DNS1}" ${REMOTE_IP} \
                || route add -inet6 "${DNS1}" ${REMOTE_IP}
        fi
        if echo "${DNS2_RAW}" | grep -q dns2; then
            DNS2=`echo "${DNS2_RAW}" | awk '{print $2}'`
            echo "${DNS2}" >> /var/etc/nameserver_v6${IF}
            route change -inet6 "${DNS2}" ${REMOTE_IP} \
                || route add -inet6 "${DNS2}" ${REMOTE_IP}
        fi
        pfSctl -c 'service reload dns'
        sleep 1
    fi
    pfSctl -c "interface newipv6 ${IF}"
fi

exit 0

Eugene Grosbein - 2018-08-02

An hour in the morning is worth two in the evening. I was wrong in my pcap-file analysis: packet capture on virtual PPPoE interface shows traffic before MSS fix obtains it and does its job. Please repeat same test but this time capture traffic using underlying interface corresponding physical NIC like this:

tcpdump -nps0 -w file.pcap -i igb0

If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
- Renato Botelho - 2018-08-02
  
  Here it is, and it also has MSS=1460
  
  capture_igb0.pcap
  
  If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
  - Eugene Grosbein - 2018-08-02
    
    It seems you have used wrong interface as the file contains plain IPoE frames with internal IP addresses instead of PPPoE frames running over NIC corresponding to WAN interface. Please double-check and re-do using external NIC.
    
    If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
    - Renato Botelho - 2018-08-02
      
      You are right and I need more coffee. Attached you will find a capture on igb1, the right interface. I couldn't filter it on tcpdump, the target IP is 52.34.248.163
      
      capture_igb1.pcap
      
      If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
      - Eugene Grosbein - 2018-08-02
        
        This capture's packet #37 confirms that tcpmssfix works as expected changing original 1460 to 1452 (1492-40)
        
        If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
    - Renato Botelho - 2018-08-02
      
      Just a note, If I add the following scrub rule to my pf.conf it makes the connection to work fine again:
      
      scrub on pppoe0 all max-mss 1440 fragment reassemble
      
      If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
      - Eugene Grosbein - 2018-08-02
        
        What happens if you use 1441 here instead if 1440?
        
        If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
        
        Renato Botelho - 2018-08-02
        
        It doesn't work anymore
        
        If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
- Renato Botelho - 2018-08-02
  
  It's interesting to note that MSS is also 1460 when MTU is configured to 1480 and connection works as you can see on attached capture
  
  capture_igb0_3.pcap
  
  If you would like to refer to this comment somewhere else in this project, copy and paste the following link:

Conectivity issues after #62

FreeBSD Multi PPP daemon

Group

Searches

Help

#63 Conectivity issues after #62

Discussion