[strongSwan] Win7 IKEv1 L2TP rekeying fails
Lars Mueller
lars at perfect-privacy.com
Wed Dec 18 16:22:50 CET 2013
Hi,
Im trying to get Windows 7 connected via l2tp/ipsec and PSK's , but,
after ~175Mb of traffic, windows will try to rekey, this fails and all
traffic will be stalled. All of the rekeying looks fine until i get
"received DELETE for ESP CHILD_SA with SPI" for no apparent reason.
After disconnecting and reconnecting, the connection is fine for another
175 MB. I found some other people [1], that have the same Problem, but
so solution either.
My windows client is behind NAT, my server is Not. I tried forceencaps
on the server, i tried setting AssumeUDPEncapsulationContextOnSendRule
on the client site as mentioned on Microsofts support page [2].
I tried all combinations of rekey,reauth ,margin, marginbytes, keylife
and so on. I tried ike=aes128-sha1-modp1024, and esp=aes128-sha1 and
various combinations of these Options.
I was using strongswan 4.5 with an older version of xl2tp, I'm now
using strongswan 5.1.1 and the newest version of xl2tp.
I still have the same Problem. Im working on nothing else for the last
few days, without any success.
Any idea why this does not work? Should it work? A Windows client behind
NAT using L2TP/IPSEC is nothing that special, am i hitting some
obscurity? I tested this with multiple windows installations and on
multiple servers, so its not a random behavior of a single system.
Greetings
Lars
[1]
http://social.technet.microsoft.com/Forums/windows/en-US/3cdcc19d-d856-40ec-8b16-1d1ad7bcf01f/windows-7-ipsecl2tp-vpn-disconnects?forum=w7itpronetworking
[2] http://support.microsoft.com/kb/926179
Here is my IPsec Config:
config setup
charondebug="dmn 3,mgr 3,ike 3,chd 3, knl 3,net 3,asn 3"
conn %default
left=94.242.255.33
leftfirewall=no
right=%any
conn psk-l2tp
keyexchange=ikev1
authby=psk
rekey=no
type=tunnel
leftnexthop=%defaultroute
leftprotoport=17/1701
rightprotoport=17/%any
rightsubnetwithin=0.0.0.0/0
auto=add
Server IP: 94.242.255.33 ( This is a public test server, username,
password and PSK is "test" )
Client IP: 66.77.88.99 (original IP replaced to hide my home IP)
==> syslog <==
#### Startup ####
Dec 18 12:16:00 base_testgroup charon: 00[DMN] signal of type SIGINT
received. Shutting down
Dec 18 12:16:00 base_testgroup charon: 00[MGR] going to destroy IKE_SA
manager and all managed IKE_SA's
Dec 18 12:16:00 base_testgroup charon: 00[MGR] set driveout flags for
all stored IKE_SA's
Dec 18 12:16:00 base_testgroup charon: 00[MGR] wait for all threads to
leave IKE_SA's
Dec 18 12:16:00 base_testgroup charon: 00[MGR] delete all IKE_SA's
Dec 18 12:16:00 base_testgroup charon: 00[MGR] destroy all entries
Dec 18 12:16:01 base_testgroup charon: 00[DMN] Starting IKE charon
daemon (strongSwan 5.1.1, Linux 2.6.32-358.6.2.el6.x86_64, x86_64)
Dec 18 12:16:01 base_testgroup charon: 00[KNL] detected Linux 2.6.32, no
support for RTA_PREFSRC for IPv6 routes
Dec 18 12:16:01 base_testgroup charon: 00[KNL] known interfaces and IP
addresses:
Dec 18 12:16:01 base_testgroup charon: 00[KNL] lo
Dec 18 12:16:01 base_testgroup charon: 00[KNL] 127.0.0.1
Dec 18 12:16:01 base_testgroup charon: 00[KNL] eth0
Dec 18 12:16:01 base_testgroup charon: 00[KNL] 94.242.255.33
Dec 18 12:16:01 base_testgroup charon: 00[KNL] tun0
Dec 18 12:16:01 base_testgroup charon: 00[KNL] 10.1.21.1
Dec 18 12:16:01 base_testgroup charon: 00[KNL] tun1
Dec 18 12:16:01 base_testgroup charon: 00[KNL] 10.1.22.1
Dec 18 12:16:01 base_testgroup charon: 00[KNL] tun2
Dec 18 12:16:01 base_testgroup charon: 00[KNL] 10.1.23.1
Dec 18 12:16:01 base_testgroup charon: 00[KNL] tun3
Dec 18 12:16:01 base_testgroup charon: 00[KNL] 10.1.24.1
Dec 18 12:16:01 base_testgroup charon: 00[CFG] loading ca certificates
from '/usr/local/etc/ipsec.d/cacerts'
Dec 18 12:16:01 base_testgroup charon: 00[CFG] loading aa certificates
from '/usr/local/etc/ipsec.d/aacerts'
Dec 18 12:16:01 base_testgroup charon: 00[CFG] loading ocsp signer
certificates from '/usr/local/etc/ipsec.d/ocspcerts'
Dec 18 12:16:01 base_testgroup charon: 00[CFG] loading attribute
certificates from '/usr/local/etc/ipsec.d/acerts'
Dec 18 12:16:01 base_testgroup charon: 00[CFG] loading crls from
'/usr/local/etc/ipsec.d/crls'
Dec 18 12:16:01 base_testgroup charon: 00[CFG] loading secrets from
'/usr/local/etc/ipsec.secrets'
Dec 18 12:16:01 base_testgroup charon: 00[CFG] loaded IKE secret for %any
Dec 18 12:16:01 base_testgroup charon: 00[CFG] loaded EAP secret for test
Dec 18 12:16:01 base_testgroup charon: 00[CFG] loaded EAP secret for test
Dec 18 12:16:01 base_testgroup charon: 00[LIB] loaded plugins: charon
aes des rc2 sha1 sha2 md5 random nonce x509 revocation constraints
pubkey pkcs1 pkcs7 pkcs8 pkcs12 pgp dnskey sshkey pem openssl fips-prf
gmp xcbc cmac hmac attr kernel-netlink resolve socket-default stroke
updown xauth-generic
Dec 18 12:16:01 base_testgroup charon: 00[LIB] unable to load 5 plugin
features (5 due to unmet dependencies)
Dec 18 12:16:01 base_testgroup charon: 00[JOB] spawning 16 worker threads
Dec 18 12:16:01 base_testgroup charon: 01[NET] waiting for data on sockets
Dec 18 12:16:01 base_testgroup charon: 07[CFG] received stroke: add
connection 'psk-l2tp'
Dec 18 12:16:01 base_testgroup charon: 07[CFG] added configuration
'psk-l2tp'
#### Windows 7 Client connect ####
Dec 18 12:16:12 base_testgroup charon: 01[NET] received packet => 384
bytes @ 0x7fd8ac34e3b0
Dec 18 12:16:12 base_testgroup charon: 01[NET] 0: 8E 7C 68 6D 8A 91
81 C0 00 00 00 00 00 00 00 00 .|hm............
Dec 18 12:16:12 base_testgroup charon: 01[NET] 16: 01 10 02 00 00 00
00 00 00 00 01 80 0D 00 00 D4 ................
Dec 18 12:16:12 base_testgroup charon: 01[NET] 32: 00 00 00 01 00 00
00 01 00 00 00 C8 01 01 00 05 ................
Dec 18 12:16:12 base_testgroup charon: 01[NET] 48: 03 00 00 28 01 01
00 00 80 01 00 07 80 0E 01 00 ...(............
Dec 18 12:16:12 base_testgroup charon: 01[NET] 64: 80 02 00 02 80 04
00 14 80 03 00 01 80 0B 00 01 ................
Dec 18 12:16:12 base_testgroup charon: 01[NET] 80: 00 0C 00 04 00 00
70 80 03 00 00 28 02 01 00 00 ......p....(....
Dec 18 12:16:12 base_testgroup charon: 01[NET] 96: 80 01 00 07 80 0E
00 80 80 02 00 02 80 04 00 13 ................
Dec 18 12:16:12 base_testgroup charon: 01[NET] 112: 80 03 00 01 80 0B
00 01 00 0C 00 04 00 00 70 80 ..............p.
Dec 18 12:16:12 base_testgroup charon: 01[NET] 128: 03 00 00 28 03 01
00 00 80 01 00 07 80 0E 01 00 ...(............
Dec 18 12:16:12 base_testgroup charon: 01[NET] 144: 80 02 00 02 80 04
00 0E 80 03 00 01 80 0B 00 01 ................
Dec 18 12:16:12 base_testgroup charon: 01[NET] 160: 00 0C 00 04 00 00
70 80 03 00 00 24 04 01 00 00 ......p....$....
Dec 18 12:16:12 base_testgroup charon: 01[NET] 176: 80 01 00 05 80 02
00 02 80 04 00 0E 80 03 00 01 ................
Dec 18 12:16:12 base_testgroup charon: 01[NET] 192: 80 0B 00 01 00 0C
00 04 00 00 70 80 00 00 00 24 ..........p....$
Dec 18 12:16:12 base_testgroup charon: 01[NET] 208: 05 01 00 00 80 01
00 05 80 02 00 02 80 04 00 02 ................
Dec 18 12:16:12 base_testgroup charon: 01[NET] 224: 80 03 00 01 80 0B
00 01 00 0C 00 04 00 00 70 80 ..............p.
Dec 18 12:16:12 base_testgroup charon: 01[NET] 240: 0D 00 00 18 1E 2B
51 69 05 99 1C 7D 7C 96 FC BF .....+Qi...}|...
Dec 18 12:16:12 base_testgroup charon: 01[NET] 256: B5 87 E4 61 00 00
00 08 0D 00 00 14 4A 13 1C 81 ...a........J...
Dec 18 12:16:12 base_testgroup charon: 01[NET] 272: 07 03 58 45 5C 57
28 F2 0E 95 45 2F 0D 00 00 14 ..XE\W(...E/....
Dec 18 12:16:12 base_testgroup charon: 01[NET] 288: 90 CB 80 91 3E BB
69 6E 08 63 81 B5 EC 42 7B 1F ....>.in.c...B{.
Dec 18 12:16:12 base_testgroup charon: 01[NET] 304: 0D 00 00 14 40 48
B7 D5 6E BC E8 85 25 E7 DE 7F .... at H..n...%...
Dec 18 12:16:12 base_testgroup charon: 01[NET] 320: 00 D6 C2 D3 0D 00
00 14 FB 1D E3 CD F3 41 B7 EA .............A..
Dec 18 12:16:12 base_testgroup charon: 01[NET] 336: 16 B7 E5 BE 08 55
F1 20 0D 00 00 14 26 24 4D 38 .....U. ....&$M8
Dec 18 12:16:12 base_testgroup charon: 01[NET] 352: ED DB 61 B3 17 2A
36 E3 D0 CF B8 19 00 00 00 14 ..a..*6.........
Dec 18 12:16:12 base_testgroup charon: 01[NET] 368: E3 A5 96 6A 76 37
9F E7 07 22 82 31 E5 CE 86 52 ...jv7...".1...R
Dec 18 12:16:12 base_testgroup charon: 01[NET] received packet: from
66.77.88.99[56367] to 94.242.255.33[500]
Dec 18 12:16:12 base_testgroup charon: 01[NET] waiting for data on sockets
Dec 18 12:16:12 base_testgroup charon: 06[MGR] checkout IKE_SA by message
Dec 18 12:16:12 base_testgroup charon: 06[MGR] created IKE_SA (unnamed)[1]
Dec 18 12:16:12 base_testgroup charon: 06[NET] received packet: from
66.77.88.99[56367] to 94.242.255.33[500] (384 bytes)
Dec 18 12:16:12 base_testgroup charon: 06[ENC] parsed ID_PROT request 0
[ SA V V V V V V V ]
Dec 18 12:16:12 base_testgroup charon: 06[ENC] received unknown vendor
ID: 1e:2b:51:69:05:99:1c:7d:7c:96:fc:bf:b5:87:e4:61:00:00:00:08
Dec 18 12:16:12 base_testgroup charon: 06[IKE] received NAT-T (RFC 3947)
vendor ID
Dec 18 12:16:12 base_testgroup charon: 06[IKE] received
draft-ietf-ipsec-nat-t-ike-02\n vendor ID
Dec 18 12:16:12 base_testgroup charon: 06[ENC] received unknown vendor
ID: 40:48:b7:d5:6e:bc:e8:85:25:e7:de:7f:00:d6:c2:d3
Dec 18 12:16:12 base_testgroup charon: 06[ENC] received unknown vendor
ID: fb:1d:e3:cd:f3:41:b7:ea:16:b7:e5:be:08:55:f1:20
Dec 18 12:16:12 base_testgroup charon: 06[ENC] received unknown vendor
ID: 26:24:4d:38:ed:db:61:b3:17:2a:36:e3:d0:cf:b8:19
Dec 18 12:16:12 base_testgroup charon: 06[ENC] received unknown vendor
ID: e3:a5:96:6a:76:37:9f:e7:07:22:82:31:e5:ce:86:52
Dec 18 12:16:12 base_testgroup charon: 06[IKE] 66.77.88.99 is initiating
a Main Mode IKE_SA
Dec 18 12:16:12 base_testgroup charon: 06[IKE] IKE_SA (unnamed)[1] state
change: CREATED => CONNECTING
Dec 18 12:16:12 base_testgroup charon: 06[IKE] sending XAuth vendor ID
Dec 18 12:16:12 base_testgroup charon: 06[IKE] sending DPD vendor ID
Dec 18 12:16:12 base_testgroup charon: 06[IKE] sending NAT-T (RFC 3947)
vendor ID
Dec 18 12:16:12 base_testgroup charon: 06[ENC] generating ID_PROT
response 0 [ SA V V V ]
Dec 18 12:16:12 base_testgroup charon: 06[NET] sending packet: from
94.242.255.33[500] to 66.77.88.99[56367] (136 bytes)
Dec 18 12:16:12 base_testgroup charon: 06[MGR] checkin IKE_SA (unnamed)[1]
Dec 18 12:16:12 base_testgroup charon: 06[MGR] check-in of IKE_SA
successful.
Dec 18 12:16:12 base_testgroup charon: 04[NET] sending packet: from
94.242.255.33[500] to 66.77.88.99[56367]
Dec 18 12:16:12 base_testgroup charon: 01[NET] received packet => 228
bytes @ 0x7fd8ac34e3b0
Dec 18 12:16:12 base_testgroup charon: 01[NET] 0: 8E 7C 68 6D 8A 91
81 C0 90 95 42 E6 C7 1B 07 05 .|hm......B.....
Dec 18 12:16:12 base_testgroup charon: 01[NET] 16: 04 10 02 00 00 00
00 00 00 00 00 E4 0A 00 00 64 ...............d
Dec 18 12:16:12 base_testgroup charon: 01[NET] 32: 92 5F B7 A9 FC 9A
5A D4 3B 75 E3 92 22 52 9E 83 ._....Z.;u.."R..
Dec 18 12:16:12 base_testgroup charon: 01[NET] 48: 35 C7 42 F3 FB E9
A8 CC E6 5C 5B 1A C0 F7 A7 D0 5.B......\[.....
Dec 18 12:16:12 base_testgroup charon: 01[NET] 64: 3B 78 AF DE B2 ED
82 48 50 67 B7 D4 F1 27 FA 30 ;x.....HPg...'.0
Dec 18 12:16:12 base_testgroup charon: 01[NET] 80: 4C EB 62 D5 E3 92
41 A9 B8 52 A7 B9 64 7B 77 21 L.b...A..R..d{w!
Dec 18 12:16:12 base_testgroup charon: 01[NET] 96: 72 B9 8A 8F 73 9B
D3 A7 C8 4A 26 2E D7 1F 9D 21 r...s....J&....!
Dec 18 12:16:12 base_testgroup charon: 01[NET] 112: 0F 80 7E 1E A2 51
B5 E1 99 85 81 CE 80 E8 75 99 ..~..Q........u.
Dec 18 12:16:12 base_testgroup charon: 01[NET] 128: 14 00 00 34 E3 D9
83 28 ED 6B 93 77 E5 AE FE E7 ...4...(.k.w....
Dec 18 12:16:12 base_testgroup charon: 01[NET] 144: 52 9F 5B A7 17 78
D8 FE 8C 97 95 1B A2 39 1B 28 R.[..x.......9.(
Dec 18 12:16:12 base_testgroup charon: 01[NET] 160: AE 9B B4 DE 4B 89
2B 27 5F 0A 75 C2 B4 1F AC A3 ....K.+'_.u.....
Dec 18 12:16:12 base_testgroup charon: 01[NET] 176: 00 EF 0B B0 14 00
00 18 AE A9 90 4C B3 21 95 3F ...........L.!.?
Dec 18 12:16:12 base_testgroup charon: 01[NET] 192: FE 84 CF 92 12 2C
2E 9F 5E 64 C9 A8 00 00 00 18 .....,..^d......
Dec 18 12:16:12 base_testgroup charon: 01[NET] 208: 78 2E E7 C7 EF A8
AE 97 6F BD 6D B3 8A 4A BF DA x.......o.m..J..
Dec 18 12:16:12 base_testgroup charon: 01[NET] 224: 80 4F 55
69 .OUi
Dec 18 12:16:12 base_testgroup charon: 01[NET] received packet: from
66.77.88.99[56367] to 94.242.255.33[500]
Dec 18 12:16:12 base_testgroup charon: 01[NET] waiting for data on sockets
Dec 18 12:16:12 base_testgroup charon: 08[MGR] checkout IKE_SA by message
Dec 18 12:16:12 base_testgroup charon: 08[MGR] IKE_SA (unnamed)[1]
successfully checked out
Dec 18 12:16:12 base_testgroup charon: 08[NET] received packet: from
66.77.88.99[56367] to 94.242.255.33[500] (228 bytes)
Dec 18 12:16:12 base_testgroup charon: 08[ENC] parsed ID_PROT request 0
[ KE No NAT-D NAT-D ]
Dec 18 12:16:12 base_testgroup charon: 08[IKE] natd_chunk => 22 bytes @
0x7fd8a7d49a20
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 0: 8E 7C 68 6D 8A 91
81 C0 90 95 42 E6 C7 1B 07 05 .|hm......B.....
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 16: 5E F2 FF 21 01
F4 ^..!..
Dec 18 12:16:12 base_testgroup charon: 08[IKE] natd_hash => 20 bytes @
0x1e0fac0
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 0: AE A9 90 4C B3 21
95 3F FE 84 CF 92 12 2C 2E 9F ...L.!.?.....,..
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 16: 5E 64 C9
A8 ^d..
Dec 18 12:16:12 base_testgroup charon: 08[IKE] natd_chunk => 22 bytes @
0x7fd8a7d49a20
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 0: 8E 7C 68 6D 8A 91
81 C0 90 95 42 E6 C7 1B 07 05 .|hm......B.....
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 16: 57 93 97 43 DC
2F W..C./
Dec 18 12:16:12 base_testgroup charon: 08[IKE] natd_hash => 20 bytes @
0x1e0fae0
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 0: AE 05 64 22 74 6B
82 E0 45 B0 49 57 09 42 49 34 ..d"tk..E.IW.BI4
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 16: DC 15 7D
3D ..}=
Dec 18 12:16:12 base_testgroup charon: 08[IKE] precalculated src_hash =>
20 bytes @ 0x1e0fae0
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 0: AE 05 64 22 74 6B
82 E0 45 B0 49 57 09 42 49 34 ..d"tk..E.IW.BI4
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 16: DC 15 7D
3D ..}=
Dec 18 12:16:12 base_testgroup charon: 08[IKE] precalculated dst_hash =>
20 bytes @ 0x1e0fac0
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 0: AE A9 90 4C B3 21
95 3F FE 84 CF 92 12 2C 2E 9F ...L.!.?.....,..
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 16: 5E 64 C9
A8 ^d..
Dec 18 12:16:12 base_testgroup charon: 08[IKE] received dst_hash => 20
bytes @ 0x1e0a770
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 0: AE A9 90 4C B3 21
95 3F FE 84 CF 92 12 2C 2E 9F ...L.!.?.....,..
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 16: 5E 64 C9
A8 ^d..
Dec 18 12:16:12 base_testgroup charon: 08[IKE] received src_hash => 20
bytes @ 0x1e0a7b0
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 0: 78 2E E7 C7 EF A8
AE 97 6F BD 6D B3 8A 4A BF DA x.......o.m..J..
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 16: 80 4F 55
69 .OUi
Dec 18 12:16:12 base_testgroup charon: 08[IKE] remote host is behind NAT
Dec 18 12:16:12 base_testgroup charon: 08[IKE] natd_chunk => 22 bytes @
0x7fd8a7d49a10
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 0: 8E 7C 68 6D 8A 91
81 C0 90 95 42 E6 C7 1B 07 05 .|hm......B.....
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 16: 57 93 97 43 DC
2F W..C./
Dec 18 12:16:12 base_testgroup charon: 08[IKE] natd_hash => 20 bytes @
0x1e0c3a0
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 0: AE 05 64 22 74 6B
82 E0 45 B0 49 57 09 42 49 34 ..d"tk..E.IW.BI4
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 16: DC 15 7D
3D ..}=
Dec 18 12:16:12 base_testgroup charon: 08[IKE] natd_chunk => 22 bytes @
0x7fd8a7d49a10
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 0: 8E 7C 68 6D 8A 91
81 C0 90 95 42 E6 C7 1B 07 05 .|hm......B.....
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 16: 5E F2 FF 21 01
F4 ^..!..
Dec 18 12:16:12 base_testgroup charon: 08[IKE] natd_hash => 20 bytes @
0x1e09460
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 0: AE A9 90 4C B3 21
95 3F FE 84 CF 92 12 2C 2E 9F ...L.!.?.....,..
Dec 18 12:16:12 base_testgroup charon: 08[IKE] 16: 5E 64 C9
A8 ^d..
Dec 18 12:16:12 base_testgroup charon: 08[ENC] generating ID_PROT
response 0 [ KE No NAT-D NAT-D ]
Dec 18 12:16:12 base_testgroup charon: 08[NET] sending packet: from
94.242.255.33[500] to 66.77.88.99[56367] (212 bytes)
Dec 18 12:16:12 base_testgroup charon: 08[MGR] checkin IKE_SA (unnamed)[1]
Dec 18 12:16:12 base_testgroup charon: 08[MGR] check-in of IKE_SA
successful.
Dec 18 12:16:12 base_testgroup charon: 04[NET] sending packet: from
94.242.255.33[500] to 66.77.88.99[56367]
Dec 18 12:16:12 base_testgroup charon: 01[NET] received packet => 80
bytes @ 0x7fd8ac34e3b0
Dec 18 12:16:12 base_testgroup charon: 01[NET] 0: 00 00 00 00 8E 7C
68 6D 8A 91 81 C0 90 95 42 E6 .....|hm......B.
Dec 18 12:16:12 base_testgroup charon: 01[NET] 16: C7 1B 07 05 05 10
02 01 00 00 00 00 00 00 00 4C ...............L
Dec 18 12:16:12 base_testgroup charon: 01[NET] 32: FB 7F A9 BD 88 ED
56 81 CC D8 96 BC 4E 06 AA 47 ......V.....N..G
Dec 18 12:16:12 base_testgroup charon: 01[NET] 48: 44 41 58 53 F7 EC
83 05 56 30 7F 35 21 F0 A0 EF DAXS....V0.5!...
Dec 18 12:16:12 base_testgroup charon: 01[NET] 64: B9 27 D9 2C B5 C5
5B F5 FF EB 0B F7 7D E6 73 4D .'.,..[.....}.sM
Dec 18 12:16:12 base_testgroup charon: 01[NET] received packet: from
66.77.88.99[57001] to 94.242.255.33[4500]
Dec 18 12:16:12 base_testgroup charon: 01[NET] waiting for data on sockets
Dec 18 12:16:12 base_testgroup charon: 10[MGR] checkout IKE_SA by message
Dec 18 12:16:12 base_testgroup charon: 10[MGR] IKE_SA (unnamed)[1]
successfully checked out
Dec 18 12:16:12 base_testgroup charon: 10[NET] received packet: from
66.77.88.99[57001] to 94.242.255.33[4500] (76 bytes)
Dec 18 12:16:12 base_testgroup charon: 10[ENC] parsed ID_PROT request 0
[ ID HASH ]
Dec 18 12:16:12 base_testgroup charon: 10[CFG] looking for pre-shared
key peer configs matching 94.242.255.33...66.77.88.99[192.168.197.137]
Dec 18 12:16:12 base_testgroup charon: 10[CFG] selected peer config
"psk-l2tp"
Dec 18 12:16:12 base_testgroup charon: 10[IKE] HASH_I data => 424 bytes
@ 0x1e0f580
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 0: 92 5F B7 A9 FC 9A
5A D4 3B 75 E3 92 22 52 9E 83 ._....Z.;u.."R..
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 16: 35 C7 42 F3 FB E9
A8 CC E6 5C 5B 1A C0 F7 A7 D0 5.B......\[.....
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 32: 3B 78 AF DE B2 ED
82 48 50 67 B7 D4 F1 27 FA 30 ;x.....HPg...'.0
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 48: 4C EB 62 D5 E3 92
41 A9 B8 52 A7 B9 64 7B 77 21 L.b...A..R..d{w!
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 64: 72 B9 8A 8F 73 9B
D3 A7 C8 4A 26 2E D7 1F 9D 21 r...s....J&....!
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 80: 0F 80 7E 1E A2 51
B5 E1 99 85 81 CE 80 E8 75 99 ..~..Q........u.
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 96: 3F 9F EC 09 4B ED
35 61 96 92 15 5C 96 C5 78 54 ?...K.5a...\..xT
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 112: 2C 5A 95 89 1A CF
41 60 AE 9E 81 D4 78 31 4C D3 ,Z....A`....x1L.
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 128: 7B CE 95 D2 14 3D
B8 F9 A4 69 72 0C 1D 15 0E FE {....=...ir.....
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 144: C8 10 34 F0 6C 6E
58 FA 92 F3 95 54 16 61 DE FB ..4.lnX....T.a..
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 160: BB CE 04 B9 0D BA
10 7C 59 10 FF 3F 84 2D F4 75 .......|Y..?.-.u
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 176: B3 F1 89 3C 12 61
77 C1 0D 89 85 8A 30 D4 04 23 ...<.aw.....0..#
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 192: 8E 7C 68 6D 8A 91
81 C0 90 95 42 E6 C7 1B 07 05 .|hm......B.....
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 208: 00 00 00 01 00 00
00 01 00 00 00 C8 01 01 00 05 ................
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 224: 03 00 00 28 01 01
00 00 80 01 00 07 80 0E 01 00 ...(............
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 240: 80 02 00 02 80 04
00 14 80 03 00 01 80 0B 00 01 ................
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 256: 00 0C 00 04 00 00
70 80 03 00 00 28 02 01 00 00 ......p....(....
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 272: 80 01 00 07 80 0E
00 80 80 02 00 02 80 04 00 13 ................
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 288: 80 03 00 01 80 0B
00 01 00 0C 00 04 00 00 70 80 ..............p.
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 304: 03 00 00 28 03 01
00 00 80 01 00 07 80 0E 01 00 ...(............
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 320: 80 02 00 02 80 04
00 0E 80 03 00 01 80 0B 00 01 ................
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 336: 00 0C 00 04 00 00
70 80 03 00 00 24 04 01 00 00 ......p....$....
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 352: 80 01 00 05 80 02
00 02 80 04 00 0E 80 03 00 01 ................
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 368: 80 0B 00 01 00 0C
00 04 00 00 70 80 00 00 00 24 ..........p....$
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 384: 05 01 00 00 80 01
00 05 80 02 00 02 80 04 00 02 ................
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 400: 80 03 00 01 80 0B
00 01 00 0C 00 04 00 00 70 80 ..............p.
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 416: 01 00 00 00 C0 A8
C5 89 ........
Dec 18 12:16:12 base_testgroup charon: 10[IKE] HASH_I => 20 bytes @
0x1e0c2e0
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 0: C4 D0 C9 F7 12 63
FD 5A EE 4D 7D 8C EF 74 A0 F2 .....c.Z.M}..t..
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 16: FC 1F 7E
29 ..~)
Dec 18 12:16:12 base_testgroup charon: 10[IKE] HASH_R data => 424 bytes
@ 0x1e0d300
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 0: 3F 9F EC 09 4B ED
35 61 96 92 15 5C 96 C5 78 54 ?...K.5a...\..xT
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 16: 2C 5A 95 89 1A CF
41 60 AE 9E 81 D4 78 31 4C D3 ,Z....A`....x1L.
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 32: 7B CE 95 D2 14 3D
B8 F9 A4 69 72 0C 1D 15 0E FE {....=...ir.....
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 48: C8 10 34 F0 6C 6E
58 FA 92 F3 95 54 16 61 DE FB ..4.lnX....T.a..
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 64: BB CE 04 B9 0D BA
10 7C 59 10 FF 3F 84 2D F4 75 .......|Y..?.-.u
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 80: B3 F1 89 3C 12 61
77 C1 0D 89 85 8A 30 D4 04 23 ...<.aw.....0..#
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 96: 92 5F B7 A9 FC 9A
5A D4 3B 75 E3 92 22 52 9E 83 ._....Z.;u.."R..
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 112: 35 C7 42 F3 FB E9
A8 CC E6 5C 5B 1A C0 F7 A7 D0 5.B......\[.....
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 128: 3B 78 AF DE B2 ED
82 48 50 67 B7 D4 F1 27 FA 30 ;x.....HPg...'.0
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 144: 4C EB 62 D5 E3 92
41 A9 B8 52 A7 B9 64 7B 77 21 L.b...A..R..d{w!
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 160: 72 B9 8A 8F 73 9B
D3 A7 C8 4A 26 2E D7 1F 9D 21 r...s....J&....!
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 176: 0F 80 7E 1E A2 51
B5 E1 99 85 81 CE 80 E8 75 99 ..~..Q........u.
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 192: 90 95 42 E6 C7 1B
07 05 8E 7C 68 6D 8A 91 81 C0 ..B......|hm....
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 208: 00 00 00 01 00 00
00 01 00 00 00 C8 01 01 00 05 ................
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 224: 03 00 00 28 01 01
00 00 80 01 00 07 80 0E 01 00 ...(............
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 240: 80 02 00 02 80 04
00 14 80 03 00 01 80 0B 00 01 ................
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 256: 00 0C 00 04 00 00
70 80 03 00 00 28 02 01 00 00 ......p....(....
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 272: 80 01 00 07 80 0E
00 80 80 02 00 02 80 04 00 13 ................
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 288: 80 03 00 01 80 0B
00 01 00 0C 00 04 00 00 70 80 ..............p.
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 304: 03 00 00 28 03 01
00 00 80 01 00 07 80 0E 01 00 ...(............
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 320: 80 02 00 02 80 04
00 0E 80 03 00 01 80 0B 00 01 ................
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 336: 00 0C 00 04 00 00
70 80 03 00 00 24 04 01 00 00 ......p....$....
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 352: 80 01 00 05 80 02
00 02 80 04 00 0E 80 03 00 01 ................
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 368: 80 0B 00 01 00 0C
00 04 00 00 70 80 00 00 00 24 ..........p....$
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 384: 05 01 00 00 80 01
00 05 80 02 00 02 80 04 00 02 ................
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 400: 80 03 00 01 80 0B
00 01 00 0C 00 04 00 00 70 80 ..............p.
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 416: 01 00 00 00 5E F2
FF 21 ....^..!
Dec 18 12:16:12 base_testgroup charon: 10[IKE] HASH_R => 20 bytes @
0x1e09270
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 0: 66 2A 9C 2F CE D2
C2 50 B9 96 3E 3D 6E 7D E1 6B f*./...P..>=n}.k
Dec 18 12:16:12 base_testgroup charon: 10[IKE] 16: A6 DC 4D
CE ..M.
Dec 18 12:16:12 base_testgroup charon: 10[IKE] IKE_SA psk-l2tp[1]
established between
94.242.255.33[94.242.255.33]...66.77.88.99[192.168.197.137]
Dec 18 12:16:12 base_testgroup charon: 10[IKE] IKE_SA psk-l2tp[1] state
change: CONNECTING => ESTABLISHED
Dec 18 12:16:12 base_testgroup charon: 09[MGR] checkout IKE_SA
Dec 18 12:16:12 base_testgroup charon: 10[ENC] generating ID_PROT
response 0 [ ID HASH ]
Dec 18 12:16:12 base_testgroup charon: 10[NET] sending packet: from
94.242.255.33[4500] to 66.77.88.99[57001] (76 bytes)
Dec 18 12:16:12 base_testgroup charon: 10[MGR] checkin IKE_SA psk-l2tp[1]
Dec 18 12:16:12 base_testgroup charon: 04[NET] sending packet: from
94.242.255.33[4500] to 66.77.88.99[57001]
Dec 18 12:16:12 base_testgroup charon: 10[MGR] check-in of IKE_SA
successful.
Dec 18 12:16:12 base_testgroup charon: 09[MGR] IKE_SA psk-l2tp[1]
successfully checked out
Dec 18 12:16:12 base_testgroup charon: 09[MGR] checkin IKE_SA psk-l2tp[1]
Dec 18 12:16:12 base_testgroup charon: 09[MGR] check-in of IKE_SA
successful.
Dec 18 12:16:12 base_testgroup charon: 01[NET] received packet => 336
bytes @ 0x7fd8ac34e3b0
Dec 18 12:16:12 base_testgroup charon: 01[NET] 0: 00 00 00 00 8E 7C
68 6D 8A 91 81 C0 90 95 42 E6 .....|hm......B.
Dec 18 12:16:12 base_testgroup charon: 01[NET] 16: C7 1B 07 05 08 10
20 01 00 00 00 01 00 00 01 4C ...... ........L
Dec 18 12:16:12 base_testgroup charon: 01[NET] 32: 8C BD 99 60 09 81
0D 99 00 DD 9F F1 EC 6C 5F 5A ...`.........l_Z
Dec 18 12:16:12 base_testgroup charon: 01[NET] 48: 99 B3 81 46 7F 2A
82 0B ED 28 7A 74 38 E4 A0 99 ...F.*...(zt8...
Dec 18 12:16:12 base_testgroup charon: 01[NET] 64: 21 36 34 3C 31 C9
A5 98 7E 94 B1 F9 95 D1 DF FD !64<1...~.......
Dec 18 12:16:12 base_testgroup charon: 01[NET] 80: 84 E4 0B 83 58 D8
DF 20 B8 80 80 2A 20 21 BA D3 ....X.. ...* !..
Dec 18 12:16:12 base_testgroup charon: 01[NET] 96: EF D0 33 0F 58 02
D7 CE 5A 94 D4 FF 21 CE 03 7F ..3.X...Z...!...
Dec 18 12:16:12 base_testgroup charon: 01[NET] 112: CC BC 85 BE 64 38
ED F1 32 D2 34 79 1E E7 57 C8 ....d8..2.4y..W.
Dec 18 12:16:12 base_testgroup charon: 01[NET] 128: 29 06 E4 B7 65 16
4F 05 AC 6D 6B 37 BF AB 2C 2F )...e.O..mk7..,/
Dec 18 12:16:12 base_testgroup charon: 01[NET] 144: FA 41 E6 42 F7 5D
AA 94 57 88 A0 75 94 16 80 2E .A.B.]..W..u....
Dec 18 12:16:12 base_testgroup charon: 01[NET] 160: E3 F9 87 04 85 01
04 B4 1A BD 69 76 8F 28 85 1D ..........iv.(..
Dec 18 12:16:12 base_testgroup charon: 01[NET] 176: D6 50 67 3A 76 10
EF 56 04 38 00 92 89 CC A4 52 .Pg:v..V.8.....R
Dec 18 12:16:12 base_testgroup charon: 01[NET] 192: 9D 09 CF 51 DE 44
06 B2 94 F4 25 00 C7 BC 9C 6E ...Q.D....%....n
Dec 18 12:16:12 base_testgroup charon: 01[NET] 208: 5D 55 29 31 2B 91
64 CF 03 29 42 14 A3 4D 04 E5 ]U)1+.d..)B..M..
Dec 18 12:16:12 base_testgroup charon: 01[NET] 224: 10 7B 8B 3E 86 A9
27 3B C2 51 F1 9C D2 65 70 7F .{.>..';.Q...ep.
Dec 18 12:16:12 base_testgroup charon: 01[NET] 240: 9A 89 18 56 31 4C
7C 8D 33 93 F9 36 2A 6A 47 07 ...V1L|.3..6*jG.
Dec 18 12:16:12 base_testgroup charon: 01[NET] 256: 71 BF D6 BC 73 47
78 21 81 C7 B9 C5 EE D1 12 62 q...sGx!.......b
Dec 18 12:16:12 base_testgroup charon: 01[NET] 272: 37 F7 31 32 0F A1
F0 59 15 9A B1 9E AD 29 B7 56 7.12...Y.....).V
Dec 18 12:16:12 base_testgroup charon: 01[NET] 288: 22 B4 F3 F8 81 66
37 8C 5B A0 1C FA 5A 40 55 E3 "....f7.[...Z at U.
Dec 18 12:16:12 base_testgroup charon: 01[NET] 304: 7D 2A 73 28 90 32
04 18 43 4F 7B AB 64 57 40 F1 }*s(.2..CO{.dW at .
Dec 18 12:16:12 base_testgroup charon: 01[NET] 320: 9F 1B EE 5C 7F 20
91 4F 48 20 C2 53 3A 74 91 75 ...\. .OH .S:t.u
Dec 18 12:16:12 base_testgroup charon: 01[NET] received packet: from
66.77.88.99[57001] to 94.242.255.33[4500]
Dec 18 12:16:12 base_testgroup charon: 01[NET] waiting for data on sockets
Dec 18 12:16:12 base_testgroup charon: 11[MGR] checkout IKE_SA by message
Dec 18 12:16:12 base_testgroup charon: 11[MGR] IKE_SA psk-l2tp[1]
successfully checked out
Dec 18 12:16:12 base_testgroup charon: 11[NET] received packet: from
66.77.88.99[57001] to 94.242.255.33[4500] (332 bytes)
Dec 18 12:16:12 base_testgroup charon: 11[ENC] parsed QUICK_MODE request
1 [ HASH SA No ID ID NAT-OA NAT-OA ]
Dec 18 12:16:12 base_testgroup charon: 11[IKE] Hash(1) => 20 bytes @
0x1e0d7f0
Dec 18 12:16:12 base_testgroup charon: 11[IKE] 0: 71 55 D7 43 33 F2
57 9D 8E FF 6D D4 AE A5 E4 9A qU.C3.W...m.....
Dec 18 12:16:12 base_testgroup charon: 11[IKE] 16: A9 FB 37
CF ..7.
Dec 18 12:16:12 base_testgroup charon: 11[IKE] changing received traffic
selectors 192.168.197.137/32[udp/l2f]=== 94.242.255.33/32[udp/l2f] due
to NAT
Dec 18 12:16:12 base_testgroup charon: 11[IKE] received 3600s lifetime,
configured 0s
Dec 18 12:16:12 base_testgroup charon: 11[IKE] received 250000000
lifebytes, configured 0
Dec 18 12:16:12 base_testgroup charon: 11[KNL] getting SPI for reqid {1}
Dec 18 12:16:12 base_testgroup charon: 11[KNL] sending
XFRM_MSG_ALLOCSPI: => 248 bytes @ 0x7fd8a5f46710
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 0: F8 00 00 00 16 00
01 00 C9 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 16: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 32: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 48: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 64: 00 00 00 00 00 00
00 00 5E F2 FF 21 00 00 00 00 ........^..!....
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 80: 00 00 00 00 00 00
00 00 00 00 00 00 32 00 00 00 ............2...
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 96: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 112: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 128: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 160: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 176: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 192: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 208: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 224: 01 00 00 00 02 00
01 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 11[KNL] 240: 00 00 00 C0 FF FF
FF CF ........
Dec 18 12:16:12 base_testgroup charon: 11[KNL] got SPI c78bd22c for
reqid {1}
Dec 18 12:16:12 base_testgroup charon: 11[IKE] Hash(2) => 20 bytes @
0x1e11760
Dec 18 12:16:12 base_testgroup charon: 11[IKE] 0: 17 F2 94 6B F2 7B
23 42 28 3D 85 31 43 C5 97 2D ...k.{#B(=.1C..-
Dec 18 12:16:12 base_testgroup charon: 11[IKE] 16: A8 CA CD
A9 ....
Dec 18 12:16:12 base_testgroup charon: 11[ENC] generating QUICK_MODE
response 1 [ HASH SA No ID ID NAT-OA NAT-OA ]
Dec 18 12:16:12 base_testgroup charon: 11[NET] sending packet: from
94.242.255.33[4500] to 66.77.88.99[57001] (204 bytes)
Dec 18 12:16:12 base_testgroup charon: 11[MGR] checkin IKE_SA psk-l2tp[1]
Dec 18 12:16:12 base_testgroup charon: 11[MGR] check-in of IKE_SA
successful.
Dec 18 12:16:12 base_testgroup charon: 04[NET] sending packet: from
94.242.255.33[4500] to 66.77.88.99[57001]
Dec 18 12:16:12 base_testgroup charon: 01[NET] received packet => 64
bytes @ 0x7fd8ac34e3b0
Dec 18 12:16:12 base_testgroup charon: 01[NET] 0: 00 00 00 00 8E 7C
68 6D 8A 91 81 C0 90 95 42 E6 .....|hm......B.
Dec 18 12:16:12 base_testgroup charon: 01[NET] 16: C7 1B 07 05 08 10
20 01 00 00 00 01 00 00 00 3C ...... ........<
Dec 18 12:16:12 base_testgroup charon: 01[NET] 32: DF 36 90 25 60 F1
16 6F C1 93 27 ED B6 99 AA 92 .6.%`..o..'.....
Dec 18 12:16:12 base_testgroup charon: 01[NET] 48: 13 96 CC 8F 83 45
5A 72 E8 6F 77 01 FE D4 77 5C .....EZr.ow...w\
Dec 18 12:16:12 base_testgroup charon: 01[NET] received packet: from
66.77.88.99[57001] to 94.242.255.33[4500]
Dec 18 12:16:12 base_testgroup charon: 01[NET] waiting for data on sockets
Dec 18 12:16:12 base_testgroup charon: 12[MGR] checkout IKE_SA by message
Dec 18 12:16:12 base_testgroup charon: 12[MGR] IKE_SA psk-l2tp[1]
successfully checked out
Dec 18 12:16:12 base_testgroup charon: 12[NET] received packet: from
66.77.88.99[57001] to 94.242.255.33[4500] (60 bytes)
Dec 18 12:16:12 base_testgroup charon: 12[ENC] parsed QUICK_MODE request
1 [ HASH ]
Dec 18 12:16:12 base_testgroup charon: 12[IKE] Hash(3) => 20 bytes @
0x1e0d580
Dec 18 12:16:12 base_testgroup charon: 12[IKE] 0: 1A E2 10 07 80 3B
D8 6B 90 C5 B6 D6 E3 AD F9 E5 .....;.k........
Dec 18 12:16:12 base_testgroup charon: 12[IKE] 16: A2 B9 6A
7D ..j}
Dec 18 12:16:12 base_testgroup charon: 12[CHD] using AES_CBC for
encryption
Dec 18 12:16:12 base_testgroup charon: 12[CHD] using HMAC_SHA1_96 for
integrity
Dec 18 12:16:12 base_testgroup charon: 12[CHD] adding inbound ESP SA
Dec 18 12:16:12 base_testgroup charon: 12[CHD] SPI 0xc78bd22c, src
66.77.88.99 dst 94.242.255.33
Dec 18 12:16:12 base_testgroup charon: 12[KNL] adding SAD entry with SPI
c78bd22c and reqid {1} (mark 0/0x00000000)
Dec 18 12:16:12 base_testgroup charon: 12[KNL] using encryption
algorithm AES_CBC with key size 128
Dec 18 12:16:12 base_testgroup charon: 12[KNL] using integrity
algorithm HMAC_SHA1_96 with key size 160
Dec 18 12:16:12 base_testgroup charon: 12[KNL] using replay window of
32 packets
Dec 18 12:16:12 base_testgroup charon: 12[KNL] sending XFRM_MSG_UPDSA:
=> 448 bytes @ 0x7fd8a55454d0
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 0: C0 01 00 00 1A 00
05 00 CA 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 16: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 32: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 48: 00 00 00 00 00 00
00 00 02 00 20 20 00 00 00 00 .......... ....
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 64: 00 00 00 00 00 00
00 00 5E F2 FF 21 00 00 00 00 ........^..!....
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 80: 00 00 00 00 00 00
00 00 C7 8B D2 2C 32 00 00 00 ...........,2...
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 96: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 112: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 128: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 160: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 176: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 192: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 208: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 224: 01 00 00 00 02 00
00 20 00 00 00 00 00 00 00 00 ....... ........
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 240: 58 00 02 00 61 65
73 00 00 00 00 00 00 00 00 00 X...aes.........
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 256: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 272: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 288: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 304: 00 00 00 00 80 00
00 00 C0 C6 61 3E B9 19 28 A9 ..........a>..(.
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 320: AA 61 83 C5 AE 2C
A4 5D 5C 00 01 00 73 68 61 31 .a...,.]\...sha1
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 336: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 352: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 368: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 384: 00 00 00 00 00 00
00 00 00 00 00 00 A0 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 400: E1 49 CE 12 18 75
2E 3E 3A 54 AB FE 18 CA 37 AB .I...u.>:T....7.
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 416: CD 7D C6 EE 1C 00
04 00 02 00 DE A9 11 94 00 00 .}..............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 432: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[CHD] adding outbound ESP SA
Dec 18 12:16:12 base_testgroup charon: 12[CHD] SPI 0x6532cbde, src
94.242.255.33 dst 66.77.88.99
Dec 18 12:16:12 base_testgroup charon: 12[KNL] adding SAD entry with SPI
6532cbde and reqid {1} (mark 0/0x00000000)
Dec 18 12:16:12 base_testgroup charon: 12[KNL] using encryption
algorithm AES_CBC with key size 128
Dec 18 12:16:12 base_testgroup charon: 12[KNL] using integrity
algorithm HMAC_SHA1_96 with key size 160
Dec 18 12:16:12 base_testgroup charon: 12[KNL] using replay window of
32 packets
Dec 18 12:16:12 base_testgroup charon: 12[KNL] sending XFRM_MSG_NEWSA:
=> 448 bytes @ 0x7fd8a55454d0
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 0: C0 01 00 00 10 00
05 00 CB 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 16: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 32: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 48: 00 00 00 00 00 00
00 00 02 00 20 20 00 00 00 00 .......... ....
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 64: 00 00 00 00 00 00
00 00 57 93 97 43 00 00 00 00 ........W..C....
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 80: 00 00 00 00 00 00
00 00 65 32 CB DE 32 00 00 00 ........e2..2...
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 96: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 112: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 128: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 160: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 176: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 192: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 208: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 224: 01 00 00 00 02 00
00 20 00 00 00 00 00 00 00 00 ....... ........
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 240: 58 00 02 00 61 65
73 00 00 00 00 00 00 00 00 00 X...aes.........
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 256: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 272: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 288: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 304: 00 00 00 00 80 00
00 00 6E 39 14 8B AC 1F A0 EA ........n9......
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 320: 99 A9 38 D9 FE EA
7E 38 5C 00 01 00 73 68 61 31 ..8...~8\...sha1
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 336: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 352: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 368: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 384: 00 00 00 00 00 00
00 00 00 00 00 00 A0 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 400: 69 D6 9D 90 20 37
F6 5A 16 A1 35 A0 19 36 F3 88 i... 7.Z..5..6..
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 416: 31 31 31 42 1C 00
04 00 02 00 11 94 DE A9 00 00 111B............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 432: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] adding policy
94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f] out (mark
0/0x00000000)
Dec 18 12:16:12 base_testgroup charon: 12[KNL] sending
XFRM_MSG_NEWPOLICY: => 184 bytes @ 0x7fd8a55453a0
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 0: B8 00 00 00 13 00
05 00 CC 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 16: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 32: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 48: 06 A5 FF FF 06 A5
FF FF 02 00 20 20 11 00 00 00 .......... ....
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 64: 00 00 00 00 00 00
00 00 FF FF FF FF FF FF FF FF ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 80: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 96: FF FF FF FF FF FF
FF FF 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 112: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 128: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 160: 00 00 00 00 00 00
00 00 00 1F 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 176: 01 01 00 00 00 00
00 00 ........
Dec 18 12:16:12 base_testgroup charon: 12[KNL] adding policy
66.77.88.99/32[udp/l2f] === 94.242.255.33/32[udp/l2f] in (mark
0/0x00000000)
Dec 18 12:16:12 base_testgroup charon: 12[KNL] sending
XFRM_MSG_NEWPOLICY: => 184 bytes @ 0x7fd8a55453a0
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 0: B8 00 00 00 13 00
05 00 CD 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 16: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 32: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 48: 06 A5 FF FF 06 A5
FF FF 02 00 20 20 11 00 00 00 .......... ....
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 64: 00 00 00 00 00 00
00 00 FF FF FF FF FF FF FF FF ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 80: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 96: FF FF FF FF FF FF
FF FF 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 112: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 128: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 160: 00 00 00 00 00 00
00 00 00 1F 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 176: 00 01 00 00 00 00
00 00 ........
Dec 18 12:16:12 base_testgroup charon: 12[KNL] policy
94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f] out (mark
0/0x00000000) already exists, increasing refcount
Dec 18 12:16:12 base_testgroup charon: 12[KNL] updating policy
94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f] out (mark
0/0x00000000)
Dec 18 12:16:12 base_testgroup charon: 12[KNL] sending
XFRM_MSG_UPDPOLICY: => 252 bytes @ 0x7fd8a55453a0
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 0: FC 00 00 00 19 00
05 00 CE 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 16: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 32: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 48: 06 A5 FF FF 06 A5
FF FF 02 00 20 20 11 00 00 00 .......... ....
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 64: 00 00 00 00 00 00
00 00 FF FF FF FF FF FF FF FF ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 80: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 96: FF FF FF FF FF FF
FF FF 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 112: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 128: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 160: 00 00 00 00 00 00
00 00 00 07 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 176: 01 00 00 00 00 00
00 00 44 00 05 00 00 00 00 00 ........D.......
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 192: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 208: 32 00 00 00 02 00
00 00 00 00 00 00 00 00 00 00 2...............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 224: 00 00 00 00 00 00
00 00 01 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 240: FF FF FF FF FF FF
FF FF FF FF FF FF ............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] policy
66.77.88.99/32[udp/l2f] === 94.242.255.33/32[udp/l2f] in (mark
0/0x00000000) already exists, increasing refcount
Dec 18 12:16:12 base_testgroup charon: 12[KNL] updating policy
66.77.88.99/32[udp/l2f] === 94.242.255.33/32[udp/l2f] in (mark
0/0x00000000)
Dec 18 12:16:12 base_testgroup charon: 12[KNL] sending
XFRM_MSG_UPDPOLICY: => 252 bytes @ 0x7fd8a55453a0
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 0: FC 00 00 00 19 00
05 00 CF 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 16: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 32: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 48: 06 A5 FF FF 06 A5
FF FF 02 00 20 20 11 00 00 00 .......... ....
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 64: 00 00 00 00 00 00
00 00 FF FF FF FF FF FF FF FF ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 80: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 96: FF FF FF FF FF FF
FF FF 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 112: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 128: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 160: 00 00 00 00 00 00
00 00 00 07 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 176: 00 00 00 00 00 00
00 00 44 00 05 00 00 00 00 00 ........D.......
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 192: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 208: 32 00 00 00 02 00
00 00 00 00 00 00 00 00 00 00 2...............
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 224: 00 00 00 00 00 00
00 00 01 00 00 00 00 00 00 00 ................
Dec 18 12:16:12 base_testgroup charon: 12[KNL] 240: FF FF FF FF FF FF
FF FF FF FF FF FF ............
Dec 18 12:16:12 base_testgroup charon: 12[IKE] CHILD_SA psk-l2tp{1}
established with SPIs c78bd22c_i 6532cbde_o and TS
94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f]
Dec 18 12:16:12 base_testgroup charon: 12[MGR] checkin IKE_SA psk-l2tp[1]
Dec 18 12:16:12 base_testgroup charon: 12[MGR] check-in of IKE_SA
successful.
#### xl2tpd Startup ####
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: get_call: allocating new
tunnel for host 66.77.88.99, port 1701.
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: handle_avps: handling avp's
for tunnel 17635, call 10899904
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: message_type_avp: message
type 1 (Start-Control-Connection-Request)
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: protocol_version_avp: peer
is using version 1, revision 0.
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: framing_caps_avp: supported
peer frames: sync
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: bearer_caps_avp: supported
peer bearers:
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: firmware_rev_avp: peer
reports firmware version 1537 (0x0601)
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: hostname_avp: peer reports
hostname 'Windows7_x86'
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: vendor_avp: peer reports
vendor 'Microsoft'
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: assigned_tunnel_avp: using
peer's tunnel 6
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: receive_window_size_avp:
peer wants RWS of 8. Will use flow control.
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: control_finish: message
type is Start-Control-Connection-Request(1). Tunnel is 6, call is 0.
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: control_finish: sending SCCRP
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: handle_avps: handling avp's
for tunnel 17635, call 10899904
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: message_type_avp: message
type 3 (Start-Control-Connection-Connected)
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: control_finish: message
type is Start-Control-Connection-Connected(3). Tunnel is 6, call is 0.
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: Connection established to
66.77.88.99, 1701. Local: 17635, Remote: 6 (ref=0/0). LNS session is
'default'
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: check_control: Received out
of order control packet on tunnel 6 (got 3, expected 2)
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: handle_packet: bad control
packet!
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: network_thread: bad packet
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: handle_avps: handling avp's
for tunnel 17635, call 10899904
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: message_type_avp: message
type 10 (Incoming-Call-Request)
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: message_type_avp: new
incoming call
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: assigned_call_avp: using
peer's call 1
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: call_serno_avp: serial
number is 0
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: bearer_type_avp: peer
bears: analog
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: result_code_avp: result
code not appropriate for Incoming-Call-Request. Ignoring.
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: control_finish: message
type is Incoming-Call-Request(10). Tunnel is 6, call is 0.
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: control_finish: Sending ICRP
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: handle_avps: handling avp's
for tunnel 17635, call 3501
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: message_type_avp: message
type 12 (Incoming-Call-Connected)
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: tx_speed_avp: transmit baud
rate is 1000000000
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: frame_type_avp: peer
uses:sync frames
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: ignore_avp : Ignoring AVP
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: control_finish: message
type is Incoming-Call-Connected(12). Tunnel is 6, call is 1.
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: start_pppd: I'm running:
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "/usr/sbin/pppd"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "passive"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "nodetach"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "10.1.26.1:10.1.26.2"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "auth"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "name"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "xl2tpd"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "file"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "/etc/ppp/options.xl2tpd"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "plugin"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "pppol2tp.so"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "pppol2tp"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "8"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "pppol2tp_lns_mode"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "pppol2tp_tunnel_id"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "17635"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "pppol2tp_session_id"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: "3501"
Dec 18 12:16:13 base_testgroup xl2tpd[5398]: Call established with
66.77.88.99, Local: 3501, Remote: 1, Serial: 0
Dec 18 12:16:13 base_testgroup pppd[19945]: Plugin pppol2tp.so loaded.
Dec 18 12:16:13 base_testgroup pppd[19945]: pppd 2.4.5 started by root,
uid 0
Dec 18 12:16:13 base_testgroup pppd[19945]: Using interface ppp0
Dec 18 12:16:13 base_testgroup pppd[19945]: Connect: ppp0 <-->
Dec 18 12:16:16 base_testgroup charon: 15[MGR] checkout IKE_SA
Dec 18 12:16:16 base_testgroup charon: 15[MGR] IKE_SA psk-l2tp[1]
successfully checked out
Dec 18 12:16:16 base_testgroup charon: 15[MGR] checkin IKE_SA psk-l2tp[1]
Dec 18 12:16:16 base_testgroup charon: 15[MGR] check-in of IKE_SA
successful.
Dec 18 12:16:16 base_testgroup charon: 13[KNL] 10.1.26.1 appeared on ppp0
Dec 18 12:16:16 base_testgroup charon: 05[KNL] 10.1.26.1 disappeared
from ppp0
Dec 18 12:16:16 base_testgroup charon: 06[KNL] 10.1.26.1 appeared on ppp0
Dec 18 12:16:16 base_testgroup charon: 09[KNL] interface ppp0 activated
Dec 18 12:16:16 base_testgroup pppd[19945]: Cannot determine ethernet
address for proxy ARP
Dec 18 12:16:16 base_testgroup pppd[19945]: local IP address 10.1.26.1
Dec 18 12:16:16 base_testgroup pppd[19945]: remote IP address 10.1.26.2
Dec 18 12:16:16 base_testgroup charon: 03[KNL] creating roam job due to
address/link change
Dec 18 12:16:16 base_testgroup charon: 03[MGR] checkout IKE_SA
Dec 18 12:16:16 base_testgroup charon: 03[MGR] IKE_SA psk-l2tp[1]
successfully checked out
Dec 18 12:16:16 base_testgroup charon: 03[KNL] using 94.242.255.33 as
address to reach 66.77.88.99
Dec 18 12:16:16 base_testgroup charon: 03[IKE] keeping connection path
94.242.255.33 - 66.77.88.99
Dec 18 12:16:16 base_testgroup charon: 03[MGR] checkin IKE_SA psk-l2tp[1]
Dec 18 12:16:16 base_testgroup charon: 03[MGR] check-in of IKE_SA
successful.
Dec 18 12:16:42 base_testgroup charon: 14[MGR] checkout IKE_SA
Dec 18 12:16:42 base_testgroup charon: 14[MGR] IKE_SA psk-l2tp[1]
successfully checked out
Dec 18 12:16:42 base_testgroup charon: 14[MGR] checkin IKE_SA psk-l2tp[1]
Dec 18 12:16:42 base_testgroup charon: 14[MGR] check-in of IKE_SA
successful.
#### After 175 Mb of traffic, Windows will send this: #####
Dec 18 12:17:04 base_testgroup charon: 01[NET] received packet => 336
bytes @ 0x7fd8ac34e3b0
Dec 18 12:17:04 base_testgroup charon: 01[NET] 0: 00 00 00 00 8E 7C
68 6D 8A 91 81 C0 90 95 42 E6 .....|hm......B.
Dec 18 12:17:04 base_testgroup charon: 01[NET] 16: C7 1B 07 05 08 10
20 01 00 00 00 02 00 00 01 4C ...... ........L
Dec 18 12:17:04 base_testgroup charon: 01[NET] 32: 8A F6 F1 5F 5B AE
61 05 16 27 A7 5B C0 A1 D4 E6 ..._[.a..'.[....
Dec 18 12:17:04 base_testgroup charon: 01[NET] 48: B7 36 DA 45 A6 F5
FC A6 D2 FD BF A6 8B 1E FD EC .6.E............
Dec 18 12:17:04 base_testgroup charon: 01[NET] 64: DF C4 45 AE FC 32
C0 5A 5F 0F 5F 59 00 59 5C CE ..E..2.Z_._Y.Y\.
Dec 18 12:17:04 base_testgroup charon: 01[NET] 80: 8C 83 4F 6B EA 11
04 44 73 07 0C 3B 62 77 DC 4C ..Ok...Ds..;bw.L
Dec 18 12:17:04 base_testgroup charon: 01[NET] 96: 33 4C FE 26 47 A6
A7 F5 08 D2 69 A0 3E A6 AA 5A 3L.&G.....i.>..Z
Dec 18 12:17:04 base_testgroup charon: 01[NET] 112: A7 49 2B 80 54 FA
C2 B3 DB F9 67 D9 63 A1 47 75 .I+.T.....g.c.Gu
Dec 18 12:17:04 base_testgroup charon: 01[NET] 128: F7 64 9D 59 72 3A
F2 D0 87 2A 6E 3F D6 66 52 43 .d.Yr:...*n?.fRC
Dec 18 12:17:04 base_testgroup charon: 01[NET] 144: BC 8C AC 40 3E 24
11 B6 99 7C AB E5 7E BC 7F 85 ...@>$...|..~...
Dec 18 12:17:04 base_testgroup charon: 01[NET] 160: 6F 52 24 5C 2D D8
C9 A7 6F 39 71 F5 8C 5E 59 B5 oR$\-...o9q..^Y.
Dec 18 12:17:04 base_testgroup charon: 01[NET] 176: AD 07 36 4A 8C F1
D0 A2 64 34 96 48 4B 69 69 B7 ..6J....d4.HKii.
Dec 18 12:17:04 base_testgroup charon: 01[NET] 192: 2F 98 60 27 7C 14
76 21 A6 D6 0F 37 98 4A 75 03 /.`'|.v!...7.Ju.
Dec 18 12:17:04 base_testgroup charon: 01[NET] 208: 6A 80 53 87 D7 B3
5F B5 45 6C 23 6F 46 EA C4 01 j.S..._.El#oF...
Dec 18 12:17:04 base_testgroup charon: 01[NET] 224: B1 91 FC 48 7F 5E
A1 89 3A 70 3B E0 2F B2 DC D9 ...H.^..:p;./...
Dec 18 12:17:04 base_testgroup charon: 01[NET] 240: 3E 5B 7D 58 3A 54
D6 BB 9B 49 81 B1 12 AF 08 78 >[}X:T...I.....x
Dec 18 12:17:04 base_testgroup charon: 01[NET] 256: 6E AA 6B 3E 46 68
D2 DB 2E 06 76 76 0B AC 78 A6 n.k>Fh....vv..x.
Dec 18 12:17:04 base_testgroup charon: 01[NET] 272: C7 F9 91 AB 2A DF
1B B9 6C 9B 38 F4 1F 41 2F F6 ....*...l.8..A/.
Dec 18 12:17:04 base_testgroup charon: 01[NET] 288: 8A 19 5F 51 2A 37
64 0E 8C 69 CA A4 0A 94 A4 D7 .._Q*7d..i......
Dec 18 12:17:04 base_testgroup charon: 01[NET] 304: 19 4A E3 E0 64 71
C9 3D 5A 63 D4 21 36 80 34 DD .J..dq.=Zc.!6.4.
Dec 18 12:17:04 base_testgroup charon: 01[NET] 320: 8F 60 C0 4D B4 5E
1E 65 CA 56 BA 1D 00 73 48 2A .`.M.^.e.V...sH*
Dec 18 12:17:04 base_testgroup charon: 01[NET] received packet: from
66.77.88.99[57001] to 94.242.255.33[4500]
Dec 18 12:17:04 base_testgroup charon: 01[NET] waiting for data on sockets
Dec 18 12:17:04 base_testgroup charon: 05[MGR] checkout IKE_SA by message
Dec 18 12:17:04 base_testgroup charon: 05[MGR] IKE_SA psk-l2tp[1]
successfully checked out
Dec 18 12:17:04 base_testgroup charon: 05[NET] received packet: from
66.77.88.99[57001] to 94.242.255.33[4500] (332 bytes)
Dec 18 12:17:04 base_testgroup charon: 05[ENC] parsed QUICK_MODE request
2 [ HASH SA No ID ID NAT-OA NAT-OA ]
Dec 18 12:17:04 base_testgroup charon: 05[IKE] Hash(1) => 20 bytes @
0x1e0e710
Dec 18 12:17:04 base_testgroup charon: 05[IKE] 0: C2 35 0A C4 A0 F2
7B 94 47 96 CB B6 FF DB 47 72 .5....{.G.....Gr
Dec 18 12:17:04 base_testgroup charon: 05[IKE] 16: EE 4A C8
8A .J..
Dec 18 12:17:04 base_testgroup charon: 05[IKE] changing received traffic
selectors 192.168.197.137/32[udp/l2f]=== 94.242.255.33/32[udp/l2f] due
to NAT
Dec 18 12:17:04 base_testgroup charon: 05[IKE] received 3600s lifetime,
configured 0s
Dec 18 12:17:04 base_testgroup charon: 05[IKE] received 250000000
lifebytes, configured 0
Dec 18 12:17:04 base_testgroup charon: 05[IKE] detected rekeying of
CHILD_SA psk-l2tp{1}
Dec 18 12:17:04 base_testgroup charon: 05[KNL] getting SPI for reqid {1}
Dec 18 12:17:04 base_testgroup charon: 05[KNL] sending
XFRM_MSG_ALLOCSPI: => 248 bytes @ 0x7fd8a9b4c710
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 0: F8 00 00 00 16 00
01 00 D0 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 16: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 32: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 48: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 64: 00 00 00 00 00 00
00 00 5E F2 FF 21 00 00 00 00 ........^..!....
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 80: 00 00 00 00 00 00
00 00 00 00 00 00 32 00 00 00 ............2...
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 96: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 112: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 128: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 160: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 176: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 192: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 208: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 224: 01 00 00 00 02 00
01 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 05[KNL] 240: 00 00 00 C0 FF FF
FF CF ........
Dec 18 12:17:04 base_testgroup charon: 05[KNL] got SPI c260a810 for
reqid {1}
Dec 18 12:17:04 base_testgroup charon: 05[IKE] Hash(2) => 20 bytes @
0x1e135b0
Dec 18 12:17:04 base_testgroup charon: 05[IKE] 0: 0C 52 8F 76 53 33
B0 A2 88 88 05 A1 53 9B 70 36 .R.vS3......S.p6
Dec 18 12:17:04 base_testgroup charon: 05[IKE] 16: 0C F1 0D
B6 ....
Dec 18 12:17:04 base_testgroup charon: 05[ENC] generating QUICK_MODE
response 2 [ HASH SA No ID ID NAT-OA NAT-OA ]
Dec 18 12:17:04 base_testgroup charon: 05[NET] sending packet: from
94.242.255.33[4500] to 66.77.88.99[57001] (204 bytes)
Dec 18 12:17:04 base_testgroup charon: 05[MGR] checkin IKE_SA psk-l2tp[1]
Dec 18 12:17:04 base_testgroup charon: 04[NET] sending packet: from
94.242.255.33[4500] to 66.77.88.99[57001]
Dec 18 12:17:04 base_testgroup charon: 05[MGR] check-in of IKE_SA
successful.
Dec 18 12:17:04 base_testgroup charon: 01[NET] received packet => 64
bytes @ 0x7fd8ac34e3b0
Dec 18 12:17:04 base_testgroup charon: 01[NET] 0: 00 00 00 00 8E 7C
68 6D 8A 91 81 C0 90 95 42 E6 .....|hm......B.
Dec 18 12:17:04 base_testgroup charon: 01[NET] 16: C7 1B 07 05 08 10
20 01 00 00 00 02 00 00 00 3C ...... ........<
Dec 18 12:17:04 base_testgroup charon: 01[NET] 32: F7 51 D7 EC DB A1
33 07 9A D1 24 E1 9A 38 A1 22 .Q....3...$..8."
Dec 18 12:17:04 base_testgroup charon: 01[NET] 48: 0A 6E 51 31 9B DD
0D 99 96 ED BC 79 30 91 76 96 .nQ1.......y0.v.
Dec 18 12:17:04 base_testgroup charon: 01[NET] received packet: from
66.77.88.99[57001] to 94.242.255.33[4500]
Dec 18 12:17:04 base_testgroup charon: 01[NET] waiting for data on sockets
Dec 18 12:17:04 base_testgroup charon: 07[MGR] checkout IKE_SA by message
Dec 18 12:17:04 base_testgroup charon: 07[MGR] IKE_SA psk-l2tp[1]
successfully checked out
Dec 18 12:17:04 base_testgroup charon: 07[NET] received packet: from
66.77.88.99[57001] to 94.242.255.33[4500] (60 bytes)
Dec 18 12:17:04 base_testgroup charon: 07[ENC] parsed QUICK_MODE request
2 [ HASH ]
Dec 18 12:17:04 base_testgroup charon: 07[IKE] Hash(3) => 20 bytes @
0x1e0e6f0
Dec 18 12:17:04 base_testgroup charon: 07[IKE] 0: 89 34 C1 9B B6 7F
06 0E 7B 65 F4 93 38 4E 5E A0 .4......{e..8N^.
Dec 18 12:17:04 base_testgroup charon: 07[IKE] 16: 90 12 45
5C ..E\
Dec 18 12:17:04 base_testgroup charon: 07[CHD] using AES_CBC for
encryption
Dec 18 12:17:04 base_testgroup charon: 07[CHD] using HMAC_SHA1_96 for
integrity
Dec 18 12:17:04 base_testgroup charon: 07[CHD] adding inbound ESP SA
Dec 18 12:17:04 base_testgroup charon: 07[CHD] SPI 0xc260a810, src
66.77.88.99 dst 94.242.255.33
Dec 18 12:17:04 base_testgroup charon: 07[KNL] adding SAD entry with SPI
c260a810 and reqid {1} (mark 0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 07[KNL] using encryption
algorithm AES_CBC with key size 128
Dec 18 12:17:04 base_testgroup charon: 07[KNL] using integrity
algorithm HMAC_SHA1_96 with key size 160
Dec 18 12:17:04 base_testgroup charon: 07[KNL] using replay window of
32 packets
Dec 18 12:17:04 base_testgroup charon: 07[KNL] sending XFRM_MSG_UPDSA:
=> 448 bytes @ 0x7fd8a874a4d0
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 0: C0 01 00 00 1A 00
05 00 D1 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 16: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 32: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 48: 00 00 00 00 00 00
00 00 02 00 20 20 00 00 00 00 .......... ....
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 64: 00 00 00 00 00 00
00 00 5E F2 FF 21 00 00 00 00 ........^..!....
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 80: 00 00 00 00 00 00
00 00 C2 60 A8 10 32 00 00 00 .........`..2...
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 96: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 112: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 128: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 160: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 176: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 192: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 208: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 224: 01 00 00 00 02 00
00 20 00 00 00 00 00 00 00 00 ....... ........
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 240: 58 00 02 00 61 65
73 00 00 00 00 00 00 00 00 00 X...aes.........
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 256: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 272: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 288: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 304: 00 00 00 00 80 00
00 00 D1 91 D0 29 E3 AF 65 86 ...........)..e.
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 320: E7 C9 4A B6 E1 24
2D BD 5C 00 01 00 73 68 61 31 ..J..$-.\...sha1
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 336: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 352: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 368: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 384: 00 00 00 00 00 00
00 00 00 00 00 00 A0 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 400: 9F 4C 5F 91 F7 D3
58 7C 9B 85 2B 40 FF 9E 54 D3 .L_...X|..+ at ..T.
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 416: 94 4F 7E BA 1C 00
04 00 02 00 DE A9 11 94 00 00 .O~.............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 432: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[CHD] adding outbound ESP SA
Dec 18 12:17:04 base_testgroup charon: 07[CHD] SPI 0x87cb8149, src
94.242.255.33 dst 66.77.88.99
Dec 18 12:17:04 base_testgroup charon: 07[KNL] adding SAD entry with SPI
87cb8149 and reqid {1} (mark 0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 07[KNL] using encryption
algorithm AES_CBC with key size 128
Dec 18 12:17:04 base_testgroup charon: 07[KNL] using integrity
algorithm HMAC_SHA1_96 with key size 160
Dec 18 12:17:04 base_testgroup charon: 07[KNL] using replay window of
32 packets
Dec 18 12:17:04 base_testgroup charon: 07[KNL] sending XFRM_MSG_NEWSA:
=> 448 bytes @ 0x7fd8a874a4d0
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 0: C0 01 00 00 10 00
05 00 D2 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 16: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 32: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 48: 00 00 00 00 00 00
00 00 02 00 20 20 00 00 00 00 .......... ....
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 64: 00 00 00 00 00 00
00 00 57 93 97 43 00 00 00 00 ........W..C....
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 80: 00 00 00 00 00 00
00 00 87 CB 81 49 32 00 00 00 ...........I2...
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 96: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 112: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 128: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 160: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 176: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 192: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 208: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 224: 01 00 00 00 02 00
00 20 00 00 00 00 00 00 00 00 ....... ........
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 240: 58 00 02 00 61 65
73 00 00 00 00 00 00 00 00 00 X...aes.........
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 256: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 272: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 288: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 304: 00 00 00 00 80 00
00 00 6A FA 52 79 D3 C2 C3 AF ........j.Ry....
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 320: 3B 35 AB D5 35 0B
5E 9F 5C 00 01 00 73 68 61 31 ;5..5.^.\...sha1
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 336: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 352: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 368: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 384: 00 00 00 00 00 00
00 00 00 00 00 00 A0 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 400: 4B ED A3 78 A3 E3
11 A5 9F 1B 9B 9D 53 2C 5D BF K..x........S,].
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 416: 3E 32 D2 CF 1C 00
04 00 02 00 11 94 DE A9 00 00 >2..............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 432: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] policy
94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f] out (mark
0/0x00000000) already exists, increasing refcount
Dec 18 12:17:04 base_testgroup charon: 07[KNL] policy
66.77.88.99/32[udp/l2f] === 94.242.255.33/32[udp/l2f] in (mark
0/0x00000000) already exists, increasing refcount
Dec 18 12:17:04 base_testgroup charon: 07[KNL] policy
94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f] out (mark
0/0x00000000) already exists, increasing refcount
Dec 18 12:17:04 base_testgroup charon: 07[KNL] updating policy
94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f] out (mark
0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 07[KNL] sending
XFRM_MSG_UPDPOLICY: => 252 bytes @ 0x7fd8a874a3a0
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 0: FC 00 00 00 19 00
05 00 D3 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 16: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 32: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 48: 06 A5 FF FF 06 A5
FF FF 02 00 20 20 11 00 00 00 .......... ....
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 64: 00 00 00 00 00 00
00 00 FF FF FF FF FF FF FF FF ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 80: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 96: FF FF FF FF FF FF
FF FF 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 112: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 128: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 160: 00 00 00 00 00 00
00 00 00 07 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 176: 01 00 00 00 00 00
00 00 44 00 05 00 00 00 00 00 ........D.......
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 192: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 208: 32 00 00 00 02 00
00 00 00 00 00 00 00 00 00 00 2...............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 224: 00 00 00 00 00 00
00 00 01 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 240: FF FF FF FF FF FF
FF FF FF FF FF FF ............
Dec 18 12:17:04 base_testgroup charon: 01[NET] received packet => 80
bytes @ 0x7fd8ac34e3b0
Dec 18 12:17:04 base_testgroup charon: 01[NET] 0: 00 00 00 00 8E 7C
68 6D 8A 91 81 C0 90 95 42 E6 .....|hm......B.
Dec 18 12:17:04 base_testgroup charon: 01[NET] 16: C7 1B 07 05 08 10
05 01 55 F3 8D 39 00 00 00 4C ........U..9...L
Dec 18 12:17:04 base_testgroup charon: 01[NET] 32: BB AB 0D A2 B9 FD
68 E1 98 B3 D1 4E 88 AD FA 07 ......h....N....
Dec 18 12:17:04 base_testgroup charon: 01[NET] 48: 5A 5E 49 5C 80 58
87 17 2B 0E 53 0D 51 8C 98 7F Z^I\.X..+.S.Q...
Dec 18 12:17:04 base_testgroup charon: 01[NET] 64: E3 07 A1 2C 0E 9E
EE F6 DD 01 A4 FA 08 E1 FF B8 ...,............
Dec 18 12:17:04 base_testgroup charon: 01[NET] received packet: from
66.77.88.99[57001] to 94.242.255.33[4500]
Dec 18 12:17:04 base_testgroup charon: 01[NET] waiting for data on sockets
Dec 18 12:17:04 base_testgroup charon: 06[MGR] checkout IKE_SA by message
Dec 18 12:17:04 base_testgroup charon: 07[KNL] policy
66.77.88.99/32[udp/l2f] === 94.242.255.33/32[udp/l2f] in (mark
0/0x00000000) already exists, increasing refcount
Dec 18 12:17:04 base_testgroup charon: 07[KNL] updating policy
66.77.88.99/32[udp/l2f] === 94.242.255.33/32[udp/l2f] in (mark
0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 07[KNL] sending
XFRM_MSG_UPDPOLICY: => 252 bytes @ 0x7fd8a874a3a0
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 0: FC 00 00 00 19 00
05 00 D4 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 16: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 32: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 48: 06 A5 FF FF 06 A5
FF FF 02 00 20 20 11 00 00 00 .......... ....
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 64: 00 00 00 00 00 00
00 00 FF FF FF FF FF FF FF FF ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 80: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 96: FF FF FF FF FF FF
FF FF 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 112: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 128: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 160: 00 00 00 00 00 00
00 00 00 07 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 176: 00 00 00 00 00 00
00 00 44 00 05 00 00 00 00 00 ........D.......
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 192: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 208: 32 00 00 00 02 00
00 00 00 00 00 00 00 00 00 00 2...............
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 224: 00 00 00 00 00 00
00 00 01 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 07[KNL] 240: FF FF FF FF FF FF
FF FF FF FF FF FF ............
Dec 18 12:17:04 base_testgroup charon: 07[IKE] CHILD_SA psk-l2tp{1}
established with SPIs c260a810_i 87cb8149_o and TS
94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f]
Dec 18 12:17:04 base_testgroup charon: 07[MGR] checkin IKE_SA psk-l2tp[1]
Dec 18 12:17:04 base_testgroup charon: 07[MGR] check-in of IKE_SA
successful.
Dec 18 12:17:04 base_testgroup charon: 06[MGR] IKE_SA psk-l2tp[1]
successfully checked out
Dec 18 12:17:04 base_testgroup charon: 06[NET] received packet: from
66.77.88.99[57001] to 94.242.255.33[4500] (76 bytes)
Dec 18 12:17:04 base_testgroup charon: 06[ENC] parsed INFORMATIONAL_V1
request 1442024761 [ HASH D ]
Dec 18 12:17:04 base_testgroup charon: 06[IKE] Hash => 20 bytes @ 0x1e0d5e0
Dec 18 12:17:04 base_testgroup charon: 06[IKE] 0: 81 2F 90 D1 BE D4
C2 2E 1B 9E 3E 59 66 EE 66 3D ./........>Yf.f=
Dec 18 12:17:04 base_testgroup charon: 06[IKE] 16: C9 6E 86
84 .n..
Dec 18 12:17:04 base_testgroup charon: 06[IKE] received DELETE for ESP
CHILD_SA with SPI 6532cbde
Dec 18 12:17:04 base_testgroup charon: 06[KNL] querying SAD entry with
SPI c78bd22c (mark 0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 06[KNL] sending XFRM_MSG_GETSA:
=> 40 bytes @ 0x7fd8a914b510
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 0: 28 00 00 00 12 00
01 00 D5 00 00 00 BD 4D 00 00 (............M..
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 16: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 32: C7 8B D2 2C 02 00
32 00 ...,..2.
Dec 18 12:17:04 base_testgroup charon: 06[KNL] querying SAD entry with
SPI 6532cbde (mark 0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 06[KNL] sending XFRM_MSG_GETSA:
=> 40 bytes @ 0x7fd8a914b510
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 0: 28 00 00 00 12 00
01 00 D6 00 00 00 BD 4D 00 00 (............M..
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 16: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 32: 65 32 CB DE 02 00
32 00 e2....2.
Dec 18 12:17:04 base_testgroup charon: 06[IKE] closing CHILD_SA
psk-l2tp{1} with SPIs c78bd22c_i (4538627 bytes) 6532cbde_o (188966643
bytes) and TS 94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f]
Dec 18 12:17:04 base_testgroup charon: 06[KNL] deleting SAD entry with
SPI c78bd22c (mark 0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 06[KNL] sending XFRM_MSG_DELSA:
=> 40 bytes @ 0x7fd8a914b590
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 0: 28 00 00 00 11 00
05 00 D7 00 00 00 BD 4D 00 00 (............M..
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 16: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 32: C7 8B D2 2C 02 00
32 00 ...,..2.
Dec 18 12:17:04 base_testgroup charon: 06[KNL] deleted SAD entry with
SPI c78bd22c (mark 0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 06[KNL] deleting SAD entry with
SPI 6532cbde (mark 0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 06[KNL] sending XFRM_MSG_DELSA:
=> 40 bytes @ 0x7fd8a914b590
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 0: 28 00 00 00 11 00
05 00 D8 00 00 00 BD 4D 00 00 (............M..
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 16: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 32: 65 32 CB DE 02 00
32 00 e2....2.
Dec 18 12:17:04 base_testgroup charon: 06[KNL] deleted SAD entry with
SPI 6532cbde (mark 0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 06[KNL] deleting policy
94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f] out (mark
0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 06[KNL] policy still used by
another CHILD_SA, not removed
Dec 18 12:17:04 base_testgroup charon: 06[KNL] updating policy
94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f] out (mark
0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 06[KNL] sending
XFRM_MSG_UPDPOLICY: => 252 bytes @ 0x7fd8a914afa0
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 0: FC 00 00 00 19 00
05 00 D9 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 16: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 32: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 48: 06 A5 FF FF 06 A5
FF FF 02 00 20 20 11 00 00 00 .......... ....
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 64: 00 00 00 00 00 00
00 00 FF FF FF FF FF FF FF FF ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 80: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 96: FF FF FF FF FF FF
FF FF 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 112: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 128: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 160: 00 00 00 00 00 00
00 00 00 07 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 176: 01 00 00 00 00 00
00 00 44 00 05 00 00 00 00 00 ........D.......
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 192: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 208: 32 00 00 00 02 00
00 00 00 00 00 00 00 00 00 00 2...............
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 224: 00 00 00 00 00 00
00 00 01 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 240: FF FF FF FF FF FF
FF FF FF FF FF FF ............
Dec 18 12:17:04 base_testgroup charon: 06[KNL] deleting policy
66.77.88.99/32[udp/l2f] === 94.242.255.33/32[udp/l2f] in (mark
0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 06[KNL] policy still used by
another CHILD_SA, not removed
Dec 18 12:17:04 base_testgroup charon: 06[KNL] updating policy
66.77.88.99/32[udp/l2f] === 94.242.255.33/32[udp/l2f] in (mark
0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 06[KNL] sending
XFRM_MSG_UPDPOLICY: => 252 bytes @ 0x7fd8a914afa0
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 0: FC 00 00 00 19 00
05 00 DA 00 00 00 BD 4D 00 00 .............M..
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 16: 5E F2 FF 21 00 00
00 00 00 00 00 00 00 00 00 00 ^..!............
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 32: 57 93 97 43 00 00
00 00 00 00 00 00 00 00 00 00 W..C............
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 48: 06 A5 FF FF 06 A5
FF FF 02 00 20 20 11 00 00 00 .......... ....
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 64: 00 00 00 00 00 00
00 00 FF FF FF FF FF FF FF FF ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 80: FF FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 96: FF FF FF FF FF FF
FF FF 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 112: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 128: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 144: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 160: 00 00 00 00 00 00
00 00 00 07 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 176: 00 00 00 00 00 00
00 00 44 00 05 00 00 00 00 00 ........D.......
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 192: 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 208: 32 00 00 00 02 00
00 00 00 00 00 00 00 00 00 00 2...............
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 224: 00 00 00 00 00 00
00 00 01 00 00 00 00 00 00 00 ................
Dec 18 12:17:04 base_testgroup charon: 06[KNL] 240: FF FF FF FF FF FF
FF FF FF FF FF FF ............
Dec 18 12:17:04 base_testgroup charon: 06[KNL] deleting policy
94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f] out (mark
0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 06[KNL] policy still used by
another CHILD_SA, not removed
Dec 18 12:17:04 base_testgroup charon: 06[KNL] deleting policy
66.77.88.99/32[udp/l2f] === 94.242.255.33/32[udp/l2f] in (mark
0/0x00000000)
Dec 18 12:17:04 base_testgroup charon: 06[KNL] policy still used by
another CHILD_SA, not removed
Dec 18 12:17:04 base_testgroup charon: 06[MGR] checkin IKE_SA psk-l2tp[1]
Dec 18 12:17:04 base_testgroup charon: 06[MGR] check-in of IKE_SA
successful.
Dec 18 12:17:04 base_testgroup charon: 07[IKE] CHILD_SA psk-l2tp{1}
established with SPIs c260a810_i 87cb8149_o and TS
94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f]
Dec 18 12:17:04 base_testgroup charon: 06[IKE] closing CHILD_SA
psk-l2tp{1} with SPIs c78bd22c_i (4538627 bytes) 6532cbde_o (188966643
bytes) and TS 94.242.255.33/32[udp/l2f] === 66.77.88.99/32[udp/l2f]
Dec 18 12:17:06 base_testgroup CRON[19967]: pam_unix(cron:session):
session closed for user root
Dec 18 12:17:08 base_testgroup charon: 08[MGR] checkout IKE_SA
Dec 18 12:17:08 base_testgroup charon: 08[MGR] IKE_SA psk-l2tp[1]
successfully checked out
Dec 18 12:17:08 base_testgroup charon: 08[MGR] checkin IKE_SA psk-l2tp[1]
Dec 18 12:17:08 base_testgroup charon: 08[MGR] check-in of IKE_SA
successful.
#### At this point, the tunnel is is stalled, Windows still thinks its
connected, ####
#### but there are no more packets transmitted
####
Dec 18 12:18:03 base_testgroup xl2tpd[5398]: handle_avps: handling avp's
for tunnel 17635, call 10899904
Dec 18 12:18:03 base_testgroup xl2tpd[5398]: message_type_avp: message
type 6 (Hello)
Dec 18 12:18:03 base_testgroup xl2tpd[5398]: control_finish: message
type is Hello(6). Tunnel is 6, call is 0.
Dec 18 12:19:01 base_testgroup /USR/SBIN/CRON[20015]: (root) CMD (
/etc/publicTraffic.py)
Dec 18 12:19:03 base_testgroup xl2tpd[5398]: handle_avps: handling avp's
for tunnel 17635, call 10899904
Dec 18 12:19:03 base_testgroup xl2tpd[5398]: message_type_avp: message
type 6 (Hello)
Dec 18 12:19:03 base_testgroup xl2tpd[5398]: control_finish: message
type is Hello(6). Tunnel is 6, call is 0.
Dec 18 12:19:16 base_testgroup pppd[19945]: No response to 4 echo-requests
Dec 18 12:19:16 base_testgroup pppd[19945]: Serial link appears to be
disconnected.
Dec 18 12:19:16 base_testgroup pppd[19945]: Connect time 3.0 minutes.
Dec 18 12:19:16 base_testgroup pppd[19945]: Sent 187051136 bytes,
received 3098392 bytes.
Dec 18 12:19:16 base_testgroup charon: 10[KNL] interface ppp0 deactivated
Dec 18 12:19:16 base_testgroup charon: 09[KNL] 10.1.26.1 disappeared
from ppp0
Dec 18 12:19:16 base_testgroup charon: 12[KNL] creating roam job due to
address/link change
Dec 18 12:19:16 base_testgroup charon: 12[MGR] checkout IKE_SA
Dec 18 12:19:16 base_testgroup charon: 12[MGR] IKE_SA psk-l2tp[1]
successfully checked out
Dec 18 12:19:16 base_testgroup charon: 12[KNL] using 94.242.255.33 as
address to reach 66.77.88.99
Dec 18 12:19:16 base_testgroup charon: 12[IKE] keeping connection path
94.242.255.33 - 66.77.88.99
Dec 18 12:19:16 base_testgroup charon: 12[MGR] checkin IKE_SA psk-l2tp[1]
Dec 18 12:19:16 base_testgroup charon: 12[MGR] check-in of IKE_SA
successful.
Dec 18 12:19:22 base_testgroup pppd[19945]: Connection terminated.
Dec 18 12:19:22 base_testgroup charon: 13[KNL] interface ppp0 deleted
Dec 18 12:19:22 base_testgroup pppd[19945]: Modem hangup
Dec 18 12:19:22 base_testgroup pppd[19945]: Exit.
Dec 18 12:19:22 base_testgroup xl2tpd[5398]: child_handler : pppd exited
for call 1 with code 16
Dec 18 12:19:22 base_testgroup xl2tpd[5398]: call_close: Call 3501 to
66.77.88.99 disconnected
Dec 18 12:19:22 base_testgroup xl2tpd[5398]: handle_avps: handling avp's
for tunnel 17635, call 10899904
Dec 18 12:19:22 base_testgroup xl2tpd[5398]: message_type_avp: message
type 4 (Stop-Control-Connection-Notification)
Dec 18 12:19:22 base_testgroup xl2tpd[5398]: assigned_tunnel_avp: using
peer's tunnel 6
Dec 18 12:19:22 base_testgroup xl2tpd[5398]: result_code_avp: peer
closing for reason 6 (Requester is being shut down), error = 0 ()
Dec 18 12:19:22 base_testgroup xl2tpd[5398]: control_finish: message
type is Stop-Control-Connection-Notification(4). Tunnel is 6, call is 0.
Dec 18 12:19:22 base_testgroup xl2tpd[5398]: control_finish: Connection
closed to 66.77.88.99, port 1701 (), Local: 17635, Remote: 6
Dec 18 12:19:22 base_testgroup xl2tpd[5398]: build_fdset: closing down
tunnel 17635
Dec 18 12:19:22 base_testgroup xl2tpd[5398]: Terminating pppd: sending
TERM signal to pid 19945
Dec 18 12:19:22 base_testgroup charon: 03[KNL] creating roam job due to
address/link change
Dec 18 12:19:22 base_testgroup charon: 03[MGR] checkout IKE_SA
Dec 18 12:19:22 base_testgroup charon: 03[MGR] IKE_SA psk-l2tp[1]
successfully checked out
Dec 18 12:19:22 base_testgroup charon: 03[KNL] using 94.242.255.33 as
address to reach 66.77.88.99
Dec 18 12:19:22 base_testgroup charon: 03[IKE] keeping connection path
94.242.255.33 - 66.77.88.99
Dec 18 12:19:22 base_testgroup charon: 03[MGR] checkin IKE_SA psk-l2tp[1]
Dec 18 12:19:22 base_testgroup charon: 03[MGR] check-in of IKE_SA
successful.
More information about the Users
mailing list