Guest User!

You are not Sophos Staff.

[CONFIRMED] [7.380] kernel_mode=1 pppoe all protocol internet access issue

22796 ?    S    0:00 0:00 /usr/sbin/pppd-pppoe call REF_JwTJmufHDn ipparam eth3#REF_JwTJmufHDn plugin /usr/sbin/rp-pppoe.so eth3

Interesting...  My experience...  No so good.

WITH KERNEL MODE ON (TRAFFIC FAILING)
=====================================

2008:12:28-15:26:37 gateway pppoe-sh: Access-Concentrator: mpls-dsl-gw41.mpls.qwest.net Got a cookie: 84 18 0f b4 4b 3c 1a 52 d3 73 28 c1 d2 26 aa 50 AC-Ethernet-Address: 00:1e:4a:74:f4:48 --------------------------------------------------
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: Plugin /usr/sbin/rp-pppoe.so loaded.
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: RP-PPPoE plugin version 3.3 compiled against pppd 2.4.3
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: pppd 2.4.3 started by root, uid 0
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: PADS: Service-Name: ''
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: PPP session is 1192
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: using channel 2
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: Using interface ppp0
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: Connect: ppp0  eth3
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: Couldn't increase MTU to 1500
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: Couldn't increase MRU to 1500
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: sent [LCP ConfReq id=0x1  ]
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: rcvd [LCP ConfNak id=0x1 ]
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: sent [LCP ConfReq id=0x2  ]
2008:12:28-15:26:37 gateway pppd-pppoe[5977]: rcvd [LCP ConfAck id=0x2  ]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: rcvd [LCP ConfReq id=0x2   ]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: sent [LCP ConfAck id=0x2   ]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: sent [LCP EchoReq id=0x0 magic=0x5027492d]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: sent [PAP AuthReq id=0x1 user="" password=]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: rcvd [LCP EchoRep id=0x0 magic=0x2ae33788]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: rcvd [PAP AuthAck id=0x1 ""]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: PAP authentication succeeded
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: peer from calling number 00:1E:4A:74:F4:48 authorized
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: sent [CCP ConfReq id=0x1 ]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: sent [IPCP ConfReq id=0x1   ]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: rcvd [IPCP ConfReq id=0x1 ]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: sent [IPCP ConfAck id=0x1 ]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: rcvd [LCP ProtRej id=0x3 80 fd 01 01 00 0c 12 06 00 00 00 01]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: rcvd [IPCP ConfNak id=0x1   ]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: sent [IPCP ConfReq id=0x2   ]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: rcvd [IPCP ConfAck id=0x2   ]
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: local  IP address 63.224.17.30
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: remote IP address 207.225.140.233
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: primary   DNS address 205.171.3.65
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: secondary DNS address 205.171.2.65
2008:12:28-15:26:39 gateway pppd-pppoe[5977]: Script /etc/ppp/ip-up started (pid 6067)
2008:12:28-15:26:40 gateway pppd-pppoe[5977]: Script /etc/ppp/ip-up finished (pid 6067), status = 0x0
2008:12:28-15:33:17 gateway pppd-pppoe[5977]: Hangup (SIGHUP)
2008:12:28-15:33:17 gateway pppd-pppoe[5977]: Connect time 6.7 minutes.
2008:12:28-15:33:17 gateway pppd-pppoe[5977]: Sent 62282 bytes, received 130293 bytes.
2008:12:28-15:33:17 gateway pppd-pppoe[5977]: Script /etc/ppp/ip-down started (pid 6581)
2008:12:28-15:33:17 gateway pppd-pppoe[5977]: Couldn't increase MTU to 1500
2008:12:28-15:33:17 gateway pppd-pppoe[5977]: Couldn't increase MRU to 1500
2008:12:28-15:33:17 gateway pppd-pppoe[5977]: sent [LCP TermReq id=0x3 "User request"]
2008:12:28-15:33:17 gateway pppd-pppoe[5977]: rcvd [LCP TermAck id=0x3]
2008:12:28-15:33:17 gateway pppd-pppoe[5977]: Connection terminated.
2008:12:28-15:33:17 gateway pppd-pppoe[5977]: Waiting for 1 child processes...
2008:12:28-15:33:17 gateway pppd-pppoe[5977]:   script /etc/ppp/ip-down, pid 6581
2008:12:28-15:33:18 gateway pppd-pppoe[5977]: Script /etc/ppp/ip-down finished (pid 6581), status = 0x0
2008:12:28-15:33:18 gateway pppd-pppoe[5977]: Exit.


WITH KERNEL MODE OFF (TRAFFIC PASSING)
======================================

