[Openswan Users] L2TP & MTU Problem?

[Dennis Leist]

Hi all,

I have troubles while connecting with l2tpd .
Server: SuSE 9.1; kernel 2.6; openswan.2.1.4; l2tpd-0.69-8jdl
Client: W2k SP4 completley patched (as possible ;-) ) even NAT-T-Patch 
installed.

Why does the client only responds with Naks?

THX for any help
Dennis
<snip>
Jul 23 03:09:38 zeus l2tpd[27339]: control_finish: Connection 
established to 213.39.113.25, 1701.  Local: 49756, Remote: 18.  LNS sessi
on is 'default'
Jul 23 03:09:38 zeus l2tpd[27339]: check_control: control, cid = 0, Ns = 
2, Nr = 1
Jul 23 03:09:38 zeus l2tpd[27339]: handle_avps: handling avp's for 
tunnel 49756, call 27450
Jul 23 03:09:38 zeus l2tpd[27339]: message_type_avp: message type 10 
(Incoming-Call-Request)
Jul 23 03:09:38 zeus l2tpd[27339]: message_type_avp: new incoming call
Jul 23 03:09:38 zeus l2tpd[27339]: ourcid = 5355, entropy_buf = 14eb
Jul 23 03:09:38 zeus l2tpd[27339]: assigned_call_avp: using peer's call 1
Jul 23 03:09:38 zeus l2tpd[27339]: call_serno_avp: serial number is 0
Jul 23 03:09:38 zeus l2tpd[27339]: bearer_type_avp: peer bears: analog
Jul 23 03:09:38 zeus l2tpd[27339]: check_control: control, cid = 0, Ns = 
3, Nr = 1
Jul 23 03:09:38 zeus l2tpd[27339]: check_control: control, cid = 1, Ns = 
3, Nr = 2
Jul 23 03:09:38 zeus l2tpd[27339]: handle_avps: handling avp's for 
tunnel 49756, call 5355
Jul 23 03:09:38 zeus l2tpd[27339]: message_type_avp: message type 12 
(Incoming-Call-Connected)
Jul 23 03:09:38 zeus l2tpd[27339]: tx_speed_avp: transmit baud rate is 
10000000
Jul 23 03:09:38 zeus l2tpd[27339]: frame_type_avp: peer uses:sync frames
Jul 23 03:09:38 zeus l2tpd[27339]: ignore_avp : Ignoring AVP
Jul 23 03:09:38 zeus l2tpd[27339]: start_pppd: I'm running:
Jul 23 03:09:38 zeus l2tpd[27339]: "/usr/sbin/pppd"
Jul 23 03:09:38 zeus l2tpd[27339]: "passive"
Jul 23 03:09:38 zeus l2tpd[27339]: "-detach"
Jul 23 03:09:38 zeus l2tpd[27339]: "192.168.0.70:192.168.0.23"
Jul 23 03:09:38 zeus l2tpd[27339]: "refuse-pap"
Jul 23 03:09:38 zeus l2tpd[27339]: "auth"
Jul 23 03:09:38 zeus l2tpd[27339]: "require-chap"
Jul 23 03:09:38 zeus l2tpd[27339]: "name"
Jul 23 03:09:38 zeus l2tpd[27339]: "VPNserver"
Jul 23 03:09:38 zeus l2tpd[27339]: "debug"
Jul 23 03:09:38 zeus l2tpd[27339]: "file"
Jul 23 03:09:38 zeus l2tpd[27339]: "/etc/ppp/options.l2tpd"
Jul 23 03:09:38 zeus l2tpd[27339]: "/dev/ttyp0"
Jul 23 03:09:38 zeus l2tpd[27339]:
Jul 23 03:09:38 zeus l2tpd[27339]: control_finish: Call established with 
213.39.113.25, Local: 5355, Remote: 1, Serial: 0
Jul 23 03:09:38 zeus l2tpd[27339]: check_control: control, cid = 0, Ns = 
4, Nr = 2
Jul 23 03:09:38 zeus pppd[27597]: pppd 2.4.2 started by root, uid 0
Jul 23 03:09:38 zeus pppd[27597]: using channel 4
Jul 23 03:09:38 zeus pppd[27597]: Using interface ppp0
Jul 23 03:09:38 zeus pppd[27597]: Connect: ppp0 <--> /dev/ttyp0
Jul 23 03:09:38 zeus pppd[27597]: sent [LCP ConfReq id=0x1 <mru 1410> 
<asyncmap 0x0> <auth chap MD5> <magic 0xbedab8f2> <pcomp> <accomp
 >]
