[Openswan Users] random l2tp/pppd failure, again

Joel Michael joel at gimps-r-us.com
Mon Mar 6 19:45:19 CET 2006


Paul Wouters wrote:
> You can try the "xl2tpd" CVS soures at Xelerance. There are a few patches that
> have not made it into the l2tpd at fedora extras yet.
> 
I'm not completely against running CVS software, but I'm wary.  The 
biggest issues for me are stability and upgradability.  What advantages 
does the xl2tpd have over the l2tpd in Fedora Extras?  You mentioned 
that a few patches are missing, what functionality do they provide, or 
what bugs do they fix?

I'm trying to weigh up whether the cost of installing and maintaining 
xl2tpd will outweigh the benefits of running xl2tpd over the l2tpd in 
Fedora Extras, with the cost being what I will charge my client for my 
time to install and maintain xl2tpd.

> Also, can you run it with l2tpd -D and see if that gives any clues as to what
> the problem might be?

I did not get a chance to do this, as the server entered production 
today.  I have a maintenance window at 11am on Saturday to do any 
testing that anyone wants to throw my way :-)

I did turn on all the debug options in the configuration file, though. 
Please find attached my l2tpd.conf and l2tp logs.  IP addresses and host 
names have been changed to protect the innocent.

> The only problems we know about related to SMP is with KLIPS, and the problem is
> that the kernel then completely locks up, which is not what you are experiencing.
> 
In an attempt to avoid triggering the problem, I tried rebooting the 
system with a UP kernel before it went into production this morning.  It 
survived the whole day without a problem, with a maximum of 6 concurrent 
users.  When the problem triggered, it was only two people constantly 
connecting and disconnecting, and the problem triggered within a couple 
of hours.

Looking at the log, there are a couple of lines that stand out to me, 
but I'm not sure if it's normal or not.  Just before the crash, these 
log lines appear:

network_thread: recv packet from 12.34.56.78, size = 337, tunnel = 
51221, call = 64125
network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 
51221, call = 64125
network_thread: recv packet from 12.34.56.78, size = 170, tunnel = 
51221, call = 64125
network_thread: recv packet from 90.123.45.67, size = 94, tunnel = 
51221, call = 64125
network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 
30822, call = 35008
network_thread: recv packet from 90.123.45.67, size = 94, tunnel = 
51221, call = 64125
network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 
30822, call = 35008
network_thread: recv packet from 12.34.56.78, size = 28, tunnel = 51221, 
call = 64125
network_thread: recv packet from 12.34.56.78, size = 108, tunnel = 
51221, call = 64125
network_thread: recv packet from 12.34.56.78, size = 173, tunnel = 
51221, call = 64125
network_thread: recv packet from 90.123.45.67, size = 94, tunnel = 
51221, call = 64125
network_thread: recv packet from 90.123.45.67, size = 94, tunnel = 
30822, call = 35008

The thing that makes them stand out to me is that the tunnel number and 
call number appear to be the same on two different machines at once.  As 
I said, I'm not sure if this is normal or not, as I haven't run with 
debugging turned all the way up before.  This is not an artifact of my 
IP address munging, the munging was done simply by a find/replace.

Thanks for your help so far!
-- 
jpm
-------------- next part --------------
[global]
; listen-addr = 192.168.1.98
debug avp = yes
debug network = yes
debug packet = yes
debug state = yes
debug tunnel = yes