2008:12:28-15:33:24 gateway pppoe-sh: Access-Concentrator: mpls-dsl-gw41.mpls.qwest.net Got a cookie: 84 18 0f b4 4b 3c 1a 52 d3 73 28 c1 d2 26 aa 50 AC-Ethernet-Address: 00:1e:4a:74:f4:48 --------------------------------------------------
2008:12:28-15:33:24 gateway pppd-pppoe[6859]: pppd 2.4.3 started by root, uid 0
2008:12:28-15:33:24 gateway pppd-pppoe[6859]: using channel 3
2008:12:28-15:33:24 gateway pppd-pppoe[6859]: Using interface ppp0
2008:12:28-15:33:24 gateway pppd-pppoe[6859]: Connect: ppp0  /dev/ttyp0
2008:12:28-15:33:24 gateway pppoe[6860]: PADS: Service-Name: ''
2008:12:28-15:33:24 gateway pppoe[6860]: PPP session is 2794 (0xaea)
2008:12:28-15:33:25 gateway pppd-pppoe[6859]: sent [LCP ConfReq id=0x1     ]
2008:12:28-15:33:25 gateway pppd-pppoe[6859]: rcvd [LCP ConfNak id=0x1 ]
2008:12:28-15:33:25 gateway pppd-pppoe[6859]: sent [LCP ConfReq id=0x2     ]
2008:12:28-15:33:25 gateway pppd-pppoe[6859]: rcvd [LCP ConfAck id=0x2     ]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: rcvd [LCP ConfReq id=0x2   ]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: sent [LCP ConfAck id=0x2   ]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: sent [LCP EchoReq id=0x0 magic=0xdb0338b0]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: sent [PAP AuthReq id=0x1 user="" password=]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: rcvd [LCP EchoRep id=0x0 magic=0x2ae97a67]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: rcvd [PAP AuthAck id=0x1 ""]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: PAP authentication succeeded
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: sent [CCP ConfReq id=0x1    ]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: sent [IPCP ConfReq id=0x1    ]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: rcvd [IPCP ConfReq id=0x1 ]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: sent [IPCP ConfAck id=0x1 ]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: rcvd [LCP ProtRej id=0x3 80 fd 01 01 00 17 12 06 00 00 00 01 1a 04 78 00 18 04 78 00 15 03 2f]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: rcvd [IPCP ConfRej id=0x1 ]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: sent [IPCP ConfReq id=0x2   ]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: rcvd [IPCP ConfNak id=0x2   ]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: sent [IPCP ConfReq id=0x3   ]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: rcvd [IPCP ConfAck id=0x3   ]
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: local  IP address 63.224.17.30
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: remote IP address 207.225.140.233
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: primary   DNS address 205.171.3.65
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: secondary DNS address 205.171.2.65
2008:12:28-15:33:26 gateway pppd-pppoe[6859]: Script /etc/ppp/ip-up started (pid 6936)
2008:12:28-15:33:27 gateway pppd-pppoe[6859]: Script /etc/ppp/ip-up finished (pid 6936), status = 0x0
2008:12:28-15:38:02 gateway pppoe[6860]: Received signal 1 on session 2794.
2008:12:28-15:38:02 gateway pppoe[6860]: Sent PADT
2008:12:28-15:38:02 gateway pppd-pppoe[6859]: Hangup (SIGHUP)
2008:12:28-15:38:02 gateway pppd-pppoe[6859]: Modem hangup
2008:12:28-15:38:02 gateway pppd-pppoe[6859]: Connect time 4.6 minutes.
2008:12:28-15:38:02 gateway pppd-pppoe[6859]: Sent 89750 bytes, received 637687 bytes.
2008:12:28-15:38:03 gateway pppd-pppoe[6859]: Script /etc/ppp/ip-down started (pid 7584)
2008:12:28-15:38:03 gateway pppd-pppoe[6859]: Connection terminated.
2008:12:28-15:38:03 gateway pppd-pppoe[6859]: Script /usr/sbin/pppoe -I eth3 -m 1414 finished (pid 6860), status = 0x0
2008:12:28-15:38:03 gateway pppd-pppoe[6859]: Waiting for 1 child processes...
2008:12:28-15:38:03 gateway pppd-pppoe[6859]:   script /etc/ppp/ip-down, pid 7584
2008:12:28-15:38:04 gateway pppd-pppoe[6859]: Script /etc/ppp/ip-down finished (pid 7584), status = 0x0
2008:12:28-15:38:04 gateway pppd-pppoe[6859]: Exit.

PLEASE CONTINUE TO NEXT POST:
Parents
  • A few notes (I have switched back and forth many times and the logs are identical each time as well as the issue):

    kernel_mode=1 seems to attempt to increase the MTU to 1500 whereas I have set (in the GUI) 1454 (PPPoE optimized), with kernel_mode=0 the daemon seems to pick up on that setting.

    Web access when in kernel mode seems to only allow me to get to a limited number of sites, qwest and google.  I have the proxy server in transparent with all networks bypassing it.

    In fact I have rebooted the ASG and made this change many times now, it is like clockwork.   "kernel_mode=1" then issue "write" all fails "kernel_mode=0" then "write" all is well.

    All nslookups pass but I cannot access the sites (microsoft.com, astaro.com/org and pretty much all others) in kernel mode.  I use the word "sites" however in further testing it affects all traffic tested, HTTP, HTTPS, IMAP, IMAPS, SMTP, SMTPS, etc.

    If you would like any specific logs let me know as I can post others as well.

    This is an odd issue I didn't know could really exist.el mode seems to only allow me to get to a limited number of sites, qwest and google.  I have the proxy server in transparent with all networks bypassing it.

    If you would like any specific logs let me know as I can post others as well.

    This is an odd issue I didn't know could really exist.
  • You are right, the netfilter TCPMSS target rule is in the wrong chain.
    Can insert the netfilter rule below after the pppoe connect and verify,
    that this works?

    Use this command on the console:
    /usr/sbin/iptables -t mangle -I FORWARD -o ppp0 -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu
  • That is exactly what the issue is.

    Instantly after applying this rule traffic flowed without issue.

    I have tested this a few times now and all is well here after a reboot and applying this rule.
  • FYI, the fix doesn't appear to have made it into 7.385 however I have a feeling that 7.385 isn't a really new build compared to what you are running in-house with multiple builds a day.

    Is there a changelog for specific items, configs or events to test with 7.385?
Reply Children