Jul 23 03:09:38 zeus pppd[27597]: rcvd [LCP ConfReq id=0x0 <magic 
0x63157a9a> <pcomp> <accomp> <callback CBCP> <mrru 1614> <endpoint [M
AC:00:e0:7d:7c:e4:d5]>]
Jul 23 03:09:38 zeus pppd[27597]: sent [LCP ConfRej id=0x0 <callback 
CBCP> <mrru 1614>]
Jul 23 03:09:38 zeus pppd[27597]: rcvd [LCP ConfNak id=0x1 <mru 1514> 
<auth chap MS-v2>]
Jul 23 03:09:38 zeus pppd[27597]: sent [LCP ConfReq id=0x2 <mru 1410> 
<asyncmap 0x0> <magic 0xbedab8f2> <pcomp> <accomp>]
Jul 23 03:09:38 zeus pluto[27255]: "w2k-client"[2] 213.39.113.25:4500 
#4: transition from state STATE_QUICK_R1 to state STATE_QUICK_R2
Jul 23 03:09:38 zeus pluto[27255]: "w2k-client"[2] 213.39.113.25:4500 
#4: IPsec SA established {ESP=>0xeb77db63 <0x61d4de0c}
Jul 23 03:09:38 zeus pppd[27597]: rcvd [LCP ConfReq id=0x1 <magic 
0x63157a9a> <pcomp> <accomp> <endpoint [MAC:00:e0:7d:7c:e4:d5]>]
Jul 23 03:09:38 zeus pppd[27597]: sent [LCP ConfAck id=0x1 <magic 
0x63157a9a> <pcomp> <accomp> <endpoint [MAC:00:e0:7d:7c:e4:d5]>]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP ConfNak id=0x2 <mru 1514>]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP ConfReq id=0x3 <mru 1410> 
<asyncmap 0x0> <magic 0xbedab8f2> <pcomp> <accomp>]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP ConfNak id=0x3 <mru 1514>]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP ConfReq id=0x4 <mru 1410> 
<asyncmap 0x0> <magic 0xbedab8f2> <pcomp> <accomp>]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP ConfNak id=0x4 <mru 1514>]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP ConfReq id=0x5 <mru 1410> 
<asyncmap 0x0> <magic 0xbedab8f2> <pcomp> <accomp>]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP ConfNak id=0x5 <mru 1514>]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP ConfReq id=0x6 <mru 1410> 
<asyncmap 0x0> <magic 0xbedab8f2> <pcomp> <accomp>]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP ConfNak id=0x6 <mru 1514>]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP ConfReq id=0x7 <mru 1410> 
<asyncmap 0x0> <magic 0xbedab8f2> <pcomp> <accomp>]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP ConfNak id=0x7 <mru 1514>]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP ConfReq id=0x8 <mru 1410> 
<asyncmap 0x0> <magic 0xbedab8f2> <pcomp> <accomp>]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP ConfNak id=0x8 <mru 1514>]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP ConfReq id=0x9 <mru 1410> 
<asyncmap 0x0> <magic 0xbedab8f2> <pcomp> <accomp>]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP ConfNak id=0x9 <mru 1514>]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP ConfReq id=0xa <mru 1410> 
<asyncmap 0x0> <magic 0xbedab8f2> <pcomp> <accomp>]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP ConfNak id=0xa <mru 1514>]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP ConfReq id=0xb <mru 1410> 
<asyncmap 0x0> <magic 0xbedab8f2> <pcomp> <accomp>]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP ConfRej id=0xb <mru 1410>]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP ConfReq id=0xc <asyncmap 
0x0> <magic 0xbedab8f2> <pcomp> <accomp>]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP ConfAck id=0xc <asyncmap 
0x0> <magic 0xbedab8f2> <pcomp> <accomp>]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP EchoReq id=0x0 magic=0xbedab8f2]
Jul 23 03:09:39 zeus pppd[27597]: peer refused to authenticate: 
terminating link
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP TermReq id=0xd "peer refused 
to authenticate"]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP code=0xc id=0x2 63 15 7a 9a 
4d 53 52 41 53 56 35 2e 30 30]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP CodeRej id=0xe 0c 02 00 12 
63 15 7a 9a 4d 53 52 41 53 56 35 2e 30 30]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP code=0xc id=0x3 63 15 7a 9a 
4d 53 52 41 53 2d 31 2d 53 49 4c 45 4b]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP CodeRej id=0xf 0c 03 00 15 
63 15 7a 9a 4d 53 52 41 53 2d 31 2d 53 49 4c 45 4b]
Jul 23 03:09:39 zeus pppd[27597]: rcvd [LCP TermReq id=0x4 
"c\025z\37777777632\000<\37777777715t\000\000\002\37777777745"]
Jul 23 03:09:39 zeus pppd[27597]: sent [LCP TermAck id=0x4]
Jul 23 03:09:39 zeus l2tpd[27339]: check_control: control, cid = 1, Ns = 
4, Nr = 2
Jul 23 03:09:39 zeus l2tpd[27339]: handle_avps: handling avp's for 
tunnel 49756, call 5355
Jul 23 03:09:39 zeus l2tpd[27339]: message_type_avp: message type 14 
(Call-Disconnect-Notify)
Jul 23 03:09:39 zeus l2tpd[27339]: result_code_avp: peer closing for 
reason 3 (Control channel already exists), error = 0 ()
Jul 23 03:09:39 zeus l2tpd[27339]: assigned_call_avp: using peer's call 1
Jul 23 03:09:39 zeus l2tpd[27339]: control_finish: Connection closed to 
213.39.113.25, serial 0 ()
Jul 23 03:09:39 zeus pppd[27597]: Terminating on signal 15.
Jul 23 03:09:39 zeus pppd[27597]: Modem hangup
Jul 23 03:09:39 zeus pppd[27597]: Connection terminated.
Jul 23 03:09:40 zeus pppd[27597]: Exit.
Jul 23 03:09:40 zeus l2tpd[27339]: check_control: control, cid = 0, Ns = 
5, Nr = 2
Jul 23 03:09:40 zeus l2tpd[27339]: handle_avps: handling avp's for 
tunnel 49756, call 27450
Jul 23 03:09:40 zeus l2tpd[27339]: message_type_avp: message type 4 
(Stop-Control-Connection-Notification)
Jul 23 03:09:40 zeus l2tpd[27339]: assigned_tunnel_avp: using peer's 
tunnel 18
Jul 23 03:09:40 zeus l2tpd[27339]: result_code_avp: peer closing for 
reason 6 (Requester is being shut down), error = 0 ()
<snap>