[lns default]
ip range = 1.2.3.4-1.2.3.254
local ip = 1.2.3.1
require chap = yes
refuse pap = yes
require authentication = yes
name = RorcVPN
ppp debug = no
pppoptfile = /etc/ppp/options.l2tpd
length bit = yes
ppp debug = yes
-------------- next part --------------
Mar  5 18:54:29 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 98, tunnel = 33925, call = 0
Mar  5 18:54:29 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 12.34.56.78, port 1701.
Mar  5 18:54:29 vpnserver l2tpd[8152]: ourtid = 15815, entropy_buf = 3dc7
Mar  5 18:54:29 vpnserver l2tpd[8152]: ourcid = 20454, entropy_buf = 4fe6
Mar  5 18:54:29 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 18:54:29 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 15815, call 20454
Mar  5 18:54:29 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 18:54:29 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 18:54:29 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 18:54:29 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 18:54:29 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 18:54:29 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host1'
Mar  5 18:54:29 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 18:54:29 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 9
Mar  5 18:54:29 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 18:54:29 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 9, call is 0.
Mar  5 18:54:29 vpnserver l2tpd[8152]: control_finish: sending SCCRP
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 98, tunnel = 0, call = 0
Mar  5 18:54:31 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 12.34.56.78, port 1701.
Mar  5 18:54:31 vpnserver l2tpd[8152]: ourtid = 11548, entropy_buf = 2d1c
Mar  5 18:54:31 vpnserver l2tpd[8152]: ourcid = 4647, entropy_buf = 1227
Mar  5 18:54:31 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 18:54:31 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 11548, call 4647
Mar  5 18:54:31 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 18:54:31 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 18:54:31 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 18:54:31 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 18:54:31 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 18:54:31 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host1'
Mar  5 18:54:31 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 18:54:31 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 9
Mar  5 18:54:31 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 18:54:31 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 9, call is 0.
Mar  5 18:54:31 vpnserver l2tpd[8152]: control_finish: Peer requested tunnel 9 twice, ignoring second one.
Mar  5 18:54:31 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 11548
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 0, call = 0
Mar  5 18:54:31 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 1, Nr = 1
Mar  5 18:54:31 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 15815, call 20454
Mar  5 18:54:31 vpnserver l2tpd[8152]: message_type_avp: message type 3 (Start-Control-Connection-Connected)
Mar  5 18:54:31 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Connected(3).  Tunnel is 9, call is 0.
Mar  5 18:54:31 vpnserver l2tpd[8152]: Connection established to 12.34.56.78, 1701.  Local: 15815, Remote: 9.  LNS session is 'default'
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 48, tunnel = 15815, call = 0
Mar  5 18:54:31 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 2, Nr = 1
Mar  5 18:54:31 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 15815, call 20454
Mar  5 18:54:31 vpnserver l2tpd[8152]: message_type_avp: message type 10 (Incoming-Call-Request)
Mar  5 18:54:31 vpnserver l2tpd[8152]: message_type_avp: new incoming call
Mar  5 18:54:31 vpnserver l2tpd[8152]: ourcid = 21789, entropy_buf = 551d
Mar  5 18:54:31 vpnserver l2tpd[8152]: assigned_call_avp: using peer's call 1
Mar  5 18:54:31 vpnserver l2tpd[8152]: call_serno_avp: serial number is 0
Mar  5 18:54:31 vpnserver l2tpd[8152]: bearer_type_avp: peer bears: analog
Mar  5 18:54:31 vpnserver l2tpd[8152]: control_finish: message type is Incoming-Call-Request(10).  Tunnel is 9, call is 0.
Mar  5 18:54:31 vpnserver l2tpd[8152]: control_finish: Sending ICRP
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 12, tunnel = 15815, call = 0
Mar  5 18:54:31 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 3, Nr = 1
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 48, tunnel = 15815, call = 0
Mar  5 18:54:31 vpnserver l2tpd[8152]: check_control: control, cid = 1, Ns = 3, Nr = 2
Mar  5 18:54:31 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 15815, call 21789
Mar  5 18:54:31 vpnserver l2tpd[8152]: message_type_avp: message type 12 (Incoming-Call-Connected)
Mar  5 18:54:31 vpnserver l2tpd[8152]: tx_speed_avp: transmit baud rate is 100000000
Mar  5 18:54:31 vpnserver l2tpd[8152]: frame_type_avp: peer uses:sync frames
Mar  5 18:54:31 vpnserver l2tpd[8152]: ignore_avp : Ignoring AVP
Mar  5 18:54:31 vpnserver l2tpd[8152]: control_finish: message type is Incoming-Call-Connected(12).  Tunnel is 9, call is 1.
Mar  5 18:54:31 vpnserver l2tpd[8152]: start_pppd: I'm running:
Mar  5 18:54:31 vpnserver l2tpd[8152]: "/usr/sbin/pppd"
Mar  5 18:54:31 vpnserver l2tpd[8152]: "passive"
Mar  5 18:54:31 vpnserver l2tpd[8152]: "-detach"
Mar  5 18:54:31 vpnserver l2tpd[8152]: "1.2.3.1:1.2.3.5"
Mar  5 18:54:31 vpnserver l2tpd[8152]: "refuse-pap"
Mar  5 18:54:31 vpnserver l2tpd[8152]: "auth"
Mar  5 18:54:31 vpnserver l2tpd[8152]: "require-chap"
Mar  5 18:54:31 vpnserver l2tpd[8152]: "name"
Mar  5 18:54:31 vpnserver l2tpd[8152]: "MyVPN"
Mar  5 18:54:31 vpnserver l2tpd[8152]: "debug"
Mar  5 18:54:31 vpnserver l2tpd[8152]: "file"
Mar  5 18:54:31 vpnserver l2tpd[8152]: "/etc/ppp/options.l2tpd"
Mar  5 18:54:31 vpnserver l2tpd[8152]: "/dev/pts/5"
Mar  5 18:54:31 vpnserver l2tpd[8152]:
Mar  5 18:54:31 vpnserver l2tpd[8152]: Call established with 12.34.56.78, Local: 21789, Remote: 1, Serial: 0
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 12, tunnel = 15815, call = 21789
Mar  5 18:54:31 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 4, Nr = 2
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 33, tunnel = 15815, call = 0
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 41, tunnel = 15815, call = 21789
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 30, tunnel = 15815, call = 21789
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 28, tunnel = 15815, call = 21789
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 31, tunnel = 15815, call = 21789
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 76, tunnel = 15815, call = 21789
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 15815, call = 21789
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 44, tunnel = 15815, call = 21789
Mar  5 18:54:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 15815, call = 21789
Mar  5 18:54:32 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 32, tunnel = 15815, call = 21789
Mar  5 18:54:32 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 15815, call = 21789
Mar  5 18:54:32 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 32, tunnel = 15815, call = 21789
Mar  5 18:54:32 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 170, tunnel = 15815, call = 21789
Mar  5 18:54:32 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 337, tunnel = 15815, call = 21789
Mar  5 18:54:32 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 15815, call = 21789
Mar  5 18:54:34 vpnserver l2tpd[8152]: Maximum retries exceeded for tunnel 33925.  Closing.
Mar  5 18:54:34 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 33925
Mar  5 18:54:34 vpnserver l2tpd[8152]: Connection 8 closed to 12.34.56.78, port 1701 (Timeout)
Mar  5 18:54:35 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 170, tunnel = 15815, call = 21789
Mar  5 18:54:35 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 337, tunnel = 15815, call = 21789
Mar  5 18:54:35 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 15815, call = 21789
Mar  5 18:54:37 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 15815, call = 21789
Mar  5 18:54:38 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 170, tunnel = 15815, call = 21789
Mar  5 18:54:38 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 15815, call = 21789
Mar  5 18:54:39 vpnserver l2tpd[8152]: Unable to deliver closing message for tunnel 33925. Destroying anyway.
Mar  5 18:54:39 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 33925
Mar  5 18:54:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 15815, call = 21789
Mar  5 18:54:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 15815, call = 21789
Mar  5 18:54:40 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 15815, call = 21789
Mar  5 18:54:40 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 15815, call = 21789
Mar  5 18:54:41 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 28, tunnel = 15815, call = 21789
Mar  5 18:54:41 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 216, tunnel = 15815, call = 21789
Mar  5 18:54:41 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 241, tunnel = 15815, call = 21789
Mar  5 18:54:43 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 38, tunnel = 15815, call = 21789
Mar  5 18:54:43 vpnserver l2tpd[8152]: check_control: control, cid = 1, Ns = 4, Nr = 2
Mar  5 18:54:43 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 15815, call 21789
Mar  5 18:54:43 vpnserver l2tpd[8152]: message_type_avp: message type 14 (Call-Disconnect-Notify)
Mar  5 18:54:43 vpnserver l2tpd[8152]: result_code_avp: peer closing for reason 3 (Control channel already exists), error = 0 ()
Mar  5 18:54:43 vpnserver l2tpd[8152]: assigned_call_avp: using peer's call 1
Mar  5 18:54:43 vpnserver l2tpd[8152]: control_finish: message type is Call-Disconnect-Notify(14).  Tunnel is 9, call is 1.
Mar  5 18:54:43 vpnserver l2tpd[8152]: control_finish: Connection closed to 12.34.56.78, serial 0 ()
Mar  5 18:54:43 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 38, tunnel = 15815, call = 21789
Mar  5 18:54:43 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 5, Nr = 2
Mar  5 18:54:43 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 15815, call 20454
Mar  5 18:54:43 vpnserver l2tpd[8152]: message_type_avp: message type 4 (Stop-Control-Connection-Notification)
Mar  5 18:54:43 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 9
Mar  5 18:54:43 vpnserver l2tpd[8152]: result_code_avp: peer closing for reason 6 (Requester is being shut down), error = 0 ()
Mar  5 18:54:43 vpnserver l2tpd[8152]: control_finish: message type is Stop-Control-Connection-Notification(4).  Tunnel is 9, call is 0.
Mar  5 18:54:43 vpnserver l2tpd[8152]: control_finish: Peer tried to disconnect with invalid TID (9 != 15815)
Mar  5 18:54:43 vpnserver l2tpd[8152]: network_thread: bad packet
Mar  5 18:55:36 vpnserver l2tpd[8152]: Maximum retries exceeded for tunnel 15815.  Closing.
Mar  5 18:55:36 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 15815
Mar  5 18:55:36 vpnserver l2tpd[8152]: Connection 9 closed to 12.34.56.78, port 1701 (Timeout)
Mar  5 18:55:41 vpnserver l2tpd[8152]: Unable to deliver closing message for tunnel 15815. Destroying anyway.
Mar  5 18:55:41 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 15815
Mar  5 18:57:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 98, tunnel = 15815, call = 0
Mar  5 18:57:26 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 12.34.56.78, port 1701.
Mar  5 18:57:26 vpnserver l2tpd[8152]: ourtid = 42203, entropy_buf = a4db
Mar  5 18:57:26 vpnserver l2tpd[8152]: ourcid = 39285, entropy_buf = 9975
Mar  5 18:57:26 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 18:57:26 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 42203, call 39285
Mar  5 18:57:26 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 18:57:26 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 18:57:26 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 18:57:26 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 18:57:26 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 18:57:26 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host1'
Mar  5 18:57:26 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 18:57:26 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 10
Mar  5 18:57:26 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 18:57:26 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 10, call is 0.
Mar  5 18:57:26 vpnserver l2tpd[8152]: control_finish: sending SCCRP
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 98, tunnel = 0, call = 0
Mar  5 18:57:28 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 12.34.56.78, port 1701.
Mar  5 18:57:28 vpnserver l2tpd[8152]: ourtid = 58010, entropy_buf = e29a
Mar  5 18:57:28 vpnserver l2tpd[8152]: ourcid = 10345, entropy_buf = 2869
Mar  5 18:57:28 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 18:57:28 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 58010, call 10345
Mar  5 18:57:28 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 18:57:28 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 18:57:28 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 18:57:28 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 18:57:28 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 18:57:28 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host1'
Mar  5 18:57:28 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 18:57:28 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 10
Mar  5 18:57:28 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 18:57:28 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 10, call is 0.
Mar  5 18:57:28 vpnserver l2tpd[8152]: control_finish: Peer requested tunnel 10 twice, ignoring second one.
Mar  5 18:57:28 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 58010
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 0, call = 0
Mar  5 18:57:28 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 1, Nr = 1
Mar  5 18:57:28 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 42203, call 39285
Mar  5 18:57:28 vpnserver l2tpd[8152]: message_type_avp: message type 3 (Start-Control-Connection-Connected)
Mar  5 18:57:28 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Connected(3).  Tunnel is 10, call is 0.
Mar  5 18:57:28 vpnserver l2tpd[8152]: Connection established to 12.34.56.78, 1701.  Local: 42203, Remote: 10.  LNS session is 'default'
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 48, tunnel = 42203, call = 0
Mar  5 18:57:28 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 2, Nr = 1
Mar  5 18:57:28 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 42203, call 39285
Mar  5 18:57:28 vpnserver l2tpd[8152]: message_type_avp: message type 10 (Incoming-Call-Request)
Mar  5 18:57:28 vpnserver l2tpd[8152]: message_type_avp: new incoming call
Mar  5 18:57:28 vpnserver l2tpd[8152]: ourcid = 7495, entropy_buf = 1d47
Mar  5 18:57:28 vpnserver l2tpd[8152]: assigned_call_avp: using peer's call 1
Mar  5 18:57:28 vpnserver l2tpd[8152]: call_serno_avp: serial number is 0
Mar  5 18:57:28 vpnserver l2tpd[8152]: bearer_type_avp: peer bears: analog
Mar  5 18:57:28 vpnserver l2tpd[8152]: control_finish: message type is Incoming-Call-Request(10).  Tunnel is 10, call is 0.
Mar  5 18:57:28 vpnserver l2tpd[8152]: control_finish: Sending ICRP
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 12, tunnel = 42203, call = 0
Mar  5 18:57:28 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 3, Nr = 1
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 48, tunnel = 42203, call = 0
Mar  5 18:57:28 vpnserver l2tpd[8152]: check_control: control, cid = 1, Ns = 3, Nr = 2
Mar  5 18:57:28 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 42203, call 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: message_type_avp: message type 12 (Incoming-Call-Connected)
Mar  5 18:57:28 vpnserver l2tpd[8152]: tx_speed_avp: transmit baud rate is 100000000
Mar  5 18:57:28 vpnserver l2tpd[8152]: frame_type_avp: peer uses:sync frames
Mar  5 18:57:28 vpnserver l2tpd[8152]: ignore_avp : Ignoring AVP
Mar  5 18:57:28 vpnserver l2tpd[8152]: control_finish: message type is Incoming-Call-Connected(12).  Tunnel is 10, call is 1.
Mar  5 18:57:28 vpnserver l2tpd[8152]: start_pppd: I'm running:
Mar  5 18:57:28 vpnserver l2tpd[8152]: "/usr/sbin/pppd"
Mar  5 18:57:28 vpnserver l2tpd[8152]: "passive"
Mar  5 18:57:28 vpnserver l2tpd[8152]: "-detach"
Mar  5 18:57:28 vpnserver l2tpd[8152]: "1.2.3.1:1.2.3.4"
Mar  5 18:57:28 vpnserver l2tpd[8152]: "refuse-pap"
Mar  5 18:57:28 vpnserver l2tpd[8152]: "auth"
Mar  5 18:57:28 vpnserver l2tpd[8152]: "require-chap"
Mar  5 18:57:28 vpnserver l2tpd[8152]: "name"
Mar  5 18:57:28 vpnserver l2tpd[8152]: "MyVPN"
Mar  5 18:57:28 vpnserver l2tpd[8152]: "debug"
Mar  5 18:57:28 vpnserver l2tpd[8152]: "file"
Mar  5 18:57:28 vpnserver l2tpd[8152]: "/etc/ppp/options.l2tpd"
Mar  5 18:57:28 vpnserver l2tpd[8152]: "/dev/pts/5"
Mar  5 18:57:28 vpnserver l2tpd[8152]:
Mar  5 18:57:28 vpnserver l2tpd[8152]: Call established with 12.34.56.78, Local: 7495, Remote: 1, Serial: 0
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 12, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 4, Nr = 2
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 33, tunnel = 42203, call = 0
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 41, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 30, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 28, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 31, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 76, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 44, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 32, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 32, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 170, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 42203, call = 7495
Mar  5 18:57:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 337, tunnel = 42203, call = 7495
Mar  5 18:57:30 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 42203, call = 7495
Mar  5 18:57:30 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 42203, call = 7495
Mar  5 18:57:30 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 28, tunnel = 42203, call = 7495
Mar  5 18:57:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 108, tunnel = 42203, call = 7495
Mar  5 18:57:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 173, tunnel = 42203, call = 7495
Mar  5 18:57:33 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 38, tunnel = 42203, call = 7495
Mar  5 18:57:33 vpnserver l2tpd[8152]: check_control: control, cid = 1, Ns = 4, Nr = 2
Mar  5 18:57:33 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 42203, call 7495
Mar  5 18:57:33 vpnserver l2tpd[8152]: message_type_avp: message type 14 (Call-Disconnect-Notify)
Mar  5 18:57:33 vpnserver l2tpd[8152]: result_code_avp: peer closing for reason 3 (Control channel already exists), error = 0 ()
Mar  5 18:57:33 vpnserver l2tpd[8152]: assigned_call_avp: using peer's call 1
Mar  5 18:57:33 vpnserver l2tpd[8152]: control_finish: message type is Call-Disconnect-Notify(14).  Tunnel is 10, call is 1.
Mar  5 18:57:33 vpnserver l2tpd[8152]: control_finish: Connection closed to 12.34.56.78, serial 0 ()
Mar  5 18:57:33 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 38, tunnel = 42203, call = 7495
Mar  5 18:57:33 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 5, Nr = 2
Mar  5 18:57:33 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 42203, call 39285
Mar  5 18:57:33 vpnserver l2tpd[8152]: message_type_avp: message type 4 (Stop-Control-Connection-Notification)
Mar  5 18:57:33 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 10
Mar  5 18:57:33 vpnserver l2tpd[8152]: result_code_avp: peer closing for reason 6 (Requester is being shut down), error = 0 ()
Mar  5 18:57:33 vpnserver l2tpd[8152]: control_finish: message type is Stop-Control-Connection-Notification(4).  Tunnel is 10, call is 0.
Mar  5 18:57:33 vpnserver l2tpd[8152]: control_finish: Peer tried to disconnect with invalid TID (10 != 42203)
Mar  5 18:57:33 vpnserver l2tpd[8152]: network_thread: bad packet
Mar  5 18:57:37 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 98, tunnel = 42203, call = 0
Mar  5 18:57:37 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 12.34.56.78, port 1701.
Mar  5 18:57:37 vpnserver l2tpd[8152]: ourtid = 24111, entropy_buf = 5e2f
Mar  5 18:57:37 vpnserver l2tpd[8152]: ourcid = 63270, entropy_buf = f726
Mar  5 18:57:37 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 18:57:37 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 24111, call 63270
Mar  5 18:57:37 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 18:57:37 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 18:57:37 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 18:57:37 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 18:57:37 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 18:57:37 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host1'
Mar  5 18:57:37 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 18:57:37 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 11
Mar  5 18:57:37 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 18:57:37 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 11, call is 0.
Mar  5 18:57:37 vpnserver l2tpd[8152]: control_finish: sending SCCRP
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 98, tunnel = 0, call = 0
Mar  5 18:57:39 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 12.34.56.78, port 1701.
Mar  5 18:57:39 vpnserver l2tpd[8152]: ourtid = 6274, entropy_buf = 1882
Mar  5 18:57:39 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 18:57:39 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 6274, call 0
Mar  5 18:57:39 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 18:57:39 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 18:57:39 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 18:57:39 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 18:57:39 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 18:57:39 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host1'
Mar  5 18:57:39 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 18:57:39 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 11
Mar  5 18:57:39 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 18:57:39 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 11, call is 0.
Mar  5 18:57:39 vpnserver l2tpd[8152]: control_finish: Peer requested tunnel 11 twice, ignoring second one.
Mar  5 18:57:39 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 6274
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 0, call = 0
Mar  5 18:57:39 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 1, Nr = 1
Mar  5 18:57:39 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 24111, call 63270
Mar  5 18:57:39 vpnserver l2tpd[8152]: message_type_avp: message type 3 (Start-Control-Connection-Connected)
Mar  5 18:57:39 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Connected(3).  Tunnel is 11, call is 0.
Mar  5 18:57:39 vpnserver l2tpd[8152]: Connection established to 12.34.56.78, 1701.  Local: 24111, Remote: 11.  LNS session is 'default'
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 48, tunnel = 24111, call = 0
Mar  5 18:57:39 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 2, Nr = 1
Mar  5 18:57:39 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 24111, call 63270
Mar  5 18:57:39 vpnserver l2tpd[8152]: message_type_avp: message type 10 (Incoming-Call-Request)
Mar  5 18:57:39 vpnserver l2tpd[8152]: message_type_avp: new incoming call
Mar  5 18:57:39 vpnserver l2tpd[8152]: ourcid = 60250, entropy_buf = eb5a
Mar  5 18:57:39 vpnserver l2tpd[8152]: assigned_call_avp: using peer's call 1
Mar  5 18:57:39 vpnserver l2tpd[8152]: call_serno_avp: serial number is 0
Mar  5 18:57:39 vpnserver l2tpd[8152]: bearer_type_avp: peer bears: analog
Mar  5 18:57:39 vpnserver l2tpd[8152]: control_finish: message type is Incoming-Call-Request(10).  Tunnel is 11, call is 0.
Mar  5 18:57:39 vpnserver l2tpd[8152]: control_finish: Sending ICRP
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 12, tunnel = 24111, call = 0
Mar  5 18:57:39 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 3, Nr = 1
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 48, tunnel = 24111, call = 0
Mar  5 18:57:39 vpnserver l2tpd[8152]: check_control: control, cid = 1, Ns = 3, Nr = 2
Mar  5 18:57:39 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 24111, call 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: message_type_avp: message type 12 (Incoming-Call-Connected)
Mar  5 18:57:39 vpnserver l2tpd[8152]: tx_speed_avp: transmit baud rate is 100000000
Mar  5 18:57:39 vpnserver l2tpd[8152]: frame_type_avp: peer uses:sync frames
Mar  5 18:57:39 vpnserver l2tpd[8152]: ignore_avp : Ignoring AVP
Mar  5 18:57:39 vpnserver l2tpd[8152]: control_finish: message type is Incoming-Call-Connected(12).  Tunnel is 11, call is 1.
Mar  5 18:57:39 vpnserver l2tpd[8152]: start_pppd: I'm running:
Mar  5 18:57:39 vpnserver l2tpd[8152]: "/usr/sbin/pppd"
Mar  5 18:57:39 vpnserver l2tpd[8152]: "passive"
Mar  5 18:57:39 vpnserver l2tpd[8152]: "-detach"
Mar  5 18:57:39 vpnserver l2tpd[8152]: "1.2.3.1:1.2.3.4"
Mar  5 18:57:39 vpnserver l2tpd[8152]: "refuse-pap"
Mar  5 18:57:39 vpnserver l2tpd[8152]: "auth"
Mar  5 18:57:39 vpnserver l2tpd[8152]: "require-chap"
Mar  5 18:57:39 vpnserver l2tpd[8152]: "name"
Mar  5 18:57:39 vpnserver l2tpd[8152]: "MyVPN"
Mar  5 18:57:39 vpnserver l2tpd[8152]: "debug"
Mar  5 18:57:39 vpnserver l2tpd[8152]: "file"
Mar  5 18:57:39 vpnserver l2tpd[8152]: "/etc/ppp/options.l2tpd"
Mar  5 18:57:39 vpnserver l2tpd[8152]: "/dev/pts/5"
Mar  5 18:57:39 vpnserver l2tpd[8152]:
Mar  5 18:57:39 vpnserver l2tpd[8152]: Call established with 12.34.56.78, Local: 60250, Remote: 1, Serial: 0
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 12, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 4, Nr = 2
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 33, tunnel = 24111, call = 0
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 41, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 30, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 28, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 31, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 76, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 44, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 32, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 32, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 337, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 24111, call = 60250
Mar  5 18:57:39 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 170, tunnel = 24111, call = 60250
Mar  5 18:57:40 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 24111, call = 60250
Mar  5 18:57:41 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 24111, call = 60250
Mar  5 18:57:41 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 73, tunnel = 24111, call = 60250
Mar  5 18:57:42 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 24111, call = 60250
Mar  5 18:57:42 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 24111, call = 60250
Mar  5 18:57:42 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 170, tunnel = 24111, call = 60250
Mar  5 18:57:43 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 24111, call = 60250
Mar  5 18:57:44 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 28, tunnel = 24111, call = 60250
Mar  5 18:57:44 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 340, tunnel = 24111, call = 60250
Mar  5 18:57:44 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 108, tunnel = 24111, call = 60250
Mar  5 18:57:47 vpnserver l2tpd[8152]: child_handler : pppd exited for call 1 with code 0
Mar  5 18:57:47 vpnserver l2tpd[8152]: call_close: Call 60250 to 12.34.56.78 disconnected
Mar  5 18:57:47 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 12, tunnel = 24111, call = 60250
Mar  5 18:57:47 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 4, Nr = 3
Mar  5 18:57:47 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 38, tunnel = 24111, call = 0
Mar  5 18:57:47 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 4, Nr = 3
Mar  5 18:57:47 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 24111, call 63270
Mar  5 18:57:47 vpnserver l2tpd[8152]: message_type_avp: message type 4 (Stop-Control-Connection-Notification)
Mar  5 18:57:47 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 11
Mar  5 18:57:47 vpnserver l2tpd[8152]: result_code_avp: peer closing for reason 6 (Requester is being shut down), error = 0 ()
Mar  5 18:57:47 vpnserver l2tpd[8152]: control_finish: message type is Stop-Control-Connection-Notification(4).  Tunnel is 11, call is 0.
Mar  5 18:57:47 vpnserver l2tpd[8152]: control_finish: Peer tried to disconnect with invalid TID (11 != 24111)
Mar  5 18:57:47 vpnserver l2tpd[8152]: network_thread: bad packet
Mar  5 18:58:33 vpnserver l2tpd[8152]: Maximum retries exceeded for tunnel 42203.  Closing.
Mar  5 18:58:33 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 42203
Mar  5 18:58:33 vpnserver l2tpd[8152]: Connection 10 closed to 12.34.56.78, port 1701 (Timeout)
Mar  5 18:58:38 vpnserver l2tpd[8152]: Unable to deliver closing message for tunnel 42203. Destroying anyway.
Mar  5 18:58:38 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 42203
Mar  5 18:58:44 vpnserver l2tpd[8152]: Maximum retries exceeded for tunnel 24111.  Closing.
Mar  5 18:58:44 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 24111
Mar  5 18:58:44 vpnserver l2tpd[8152]: Connection 11 closed to 12.34.56.78, port 1701 (Timeout)
Mar  5 18:58:49 vpnserver l2tpd[8152]: Unable to deliver closing message for tunnel 24111. Destroying anyway.
Mar  5 18:58:49 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 24111
Mar  5 19:00:33 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 132, tunnel = 24111, call = 0
Mar  5 19:00:33 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 90.123.45.67, port 1701.
Mar  5 19:00:33 vpnserver l2tpd[8152]: ourtid = 56673, entropy_buf = dd61
Mar  5 19:00:33 vpnserver l2tpd[8152]: ourcid = 3185, entropy_buf = c71
Mar  5 19:00:33 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 19:00:33 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 56673, call 3185
Mar  5 19:00:33 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 19:00:33 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 19:00:33 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 19:00:33 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 19:00:33 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 19:00:33 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host2'
Mar  5 19:00:33 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 19:00:33 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 11
Mar  5 19:00:33 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 19:00:33 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 11, call is 0.
Mar  5 19:00:33 vpnserver l2tpd[8152]: control_finish: sending SCCRP
Mar  5 19:00:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 132, tunnel = 0, call = 0
Mar  5 19:00:35 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 90.123.45.67, port 1701.
Mar  5 19:00:35 vpnserver l2tpd[8152]: ourtid = 49180, entropy_buf = c01c
Mar  5 19:00:35 vpnserver l2tpd[8152]: ourcid = 26886, entropy_buf = 6906
Mar  5 19:00:35 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 19:00:35 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 49180, call 26886
Mar  5 19:00:35 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 19:00:35 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 19:00:35 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 19:00:35 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 19:00:35 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 19:00:35 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host2'
Mar  5 19:00:35 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 19:00:35 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 11
Mar  5 19:00:35 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 19:00:35 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 11, call is 0.
Mar  5 19:00:35 vpnserver l2tpd[8152]: control_finish: Peer requested tunnel 11 twice, ignoring second one.
Mar  5 19:00:35 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 49180
Mar  5 19:00:36 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 132, tunnel = 0, call = 0
Mar  5 19:00:36 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 90.123.45.67, port 1701.
Mar  5 19:00:36 vpnserver l2tpd[8152]: ourtid = 45611, entropy_buf = b22b
Mar  5 19:00:36 vpnserver l2tpd[8152]: ourcid = 9975, entropy_buf = 26f7
Mar  5 19:00:36 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 19:00:36 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 45611, call 9975
Mar  5 19:00:36 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 19:00:36 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 19:00:36 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 19:00:36 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 19:00:36 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 19:00:36 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host2'
Mar  5 19:00:36 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 19:00:36 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 11
Mar  5 19:00:36 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 19:00:36 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 11, call is 0.
Mar  5 19:00:36 vpnserver l2tpd[8152]: control_finish: Peer requested tunnel 11 twice, ignoring second one.
Mar  5 19:00:36 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 45611
Mar  5 19:00:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 132, tunnel = 0, call = 0
Mar  5 19:00:40 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 90.123.45.67, port 1701.
Mar  5 19:00:40 vpnserver l2tpd[8152]: ourtid = 21115, entropy_buf = 527b
Mar  5 19:00:40 vpnserver l2tpd[8152]: ourcid = 32824, entropy_buf = 8038
Mar  5 19:00:40 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 19:00:40 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 21115, call 32824
Mar  5 19:00:40 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 19:00:40 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 19:00:40 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 19:00:40 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 19:00:40 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 19:00:40 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host2'
Mar  5 19:00:40 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 19:00:40 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 11
Mar  5 19:00:40 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 19:00:40 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 11, call is 0.
Mar  5 19:00:40 vpnserver l2tpd[8152]: control_finish: Peer requested tunnel 11 twice, ignoring second one.
Mar  5 19:00:40 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 21115
Mar  5 19:00:40 vpnserver l2tpd[8152]: Maximum retries exceeded for tunnel 56673.  Closing.
Mar  5 19:00:40 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 56673
Mar  5 19:00:40 vpnserver l2tpd[8152]: Connection 11 closed to 90.123.45.67, port 1701 (Timeout)
Mar  5 19:00:45 vpnserver l2tpd[8152]: Unable to deliver closing message for tunnel 56673. Destroying anyway.
Mar  5 19:00:45 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 56673
Mar  5 19:00:48 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 132, tunnel = 0, call = 0
Mar  5 19:00:48 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 90.123.45.67, port 1701.
Mar  5 19:00:48 vpnserver l2tpd[8152]: ourtid = 6656, entropy_buf = 1a00
Mar  5 19:00:48 vpnserver l2tpd[8152]: ourcid = 23331, entropy_buf = 5b23
Mar  5 19:00:48 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 19:00:48 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 6656, call 23331
Mar  5 19:00:48 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 19:00:48 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 19:00:48 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 19:00:48 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 19:00:48 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 19:00:48 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host2'
Mar  5 19:00:48 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 19:00:48 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 11
Mar  5 19:00:48 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 19:00:48 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 11, call is 0.
Mar  5 19:00:48 vpnserver l2tpd[8152]: control_finish: sending SCCRP
Mar  5 19:00:53 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 20, tunnel = 0, call = 0
Mar  5 19:00:53 vpnserver l2tpd[8152]: get_call:can't find tunnel 56673
Mar  5 19:00:53 vpnserver l2tpd[8152]: network_thread: unable to find call or tunnel to handle packet.  call = 0, tunnel = 56673 Dumping.
Mar  5 19:00:53 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 48, tunnel = 56673, call = 0
Mar  5 19:00:53 vpnserver l2tpd[8152]: get_call:can't find tunnel 56673
Mar  5 19:00:53 vpnserver l2tpd[8152]: network_thread: unable to find call or tunnel to handle packet.  call = 0, tunnel = 56673 Dumping.
Mar  5 19:00:53 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 12, tunnel = 56673, call = 0
Mar  5 19:00:53 vpnserver l2tpd[8152]: get_call:can't find tunnel 56673
Mar  5 19:00:53 vpnserver l2tpd[8152]: network_thread: unable to find call or tunnel to handle packet.  call = 0, tunnel = 56673 Dumping.
Mar  5 19:00:53 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 12, tunnel = 56673, call = 0
Mar  5 19:00:53 vpnserver l2tpd[8152]: get_call:can't find tunnel 56673
Mar  5 19:00:53 vpnserver l2tpd[8152]: network_thread: unable to find call or tunnel to handle packet.  call = 0, tunnel = 56673 Dumping.
Mar  5 19:00:55 vpnserver l2tpd[8152]: Maximum retries exceeded for tunnel 6656.  Closing.
Mar  5 19:00:55 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 6656
Mar  5 19:00:55 vpnserver l2tpd[8152]: Connection 11 closed to 90.123.45.67, port 1701 (Timeout)
Mar  5 19:00:57 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 132, tunnel = 56673, call = 0
Mar  5 19:00:57 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 90.123.45.67, port 1701.
Mar  5 19:00:57 vpnserver l2tpd[8152]: ourtid = 30822, entropy_buf = 7866
Mar  5 19:00:57 vpnserver l2tpd[8152]: ourcid = 60261, entropy_buf = eb65
Mar  5 19:00:57 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 19:00:57 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 30822, call 60261
Mar  5 19:00:57 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 19:00:57 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 19:00:57 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 19:00:57 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 19:00:57 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 19:00:57 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host2'
Mar  5 19:00:57 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 19:00:57 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 12
Mar  5 19:00:57 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 19:00:57 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 12, call is 0.
Mar  5 19:00:57 vpnserver l2tpd[8152]: control_finish: sending SCCRP
Mar  5 19:00:59 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 132, tunnel = 0, call = 0
Mar  5 19:00:59 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 90.123.45.67, port 1701.
Mar  5 19:00:59 vpnserver l2tpd[8152]: ourtid = 34848, entropy_buf = 8820
Mar  5 19:00:59 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 19:00:59 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 34848, call 2037411698
Mar  5 19:00:59 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 19:00:59 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 19:00:59 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 19:00:59 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 19:00:59 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 19:00:59 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host2'
Mar  5 19:00:59 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 19:00:59 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 12
Mar  5 19:00:59 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 19:00:59 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 12, call is 0.
Mar  5 19:00:59 vpnserver l2tpd[8152]: control_finish: Peer requested tunnel 12 twice, ignoring second one.
Mar  5 19:00:59 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 34848
Mar  5 19:01:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 20, tunnel = 0, call = 0
Mar  5 19:01:00 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 1, Nr = 1
Mar  5 19:01:00 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 30822, call 60261
Mar  5 19:01:00 vpnserver l2tpd[8152]: message_type_avp: message type 3 (Start-Control-Connection-Connected)
Mar  5 19:01:00 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Connected(3).  Tunnel is 12, call is 0.
Mar  5 19:01:00 vpnserver l2tpd[8152]: Connection established to 90.123.45.67, 1701.  Local: 30822, Remote: 12.  LNS session is 'default'
Mar  5 19:01:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 48, tunnel = 30822, call = 0
Mar  5 19:01:00 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 2, Nr = 1
Mar  5 19:01:00 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 30822, call 60261
Mar  5 19:01:00 vpnserver l2tpd[8152]: message_type_avp: message type 10 (Incoming-Call-Request)
Mar  5 19:01:00 vpnserver l2tpd[8152]: message_type_avp: new incoming call
Mar  5 19:01:00 vpnserver l2tpd[8152]: ourcid = 35008, entropy_buf = 88c0
Mar  5 19:01:00 vpnserver l2tpd[8152]: assigned_call_avp: using peer's call 1
Mar  5 19:01:00 vpnserver l2tpd[8152]: call_serno_avp: serial number is 0
Mar  5 19:01:00 vpnserver l2tpd[8152]: bearer_type_avp: peer bears: analog
Mar  5 19:01:00 vpnserver l2tpd[8152]: control_finish: message type is Incoming-Call-Request(10).  Tunnel is 12, call is 0.
Mar  5 19:01:00 vpnserver l2tpd[8152]: control_finish: Sending ICRP
Mar  5 19:01:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 12, tunnel = 30822, call = 0
Mar  5 19:01:00 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 3, Nr = 1
Mar  5 19:01:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 48, tunnel = 30822, call = 0
Mar  5 19:01:00 vpnserver l2tpd[8152]: check_control: control, cid = 1, Ns = 3, Nr = 2
Mar  5 19:01:00 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 30822, call 35008
Mar  5 19:01:00 vpnserver l2tpd[8152]: message_type_avp: message type 12 (Incoming-Call-Connected)
Mar  5 19:01:00 vpnserver l2tpd[8152]: tx_speed_avp: transmit baud rate is 54000000
Mar  5 19:01:00 vpnserver l2tpd[8152]: frame_type_avp: peer uses:sync frames
Mar  5 19:01:00 vpnserver l2tpd[8152]: ignore_avp : Ignoring AVP
Mar  5 19:01:00 vpnserver l2tpd[8152]: control_finish: message type is Incoming-Call-Connected(12).  Tunnel is 12, call is 1.
Mar  5 19:01:00 vpnserver l2tpd[8152]: start_pppd: I'm running:
Mar  5 19:01:00 vpnserver l2tpd[8152]: "/usr/sbin/pppd"
Mar  5 19:01:00 vpnserver l2tpd[8152]: "passive"
Mar  5 19:01:00 vpnserver l2tpd[8152]: "-detach"
Mar  5 19:01:00 vpnserver l2tpd[8152]: "1.2.3.1:1.2.3.4"
Mar  5 19:01:00 vpnserver l2tpd[8152]: "refuse-pap"
Mar  5 19:01:00 vpnserver l2tpd[8152]: "auth"
Mar  5 19:01:00 vpnserver l2tpd[8152]: "require-chap"
Mar  5 19:01:00 vpnserver l2tpd[8152]: "name"
Mar  5 19:01:00 vpnserver l2tpd[8152]: "MyVPN"
Mar  5 19:01:00 vpnserver l2tpd[8152]: "debug"
Mar  5 19:01:00 vpnserver l2tpd[8152]: "file"
Mar  5 19:01:00 vpnserver l2tpd[8152]: "/etc/ppp/options.l2tpd"
Mar  5 19:01:00 vpnserver l2tpd[8152]: "/dev/pts/5"
Mar  5 19:01:00 vpnserver l2tpd[8152]:
Mar  5 19:01:00 vpnserver l2tpd[8152]: Call established with 90.123.45.67, Local: 35008, Remote: 1, Serial: 0
Mar  5 19:01:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 12, tunnel = 30822, call = 35008
Mar  5 19:01:00 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 4, Nr = 2
Mar  5 19:01:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 33, tunnel = 30822, call = 0
Mar  5 19:01:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 41, tunnel = 30822, call = 35008
Mar  5 19:01:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 30, tunnel = 30822, call = 35008
Mar  5 19:01:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 28, tunnel = 30822, call = 35008
Mar  5 19:01:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 40, tunnel = 30822, call = 35008
Mar  5 19:01:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 74, tunnel = 30822, call = 35008
Mar  5 19:01:01 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 20, tunnel = 30822, call = 35008
Mar  5 19:01:01 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 44, tunnel = 30822, call = 35008
Mar  5 19:01:01 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 20, tunnel = 30822, call = 35008
Mar  5 19:01:01 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 32, tunnel = 30822, call = 35008
Mar  5 19:01:01 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 20, tunnel = 30822, call = 35008
Mar  5 19:01:01 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 32, tunnel = 30822, call = 35008
Mar  5 19:01:01 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:01 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:01 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:01 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:01 vpnserver l2tpd[8152]: Unable to deliver closing message for tunnel 6656. Destroying anyway.
Mar  5 19:01:01 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 6656
Mar  5 19:01:01 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:01 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 337, tunnel = 30822, call = 35008
Mar  5 19:01:02 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:02 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:02 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:02 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:02 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:02 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:02 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:02 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:02 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:02 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:02 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:03 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:04 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:04 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:04 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:04 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:04 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 337, tunnel = 30822, call = 35008
Mar  5 19:01:05 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:07 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:07 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:07 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:07 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 100, tunnel = 30822, call = 35008
Mar  5 19:01:08 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 218, tunnel = 30822, call = 35008
Mar  5 19:01:08 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:08 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:08 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:09 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 69, tunnel = 30822, call = 35008
Mar  5 19:01:09 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:09 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 69, tunnel = 30822, call = 35008
Mar  5 19:01:09 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:09 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 186, tunnel = 30822, call = 35008
Mar  5 19:01:09 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 381, tunnel = 30822, call = 35008
Mar  5 19:01:09 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:09 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:09 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:09 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1400, tunnel = 30822, call = 35008
Mar  5 19:01:09 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1392, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 95, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 105, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 183, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 125, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 69, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 87, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 186, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 396, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 412, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 222, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 238, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:10 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 230, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 87, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 224, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 445, tunnel = 30822, call = 35008
Mar  5 19:01:11 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 87, tunnel = 30822, call = 35008
Mar  5 19:01:12 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 135, tunnel = 30822, call = 35008
Mar  5 19:01:12 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:12 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 222, tunnel = 30822, call = 35008
Mar  5 19:01:13 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 222, tunnel = 30822, call = 35008
Mar  5 19:01:13 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:15 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 222, tunnel = 30822, call = 35008
Mar  5 19:01:16 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:21 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 92, tunnel = 30822, call = 35008
Mar  5 19:01:21 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 88, tunnel = 30822, call = 35008
Mar  5 19:01:21 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 87, tunnel = 30822, call = 35008
Mar  5 19:01:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 121, tunnel = 30822, call = 35008
Mar  5 19:01:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 205, tunnel = 30822, call = 35008
Mar  5 19:01:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 237, tunnel = 30822, call = 35008
Mar  5 19:01:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 233, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 226, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 181, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 205, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 236, tunnel = 30822, call = 35008
Mar  5 19:01:30 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 232, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 205, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 226, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 149, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 237, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 205, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 233, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 233, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:31 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 226, tunnel = 30822, call = 35008
Mar  5 19:01:32 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 226, tunnel = 30822, call = 35008
Mar  5 19:01:32 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 501, tunnel = 30822, call = 35008
Mar  5 19:01:32 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 181, tunnel = 30822, call = 35008
Mar  5 19:01:32 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:32 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 261, tunnel = 30822, call = 35008
Mar  5 19:01:32 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:32 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 149, tunnel = 30822, call = 35008
Mar  5 19:01:32 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:32 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:33 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:33 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:33 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 245, tunnel = 30822, call = 35008
Mar  5 19:01:34 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:34 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:34 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 389, tunnel = 30822, call = 35008
Mar  5 19:01:34 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:34 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 245, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 165, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 197, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 501, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 437, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 1409, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 233, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 226, tunnel = 30822, call = 35008
Mar  5 19:01:35 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 245, tunnel = 30822, call = 35008
Mar  5 19:01:36 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 261, tunnel = 30822, call = 35008
Mar  5 19:01:36 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 165, tunnel = 30822, call = 35008
Mar  5 19:01:36 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:36 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 357, tunnel = 30822, call = 35008
Mar  5 19:01:36 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 245, tunnel = 30822, call = 35008
Mar  5 19:01:36 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 309, tunnel = 30822, call = 35008
Mar  5 19:01:36 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 197, tunnel = 30822, call = 35008
Mar  5 19:01:36 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:37 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 501, tunnel = 30822, call = 35008
Mar  5 19:01:37 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 325, tunnel = 30822, call = 35008
Mar  5 19:01:38 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:38 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 245, tunnel = 30822, call = 35008
Mar  5 19:01:38 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 197, tunnel = 30822, call = 35008
Mar  5 19:01:38 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:38 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 293, tunnel = 30822, call = 35008
Mar  5 19:01:38 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:01:38 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 277, tunnel = 30822, call = 35008
Mar  5 19:01:38 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:38 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:38 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:38 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:01:39 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 373, tunnel = 30822, call = 35008
Mar  5 19:01:39 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:39 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:01:39 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:01:39 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 71, tunnel = 30822, call = 35008
Mar  5 19:01:39 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:01:39 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 293, tunnel = 30822, call = 35008
Mar  5 19:01:39 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 245, tunnel = 30822, call = 35008
Mar  5 19:01:39 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 277, tunnel = 30822, call = 35008
Mar  5 19:01:39 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 293, tunnel = 30822, call = 35008
Mar  5 19:01:39 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:39 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:39 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 485, tunnel = 30822, call = 35008
Mar  5 19:01:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 181, tunnel = 30822, call = 35008
Mar  5 19:01:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:01:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 71, tunnel = 30822, call = 35008
Mar  5 19:01:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 197, tunnel = 30822, call = 35008
Mar  5 19:01:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 197, tunnel = 30822, call = 35008
Mar  5 19:01:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 197, tunnel = 30822, call = 35008
Mar  5 19:01:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:40 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 57, tunnel = 30822, call = 35008
Mar  5 19:01:41 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 197, tunnel = 30822, call = 35008
Mar  5 19:01:41 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:41 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:41 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 63, tunnel = 30822, call = 35008
Mar  5 19:01:41 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 261, tunnel = 30822, call = 35008
Mar  5 19:01:41 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 64, tunnel = 30822, call = 35008
Mar  5 19:01:41 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 55, tunnel = 30822, call = 35008
Mar  5 19:01:41 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 245, tunnel = 30822, call = 35008
Mar  5 19:01:41 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:41 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 261, tunnel = 30822, call = 35008
Mar  5 19:01:41 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 197, tunnel = 30822, call = 35008
Mar  5 19:01:41 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:42 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 55, tunnel = 30822, call = 35008
Mar  5 19:01:42 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 261, tunnel = 30822, call = 35008
Mar  5 19:01:42 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:42 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:42 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:43 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 693, tunnel = 30822, call = 35008
Mar  5 19:01:43 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:01:43 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:43 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:43 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:43 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 197, tunnel = 30822, call = 35008
Mar  5 19:01:43 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 277, tunnel = 30822, call = 35008
Mar  5 19:01:43 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 277, tunnel = 30822, call = 35008
Mar  5 19:01:44 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 373, tunnel = 30822, call = 35008
Mar  5 19:01:44 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 277, tunnel = 30822, call = 35008
Mar  5 19:01:44 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 261, tunnel = 30822, call = 35008
Mar  5 19:01:44 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 277, tunnel = 30822, call = 35008
Mar  5 19:01:44 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:44 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 245, tunnel = 30822, call = 35008
Mar  5 19:01:44 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:45 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:01:45 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:45 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:01:46 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 389, tunnel = 30822, call = 35008
Mar  5 19:01:46 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:47 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 245, tunnel = 30822, call = 35008
Mar  5 19:01:47 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 373, tunnel = 30822, call = 35008
Mar  5 19:01:47 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:47 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 245, tunnel = 30822, call = 35008
Mar  5 19:01:47 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:01:47 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:01:48 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 245, tunnel = 30822, call = 35008
Mar  5 19:01:48 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 293, tunnel = 30822, call = 35008
Mar  5 19:01:48 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:48 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:01:48 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:48 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:52 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:01:59 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:02:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 12, tunnel = 30822, call = 35008
Mar  5 19:02:00 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 4, Nr = 3
Mar  5 19:02:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 0
Mar  5 19:02:43 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:02:48 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 197, tunnel = 30822, call = 35008
Mar  5 19:02:48 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 197, tunnel = 30822, call = 35008
Mar  5 19:02:48 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:02:59 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:02:59 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:02:59 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 197, tunnel = 30822, call = 35008
Mar  5 19:02:59 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 261, tunnel = 30822, call = 35008
Mar  5 19:02:59 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 277, tunnel = 30822, call = 35008
Mar  5 19:02:59 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 229, tunnel = 30822, call = 35008
Mar  5 19:02:59 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 245, tunnel = 30822, call = 35008
Mar  5 19:03:00 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:03:01 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 12, tunnel = 30822, call = 35008
Mar  5 19:03:01 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 4, Nr = 4
Mar  5 19:03:04 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 341, tunnel = 30822, call = 0
Mar  5 19:03:04 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:03:04 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 213, tunnel = 30822, call = 35008
Mar  5 19:03:04 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 261, tunnel = 30822, call = 35008
Mar  5 19:03:05 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:03:12 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 49, tunnel = 30822, call = 35008
Mar  5 19:03:23 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 98, tunnel = 30822, call = 35008
Mar  5 19:03:23 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 12.34.56.78, port 1701.
Mar  5 19:03:23 vpnserver l2tpd[8152]: ourtid = 51221, entropy_buf = c815
Mar  5 19:03:23 vpnserver l2tpd[8152]: ourcid = 9608, entropy_buf = 2588
Mar  5 19:03:23 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 19:03:23 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 51221, call 9608
Mar  5 19:03:23 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 19:03:23 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 19:03:23 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 19:03:23 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 19:03:23 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 19:03:23 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host1'
Mar  5 19:03:23 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 19:03:23 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 12
Mar  5 19:03:23 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 19:03:23 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 12, call is 0.
Mar  5 19:03:23 vpnserver l2tpd[8152]: control_finish: sending SCCRP
Mar  5 19:03:25 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 98, tunnel = 0, call = 0
Mar  5 19:03:25 vpnserver l2tpd[8152]: get_call: allocating new tunnel for host 12.34.56.78, port 1701.
Mar  5 19:03:25 vpnserver l2tpd[8152]: ourtid = 36725, entropy_buf = 8f75
Mar  5 19:03:25 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 0, Nr = 0
Mar  5 19:03:25 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 36725, call 0
Mar  5 19:03:25 vpnserver l2tpd[8152]: message_type_avp: message type 1 (Start-Control-Connection-Request)
Mar  5 19:03:25 vpnserver l2tpd[8152]: protocol_version_avp: peer is using version 1, revision 0.
Mar  5 19:03:25 vpnserver l2tpd[8152]: framing_caps_avp: supported peer frames: sync
Mar  5 19:03:25 vpnserver l2tpd[8152]: bearer_caps_avp: supported peer bearers:
Mar  5 19:03:25 vpnserver l2tpd[8152]: firmware_rev_avp: peer reports firmware version 1280 (0x0500)
Mar  5 19:03:25 vpnserver l2tpd[8152]: hostname_avp: peer reports hostname 'host1'
Mar  5 19:03:25 vpnserver l2tpd[8152]: vendor_avp: peer reports vendor 'Microsoft'
Mar  5 19:03:25 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 12
Mar  5 19:03:25 vpnserver l2tpd[8152]: receive_window_size_avp: peer wants RWS of 8.  Will use flow control.
Mar  5 19:03:25 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Request(1).  Tunnel is 12, call is 0.
Mar  5 19:03:25 vpnserver l2tpd[8152]: control_finish: Peer requested tunnel 12 twice, ignoring second one.
Mar  5 19:03:25 vpnserver l2tpd[8152]: build_fdset: closing down tunnel 36725
Mar  5 19:03:25 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 0, call = 0
Mar  5 19:03:25 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 1, Nr = 1
Mar  5 19:03:25 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 51221, call 9608
Mar  5 19:03:25 vpnserver l2tpd[8152]: message_type_avp: message type 3 (Start-Control-Connection-Connected)
Mar  5 19:03:25 vpnserver l2tpd[8152]: control_finish: message type is Start-Control-Connection-Connected(3).  Tunnel is 12, call is 0.
Mar  5 19:03:25 vpnserver l2tpd[8152]: Connection established to 12.34.56.78, 1701.  Local: 51221, Remote: 12.  LNS session is 'default'
Mar  5 19:03:25 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 48, tunnel = 51221, call = 0
Mar  5 19:03:25 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 2, Nr = 1
Mar  5 19:03:25 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 51221, call 9608
Mar  5 19:03:25 vpnserver l2tpd[8152]: message_type_avp: message type 10 (Incoming-Call-Request)
Mar  5 19:03:25 vpnserver l2tpd[8152]: message_type_avp: new incoming call
Mar  5 19:03:25 vpnserver l2tpd[8152]: ourcid = 64125, entropy_buf = fa7d
Mar  5 19:03:25 vpnserver l2tpd[8152]: assigned_call_avp: using peer's call 1
Mar  5 19:03:25 vpnserver l2tpd[8152]: call_serno_avp: serial number is 0
Mar  5 19:03:25 vpnserver l2tpd[8152]: bearer_type_avp: peer bears: analog
Mar  5 19:03:25 vpnserver l2tpd[8152]: control_finish: message type is Incoming-Call-Request(10).  Tunnel is 12, call is 0.
Mar  5 19:03:25 vpnserver l2tpd[8152]: control_finish: Sending ICRP
Mar  5 19:03:25 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 12, tunnel = 51221, call = 0
Mar  5 19:03:25 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 3, Nr = 1
Mar  5 19:03:25 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 94, tunnel = 51221, call = 0
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 48, tunnel = 30822, call = 35008
Mar  5 19:03:26 vpnserver l2tpd[8152]: check_control: control, cid = 1, Ns = 3, Nr = 2
Mar  5 19:03:26 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 51221, call 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: message_type_avp: message type 12 (Incoming-Call-Connected)
Mar  5 19:03:26 vpnserver l2tpd[8152]: tx_speed_avp: transmit baud rate is 100000000
Mar  5 19:03:26 vpnserver l2tpd[8152]: frame_type_avp: peer uses:sync frames
Mar  5 19:03:26 vpnserver l2tpd[8152]: ignore_avp : Ignoring AVP
Mar  5 19:03:26 vpnserver l2tpd[8152]: control_finish: message type is Incoming-Call-Connected(12).  Tunnel is 12, call is 1.
Mar  5 19:03:26 vpnserver l2tpd[8152]: start_pppd: I'm running:
Mar  5 19:03:26 vpnserver l2tpd[8152]: "/usr/sbin/pppd"
Mar  5 19:03:26 vpnserver l2tpd[8152]: "passive"
Mar  5 19:03:26 vpnserver l2tpd[8152]: "-detach"
Mar  5 19:03:26 vpnserver l2tpd[8152]: "1.2.3.1:1.2.3.5"
Mar  5 19:03:26 vpnserver l2tpd[8152]: "refuse-pap"
Mar  5 19:03:26 vpnserver l2tpd[8152]: "auth"
Mar  5 19:03:26 vpnserver l2tpd[8152]: "require-chap"
Mar  5 19:03:26 vpnserver l2tpd[8152]: "name"
Mar  5 19:03:26 vpnserver l2tpd[8152]: "MyVPN"
Mar  5 19:03:26 vpnserver l2tpd[8152]: "debug"
Mar  5 19:03:26 vpnserver l2tpd[8152]: "file"
Mar  5 19:03:26 vpnserver l2tpd[8152]: "/etc/ppp/options.l2tpd"
Mar  5 19:03:26 vpnserver l2tpd[8152]: "/dev/pts/6"
Mar  5 19:03:26 vpnserver l2tpd[8152]:
Mar  5 19:03:26 vpnserver l2tpd[8152]: Call established with 12.34.56.78, Local: 64125, Remote: 1, Serial: 0
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 12, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 4, Nr = 2
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 33, tunnel = 51221, call = 0
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 41, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 30, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 28, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 31, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 76, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 44, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 32, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 20, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 32, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 337, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 170, tunnel = 51221, call = 64125
Mar  5 19:03:26 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 94, tunnel = 51221, call = 64125
Mar  5 19:03:27 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 30822, call = 35008
Mar  5 19:03:27 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 94, tunnel = 51221, call = 64125
Mar  5 19:03:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 105, tunnel = 30822, call = 35008
Mar  5 19:03:28 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 28, tunnel = 51221, call = 64125
Mar  5 19:03:29 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 108, tunnel = 51221, call = 64125
Mar  5 19:03:29 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 173, tunnel = 51221, call = 64125
Mar  5 19:03:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 94, tunnel = 51221, call = 64125
Mar  5 19:03:29 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 94, tunnel = 30822, call = 35008
Mar  5 19:03:31 vpnserver l2tpd[8152]: child_handler : pppd exited for call 1 with code 0
Mar  5 19:03:31 vpnserver l2tpd[8152]: call_close: Call 64125 to 12.34.56.78 disconnected
Mar  5 19:03:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 12, tunnel = 30822, call = 35008
Mar  5 19:03:31 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 4, Nr = 3
Mar  5 19:03:31 vpnserver l2tpd[8152]: network_thread: recv packet from 12.34.56.78, size = 38, tunnel = 51221, call = 0
Mar  5 19:03:31 vpnserver l2tpd[8152]: check_control: control, cid = 0, Ns = 4, Nr = 3
Mar  5 19:03:31 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 51221, call 9608
Mar  5 19:03:31 vpnserver l2tpd[8152]: message_type_avp: message type 4 (Stop-Control-Connection-Notification)
Mar  5 19:03:31 vpnserver l2tpd[8152]: assigned_tunnel_avp: using peer's tunnel 12
Mar  5 19:03:31 vpnserver l2tpd[8152]: result_code_avp: peer closing for reason 6 (Requester is being shut down), error = 0 ()
Mar  5 19:03:31 vpnserver l2tpd[8152]: control_finish: message type is Stop-Control-Connection-Notification(4).  Tunnel is 12, call is 0.
Mar  5 19:03:31 vpnserver l2tpd[8152]: control_finish: Peer tried to disconnect with invalid TID (12 != 51221)
Mar  5 19:03:31 vpnserver l2tpd[8152]: network_thread: bad packet
Mar  5 19:03:33 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 94, tunnel = 51221, call = 0
Mar  5 19:03:33 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 94, tunnel = 30822, call = 35008
Mar  5 19:03:46 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 28, tunnel = 30822, call = 35008
Mar  5 19:03:46 vpnserver l2tpd[8152]: network_thread: recv packet from 90.123.45.67, size = 38, tunnel = 30822, call = 35008
Mar  5 19:03:46 vpnserver l2tpd[8152]: check_control: control, cid = 1, Ns = 4, Nr = 4
Mar  5 19:03:46 vpnserver l2tpd[8152]: handle_avps: handling avp's for tunnel 30822, call 35008
Mar  5 19:03:46 vpnserver l2tpd[8152]: message_type_avp: message type 14 (Call-Disconnect-Notify)
Mar  5 19:03:46 vpnserver l2tpd[8152]: result_code_avp: peer closing for reason 3 (Control channel already exists), error = 0 ()
Mar  5 19:03:46 vpnserver l2tpd[8152]: assigned_call_avp: using peer's call 1
Mar  5 19:03:46 vpnserver l2tpd[8152]: control_finish: message type is Call-Disconnect-Notify(14).  Tunnel is 12, call is 1.
Mar  5 19:03:46 vpnserver l2tpd[8152]: control_finish: Connection closed to 90.123.45.67, serial 0 ()
*** DIES HERE ***
Mar  5 19:11:06 vpnserver l2tpd[8152]: death_handler: Fatal signal 15 received
Mar  5 19:11:06 vpnserver l2tpd[9223]: This binary does not support kernel L2TP.
Mar  5 19:11:06 vpnserver l2tpd[9224]: l2tpd version 0.69-FedoraExtra started on vpnserver.network.local PID:9224
Mar  5 19:11:06 vpnserver l2tpd[9224]: Written by Mark Spencer, Copyright (C) 1998, Adtran, Inc.
Mar  5 19:11:06 vpnserver l2tpd[9224]: Forked by Scott Balmos and David Stipp, (C) 2001
Mar  5 19:11:06 vpnserver l2tpd[9224]: Inherited by Jeff McAdams, (C) 2002
Mar  5 19:11:06 vpnserver l2tpd[9224]: Listening on IP address 0.0.0.0, port 1701


More information about the Users mailing list