[Openswan Users] xl2tpd failing to connect. reason not clear

Wed Jun 29 10:28:50 EDT 2011

Hi,

I'd be very grateful if anyone could give me an indication as to why this connection is failing.  The underlying IPSEC connection seems to be happy.

Jun 29 14:24:03 myclient xl2tpd[20886]: setsockopt recvref[22]: Protocol not available
Jun 29 14:24:03 myclient xl2tpd[20886]: This binary does not support kernel L2TP.
Jun 29 14:24:03 myclient xl2tpd[20887]: xl2tpd version xl2tpd-1.2.8 started on myclient PID:20887
Jun 29 14:24:03 myclient xl2tpd[20887]: Written by Mark Spencer, Copyright (C) 1998, Adtran, Inc.
Jun 29 14:24:03 myclient xl2tpd[20887]: Forked by Scott Balmos and David Stipp, (C) 2001
Jun 29 14:24:03 myclient xl2tpd[20887]: Inherited by Jeff McAdams, (C) 2002
Jun 29 14:24:03 myclient xl2tpd[20887]: Forked again by Xelerance (www.xelerance.com) (C) 2006
Jun 29 14:24:03 myclient xl2tpd[20887]: Listening on IP address 0.0.0.0, port 1701
Jun 29 14:24:14 myclient xl2tpd[20887]: get_call: allocating new tunnel for host 86.XXX.XXX.211, port 1701.
Jun 29 14:24:14 myclient xl2tpd[20887]: Connecting to host draytek.xxxxxxxx.net, port 1701
Jun 29 14:24:14 myclient xl2tpd[20887]: control_finish: message type is (null)(0).  Tunnel is 0, call is 0.
Jun 29 14:24:14 myclient xl2tpd[20887]: control_finish: sending SCCRQ
Jun 29 14:24:15 myclient xl2tpd[20887]: network_thread: recv packet from 86.XXX.XXX.211, size = 104, tunnel = 33417, call = 0 ref=0 refhim=0
Jun 29 14:24:15 myclient xl2tpd[20887]: handle_avps: handling avp's for tunnel 33417, call 0
Jun 29 14:24:15 myclient xl2tpd[20887]: message_type_avp: message type 2 (Start-Control-Connection-Reply)
Jun 29 14:24:15 myclient xl2tpd[20887]: protocol_version_avp: peer is using version 1, revision 0.
Jun 29 14:24:15 myclient xl2tpd[20887]: framing_caps_avp: supported peer frames: sync
Jun 29 14:24:15 myclient xl2tpd[20887]: bearer_caps_avp: supported peer bearers: digital
Jun 29 14:24:15 myclient xl2tpd[20887]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Jun 29 14:24:15 myclient xl2tpd[20887]: hostname_avp: peer reports hostname 'draytek'
Jun 29 14:24:15 myclient xl2tpd[20887]: vendor_avp: peer reports vendor 'DrayTek, l2tp'
Jun 29 14:24:15 myclient xl2tpd[20887]: assigned_tunnel_avp: using peer's tunnel 10
Jun 29 14:24:15 myclient xl2tpd[20887]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Jun 29 14:24:15 myclient xl2tpd[20887]: control_finish: message type is Start-Control-Connection-Reply(2).  Tunnel is 10, call is 0.
Jun 29 14:24:15 myclient xl2tpd[20887]: control_finish: sending SCCCN
Jun 29 14:24:15 myclient xl2tpd[20887]: Connection established to 86.XXX.XXX.211, 1701.  Local: 33417, Remote: 10 (ref=0/0).
Jun 29 14:24:15 myclient xl2tpd[20887]: Calling on tunnel 33417
Jun 29 14:24:15 myclient xl2tpd[20887]: control_finish: message type is (null)(0).  Tunnel is 10, call is 0.
Jun 29 14:24:15 myclient xl2tpd[20887]: control_finish: sending ICRQ
Jun 29 14:24:15 myclient xl2tpd[20887]: network_thread: recv packet from 86.XXX.XXX.211, size = 36, tunnel = 33417, call = 27161 ref=0 refhim=0
Jun 29 14:24:15 myclient xl2tpd[20887]: handle_avps: handling avp's for tunnel 33417, call 27161
Jun 29 14:24:15 myclient xl2tpd[20887]: message_type_avp: message type 11 (Incoming-Call-Reply)
Jun 29 14:24:15 myclient xl2tpd[20887]: assigned_call_avp: using peer's call 2
Jun 29 14:24:15 myclient xl2tpd[20887]: receive_window_size_avp: RWS not appropriate for message Incoming-Call-Reply.  Ignoring.
Jun 29 14:24:15 myclient xl2tpd[20887]: control_finish: message type is Incoming-Call-Reply(11).  Tunnel is 10, call is 2.
Jun 29 14:24:15 myclient xl2tpd[20887]: control_finish: Sending ICCN
Jun 29 14:24:15 myclient xl2tpd[20887]: Call established with 86.XXX.XXX.211, Local: 27161, Remote: 2, Serial: 1 (ref=0/0)
Jun 29 14:24:15 myclient xl2tpd[20887]: start_pppd: I'm running: 
Jun 29 14:24:15 myclient xl2tpd[20887]: "/usr/sbin/pppd" 
Jun 29 14:24:15 myclient xl2tpd[20887]: "passive" 
Jun 29 14:24:15 myclient xl2tpd[20887]: "nodetach" 
Jun 29 14:24:15 myclient xl2tpd[20887]: ":" 
Jun 29 14:24:15 myclient xl2tpd[20887]: "refuse-pap" 
Jun 29 14:24:15 myclient xl2tpd[20887]: "auth" 
Jun 29 14:24:15 myclient xl2tpd[20887]: "require-chap" 
Jun 29 14:24:15 myclient xl2tpd[20887]: "name" 
Jun 29 14:24:15 myclient xl2tpd[20887]: "myclient" 
Jun 29 14:24:15 myclient xl2tpd[20887]: "debug" 
Jun 29 14:24:15 myclient xl2tpd[20887]: "file" 
Jun 29 14:24:15 myclient xl2tpd[20887]: "/etc/ppp/options.l2tpd.client" 
Jun 29 14:24:15 myclient xl2tpd[20887]: "ipparam" 
Jun 29 14:24:15 myclient xl2tpd[20887]: "86.XXX.XXX.211" 
Jun 29 14:24:15 myclient xl2tpd[20887]: "/dev/pts/1" 
Jun 29 14:24:15 myclient xl2tpd[20887]: network_thread: recv packet from 86.XXX.XXX.211, size = 38, tunnel = 33417, call = 27161 ref=0 refhim=0
Jun 29 14:24:15 myclient xl2tpd[20887]: handle_avps: handling avp's for tunnel 33417, call 27161
Jun 29 14:24:15 myclient xl2tpd[20887]: message_type_avp: message type 14 (Call-Disconnect-Notify)
Jun 29 14:24:15 myclient xl2tpd[20887]: result_code_avp: peer closing for reason 1 (General request to clear control connection), error = 0 ()
Jun 29 14:24:15 myclient xl2tpd[20887]: assigned_call_avp: using peer's call 2
Jun 29 14:24:15 myclient xl2tpd[20887]: control_finish: message type is Call-Disconnect-Notify(14).  Tunnel is 10, call is 2.
Jun 29 14:24:15 myclient xl2tpd[20887]: control_finish: Connection closed to 86.XXX.XXX.211, serial 1 ()
Jun 29 14:24:15 myclient xl2tpd[20887]: Terminating pppd: sending TERM signal to pid 21100
Jun 29 14:24:15 myclient xl2tpd[20887]: network_thread: select returned error 4 (Interrupted system call)
Jun 29 14:24:15 myclient xl2tpd[20887]: network_thread: recv packet from 86.XXX.XXX.211, size = 38, tunnel = 33417, call = 0 ref=0 refhim=0
Jun 29 14:24:15 myclient xl2tpd[20887]: handle_avps: handling avp's for tunnel 33417, call 0
Jun 29 14:24:15 myclient xl2tpd[20887]: message_type_avp: message type 4 (Stop-Control-Connection-Notification)
Jun 29 14:24:15 myclient xl2tpd[20887]: assigned_tunnel_avp: using peer's tunnel 10
Jun 29 14:24:15 myclient xl2tpd[20887]: result_code_avp: peer closing for reason 1 (General request to clear control connection), error = 0 ()
Jun 29 14:24:15 myclient xl2tpd[20887]: control_finish: message type is Stop-Control-Connection-Notification(4).  Tunnel is 10, call is 0.
Jun 29 14:24:15 myclient xl2tpd[20887]: control_finish: Connection closed to 86.XXX.XXX.211, port 1701 (), Local: 33417, Remote: 10
Jun 29 14:24:15 myclient xl2tpd[20887]: build_fdset: closing down tunnel 33417

Thanks in advance.

Mike