Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 302685 Details for
Bug 442821
[IPv6-DoD] openswan ikev2 fails with IPv6 and full pluto debug logging
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
Logs from eal3 (responder)
eal3.log (text/plain), 88.68 KB, created by
IBM Bug Proxy
on 2008-04-17 00:40:30 UTC
(
hide
)
Description:
Logs from eal3 (responder)
Filename:
MIME Type:
Creator:
IBM Bug Proxy
Created:
2008-04-17 00:40:30 UTC
Size:
88.68 KB
patch
obsolete
> >Apr 16 02:15:24 eal3 ipsec__plutorun: Starting Pluto subsystem... >Apr 16 02:15:24 eal3 pluto[26516]: Starting Pluto (Openswan Version 2.6.11; Vendor ID OE^\177LyWKCETe) pid:26516 >Apr 16 02:15:24 eal3 pluto[26516]: Setting NAT-Traversal port-4500 floating to on >Apr 16 02:15:24 eal3 pluto[26516]: port floating activation criteria nat_t=1/port_float=1 >Apr 16 02:15:24 eal3 pluto[26516]: including NAT-Traversal patch (Version 0.6c) >Apr 16 02:15:24 eal3 pluto[26516]: | opening /dev/urandom >Apr 16 02:15:24 eal3 pluto[26516]: using /dev/urandom as source of random entropy >Apr 16 02:15:24 eal3 pluto[26516]: | inserting event EVENT_REINIT_SECRET, timeout in 3600 seconds >Apr 16 02:15:24 eal3 pluto[26516]: | event added at head of queue >Apr 16 02:15:24 eal3 pluto[26516]: | inserting event EVENT_PENDING_PHASE2, timeout in 120 seconds >Apr 16 02:15:24 eal3 pluto[26516]: | event added at head of queue >Apr 16 02:15:24 eal3 pluto[26516]: ike_alg_register_enc(): Activating OAKLEY_TWOFISH_CBC_SSH: Ok (ret=0) >Apr 16 02:15:24 eal3 pluto[26516]: ike_alg_register_enc(): Activating OAKLEY_TWOFISH_CBC: Ok (ret=0) >Apr 16 02:15:24 eal3 pluto[26516]: ike_alg_register_enc(): Activating OAKLEY_SERPENT_CBC: Ok (ret=0) >Apr 16 02:15:24 eal3 pluto[26516]: ike_alg_register_enc(): Activating OAKLEY_AES_CBC: Ok (ret=0) >Apr 16 02:15:24 eal3 pluto[26516]: ike_alg_register_enc(): Activating OAKLEY_BLOWFISH_CBC: Ok (ret=0) >Apr 16 02:15:24 eal3 pluto[26516]: ike_alg_register_hash(): Activating OAKLEY_SHA2_512: Ok (ret=0) >Apr 16 02:15:24 eal3 pluto[26516]: ike_alg_register_hash(): Activating OAKLEY_SHA2_256: Ok (ret=0) >Apr 16 02:15:24 eal3 pluto[26516]: starting up 3 cryptographic helpers >Apr 16 02:15:24 eal3 pluto[26516]: started helper pid=26527 (fd:7) >Apr 16 02:15:24 eal3 pluto[26527]: | opening /dev/urandom >Apr 16 02:15:24 eal3 pluto[26528]: | opening /dev/urandom >Apr 16 02:15:24 eal3 pluto[26516]: started helper pid=26528 (fd:8) >Apr 16 02:15:24 eal3 pluto[26527]: using /dev/urandom as source of random entropy >Apr 16 02:15:24 eal3 pluto[26528]: using /dev/urandom as source of random entropy >Apr 16 02:15:24 eal3 pluto[26530]: | opening /dev/urandom >Apr 16 02:15:24 eal3 pluto[26516]: started helper pid=26530 (fd:9) >Apr 16 02:15:24 eal3 pluto[26527]: ! helper 0 waiting on fd: 8 >Apr 16 02:15:24 eal3 pluto[26528]: ! helper 1 waiting on fd: 9 >Apr 16 02:15:25 eal3 pluto[26530]: using /dev/urandom as source of random entropy >Apr 16 02:15:25 eal3 pluto[26516]: Using Linux 2.6 IPsec interface code on 2.6.18-87.el5 (experimental code) >Apr 16 02:15:25 eal3 pluto[26530]: ! helper 2 waiting on fd: 10 >Apr 16 02:15:25 eal3 pluto[26516]: | process 26516 listening for PF_KEY_V2 on file descriptor 12 >Apr 16 02:15:25 eal3 pluto[26516]: | finish_pfkey_msg: SADB_REGISTER message 1 for AH >Apr 16 02:15:25 eal3 pluto[26516]: | 02 07 00 02 02 00 00 00 01 00 00 00 94 67 00 00 >Apr 16 02:15:25 eal3 pluto[26516]: | pfkey_get: SADB_REGISTER message 1 >Apr 16 02:15:25 eal3 pluto[26516]: | AH registered with kernel. >Apr 16 02:15:25 eal3 pluto[26516]: | finish_pfkey_msg: SADB_REGISTER message 2 for ESP >Apr 16 02:15:25 eal3 pluto[26516]: | 02 07 00 03 02 00 00 00 02 00 00 00 94 67 00 00 >Apr 16 02:15:25 eal3 pluto[26516]: | pfkey_get: SADB_REGISTER message 2 >Apr 16 02:15:25 eal3 pluto[26516]: | alg_init():memset(0x5a3a00, 0, 2016) memset(0x5a41e0, 0, 2048) >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: sadb_msg_len=19 sadb_supported_len=56 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=14, alg_id=251 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[0], exttype=14, satype=3, alg_id=251, alg_ivlen=0, alg_minbits=0, alg_maxbits=0, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=14, alg_id=2 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[1], exttype=14, satype=3, alg_id=2, alg_ivlen=0, alg_minbits=128, alg_maxbits=128, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=14, alg_id=3 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[2], exttype=14, satype=3, alg_id=3, alg_ivlen=0, alg_minbits=160, alg_maxbits=160, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=14, alg_id=5 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[3], exttype=14, satype=3, alg_id=5, alg_ivlen=0, alg_minbits=256, alg_maxbits=256, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=14, alg_id=8 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[4], exttype=14, satype=3, alg_id=8, alg_ivlen=0, alg_minbits=160, alg_maxbits=160, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=14, alg_id=9 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[5], exttype=14, satype=3, alg_id=9, alg_ivlen=0, alg_minbits=128, alg_maxbits=128, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: sadb_msg_len=19 sadb_supported_len=80 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=15, alg_id=11 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[6], exttype=15, satype=3, alg_id=11, alg_ivlen=0, alg_minbits=0, alg_maxbits=0, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=15, alg_id=2 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[7], exttype=15, satype=3, alg_id=2, alg_ivlen=8, alg_minbits=64, alg_maxbits=64, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=15, alg_id=3 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[8], exttype=15, satype=3, alg_id=3, alg_ivlen=8, alg_minbits=192, alg_maxbits=192, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=15, alg_id=6 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[9], exttype=15, satype=3, alg_id=6, alg_ivlen=8, alg_minbits=40, alg_maxbits=128, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=15, alg_id=7 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[10], exttype=15, satype=3, alg_id=7, alg_ivlen=8, alg_minbits=40, alg_maxbits=448, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=15, alg_id=12 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[11], exttype=15, satype=3, alg_id=12, alg_ivlen=8, alg_minbits=128, alg_maxbits=256, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=15, alg_id=252 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[12], exttype=15, satype=3, alg_id=252, alg_ivlen=8, alg_minbits=128, alg_maxbits=256, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=15, alg_id=253 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[13], exttype=15, satype=3, alg_id=253, alg_ivlen=8, alg_minbits=128, alg_maxbits=256, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_add():satype=3, exttype=15, alg_id=13 >Apr 16 02:15:25 eal3 pluto[26516]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[14], exttype=15, satype=3, alg_id=13, alg_ivlen=8, alg_minbits=128, alg_maxbits=256, res=0, ret=1 >Apr 16 02:15:25 eal3 pluto[26516]: | ESP registered with kernel. >Apr 16 02:15:26 eal3 pluto[26516]: | finish_pfkey_msg: SADB_REGISTER message 3 for IPCOMP >Apr 16 02:15:26 eal3 pluto[26516]: | 02 07 00 09 02 00 00 00 03 00 00 00 94 67 00 00 >Apr 16 02:15:26 eal3 pluto[26516]: | pfkey_get: SADB_REGISTER message 3 >Apr 16 02:15:26 eal3 pluto[26516]: | IPCOMP registered with kernel. >Apr 16 02:15:26 eal3 pluto[26516]: Could not change to directory '/etc/ipsec.d/cacerts': / >Apr 16 02:15:26 eal3 pluto[26516]: Could not change to directory '/etc/ipsec.d/aacerts': / >Apr 16 02:15:26 eal3 pluto[26516]: Could not change to directory '/etc/ipsec.d/ocspcerts': / >Apr 16 02:15:26 eal3 pluto[26516]: Could not change to directory '/etc/ipsec.d/crls' >Apr 16 02:15:26 eal3 pluto[26516]: | inserting event EVENT_LOG_DAILY, timeout in 78274 seconds >Apr 16 02:15:26 eal3 pluto[26516]: | event added after event EVENT_REINIT_SECRET >Apr 16 02:15:26 eal3 pluto[26516]: | next event EVENT_PENDING_PHASE2 in 118 seconds >Apr 16 02:15:26 eal3 pluto[26516]: | >Apr 16 02:15:26 eal3 pluto[26516]: | *received whack message >Apr 16 02:15:26 eal3 pluto[26516]: | Added new connection i386-i386-v6 with policy PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Apr 16 02:15:26 eal3 pluto[26516]: | counting wild cards for (none) is 15 >Apr 16 02:15:26 eal3 pluto[26516]: | counting wild cards for (none) is 15 >Apr 16 02:15:26 eal3 pluto[26516]: added connection description "i386-i386-v6" >Apr 16 02:15:26 eal3 pluto[26516]: | fc00::105:0:0:0:22<fc00:0:0:105::22>[S=C]...fc00::105:0:0:0:24<fc00:0:0:105::24>[S=C] >Apr 16 02:15:26 eal3 pluto[26516]: | ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 3; policy: PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Apr 16 02:15:26 eal3 pluto[26516]: | * processed 0 messages from cryptographic helpers >Apr 16 02:15:26 eal3 pluto[26516]: | next event EVENT_PENDING_PHASE2 in 118 seconds >Apr 16 02:15:26 eal3 pluto[26516]: | >Apr 16 02:15:26 eal3 pluto[26516]: | *received whack message >Apr 16 02:15:26 eal3 pluto[26516]: listening for IKE messages >Apr 16 02:15:26 eal3 pluto[26516]: | found lo with address 127.0.0.1 >Apr 16 02:15:26 eal3 pluto[26516]: | found eth0 with address 9.3.190.196 >Apr 16 02:15:26 eal3 pluto[26516]: adding interface eth0/eth0 9.3.190.196:500 >Apr 16 02:15:26 eal3 pluto[26516]: adding interface eth0/eth0 9.3.190.196:4500 >Apr 16 02:15:26 eal3 pluto[26516]: adding interface lo/lo 127.0.0.1:500 >Apr 16 02:15:26 eal3 pluto[26516]: adding interface lo/lo 127.0.0.1:4500 >Apr 16 02:15:26 eal3 pluto[26516]: | found lo with address 0000:0000:0000:0000:0000:0000:0000:0001 >Apr 16 02:15:26 eal3 pluto[26516]: | found eth0 with address fc00:0000:0000:0105:0000:0000:0000:0024 >Apr 16 02:15:26 eal3 pluto[26516]: adding interface eth0/eth0 fc00::105:0:0:0:24:500 >Apr 16 02:15:26 eal3 pluto[26516]: adding interface lo/lo ::1:500 >Apr 16 02:15:26 eal3 pluto[26516]: | connect_to_host_pair: fc00::105:0:0:0:24:500 fc00::105:0:0:0:22:500 -> hp:none >Apr 16 02:15:26 eal3 pluto[26516]: loading secrets from "/etc/ipsec.secrets" >Apr 16 02:15:26 eal3 pluto[26516]: | id type added to secret(0x995aef8) 1: fc00::105:0:0:0:22 >Apr 16 02:15:26 eal3 pluto[26516]: | id type added to secret(0x995aef8) 1: fc00::105:0:0:0:23 >Apr 16 02:15:26 eal3 pluto[26516]: | id type added to secret(0x995aef8) 1: fc00::105:0:0:0:24 >Apr 16 02:15:26 eal3 pluto[26516]: | Processing PSK at line 1: passed >Apr 16 02:15:26 eal3 pluto[26516]: | * processed 0 messages from cryptographic helpers >Apr 16 02:15:26 eal3 pluto[26516]: | next event EVENT_PENDING_PHASE2 in 118 seconds >Apr 16 02:15:55 eal3 pluto[26516]: | >Apr 16 02:15:55 eal3 pluto[26516]: | *received 668 bytes from fc00::105:0:0:0:22:500 on eth0 (port=500) >Apr 16 02:15:55 eal3 pluto[26516]: | 5c 24 23 06 49 5c ec a0 00 00 00 00 00 00 00 00 >Apr 16 02:15:55 eal3 pluto[26516]: | 21 20 22 08 00 00 00 00 00 00 02 9c 22 80 01 94 >Apr 16 02:15:55 eal3 pluto[26516]: | 02 00 00 28 01 01 00 04 03 00 00 08 01 00 00 0c >Apr 16 02:15:55 eal3 pluto[26516]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 16 02:15:55 eal3 pluto[26516]: | 00 00 00 08 04 00 00 05 02 00 00 28 02 01 00 04 >Apr 16 02:15:55 eal3 pluto[26516]: | 03 00 00 08 01 00 00 0c 03 00 00 08 03 00 00 02 >Apr 16 02:15:55 eal3 pluto[26516]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 05 >Apr 16 02:15:55 eal3 pluto[26516]: | 02 00 00 28 03 01 00 04 03 00 00 08 01 00 00 03 >Apr 16 02:15:55 eal3 pluto[26516]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 16 02:15:55 eal3 pluto[26516]: | 00 00 00 08 04 00 00 05 02 00 00 28 04 01 00 04 >Apr 16 02:15:55 eal3 pluto[26516]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Apr 16 02:15:55 eal3 pluto[26516]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 05 >Apr 16 02:15:55 eal3 pluto[26516]: | 02 00 00 28 05 01 00 04 03 00 00 08 01 00 00 03 >Apr 16 02:15:55 eal3 pluto[26516]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 16 02:15:55 eal3 pluto[26516]: | 00 00 00 08 04 00 00 02 02 00 00 28 06 01 00 04 >Apr 16 02:15:55 eal3 pluto[26516]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Apr 16 02:15:55 eal3 pluto[26516]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 02 >Apr 16 02:15:55 eal3 pluto[26516]: | 02 00 00 28 07 01 00 04 03 00 00 08 01 00 00 0c >Apr 16 02:15:55 eal3 pluto[26516]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 16 02:15:55 eal3 pluto[26516]: | 00 00 00 08 04 00 00 0e 02 00 00 28 08 01 00 04 >Apr 16 02:15:55 eal3 pluto[26516]: | 03 00 00 08 01 00 00 0c 03 00 00 08 03 00 00 02 >Apr 16 02:15:55 eal3 pluto[26516]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 0e >Apr 16 02:15:55 eal3 pluto[26516]: | 02 00 00 28 09 01 00 04 03 00 00 08 01 00 00 03 >Apr 16 02:15:56 eal3 pluto[26516]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 16 02:15:56 eal3 pluto[26516]: | 00 00 00 08 04 00 00 0e 00 00 00 28 0a 01 00 04 >Apr 16 02:15:56 eal3 pluto[26516]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Apr 16 02:15:56 eal3 pluto[26516]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 0e >Apr 16 02:15:56 eal3 pluto[26516]: | 28 00 00 c8 00 05 00 00 59 99 03 df ac 62 97 2b >Apr 16 02:15:56 eal3 pluto[26516]: | 1c 91 f4 c2 8b 3b 6f 14 d3 35 52 22 b8 0f 8e ab >Apr 16 02:15:56 eal3 pluto[26516]: | c2 b0 b3 b2 39 41 69 47 4c f5 c0 aa b3 b6 99 92 >Apr 16 02:15:56 eal3 pluto[26516]: | 6f 53 1a b5 0a 9a 9a 10 df 28 31 5d 26 ee 5c 19 >Apr 16 02:15:56 eal3 pluto[26516]: | e8 bd 97 f7 9a 22 5c 7a 43 30 2d 5c 3b d7 ea da >Apr 16 02:15:56 eal3 pluto[26516]: | 54 23 26 05 8a 34 a8 93 cf 81 26 f0 89 04 c3 ab >Apr 16 02:15:56 eal3 pluto[26516]: | ec 05 a7 9b 8d 0b 77 9f 78 ec 81 da c0 70 9d 1c >Apr 16 02:15:56 eal3 pluto[26516]: | 33 1d dc fe 2c 86 0c cd e6 5f 34 bf bd c6 76 9e >Apr 16 02:15:56 eal3 pluto[26516]: | 20 42 e2 30 c3 17 22 68 71 64 b1 c5 52 c4 1a 09 >Apr 16 02:15:56 eal3 pluto[26516]: | 2d 2a bd 6c a2 00 dd 68 f7 11 b8 60 8d ca 5d 7f >Apr 16 02:15:56 eal3 pluto[26516]: | b8 49 a4 07 22 29 c1 11 bf e4 2f e7 08 85 7c cd >Apr 16 02:15:56 eal3 pluto[26516]: | 3e 73 5d 09 94 61 a9 8d ff b8 10 0a 29 73 bb 3e >Apr 16 02:15:56 eal3 pluto[26516]: | 79 ed 14 b3 07 8b e9 4a 2b 80 00 14 42 2f 14 f6 >Apr 16 02:15:56 eal3 pluto[26516]: | 2a 16 73 a4 80 7c fc e7 e8 63 bb d2 00 00 00 10 >Apr 16 02:15:56 eal3 pluto[26516]: | 4f 45 5e 7f 4c 79 57 4b 43 45 54 65 >Apr 16 02:15:56 eal3 pluto[26516]: | **parse ISAKMP Message: >Apr 16 02:15:56 eal3 pluto[26516]: | initiator cookie: >Apr 16 02:15:56 eal3 pluto[26516]: | 5c 24 23 06 49 5c ec a0 >Apr 16 02:15:56 eal3 pluto[26516]: | responder cookie: >Apr 16 02:15:56 eal3 pluto[26516]: | 00 00 00 00 00 00 00 00 >Apr 16 02:15:56 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2SA >Apr 16 02:15:56 eal3 pluto[26516]: | ISAKMP version: IKEv2 version 2.0 (rfc4306) >Apr 16 02:15:56 eal3 pluto[26516]: | exchange type: ISAKMP_v2_SA_INIT >Apr 16 02:15:56 eal3 pluto[26516]: | flags: ISAKMP_FLAG_INIT >Apr 16 02:15:56 eal3 pluto[26516]: | message ID: 00 00 00 00 >Apr 16 02:15:56 eal3 pluto[26516]: | length: 668 >Apr 16 02:15:56 eal3 pluto[26516]: | processing version=2.0 packet with exchange type=ISAKMP_v2_SA_INIT (34) >Apr 16 02:15:56 eal3 pluto[26516]: | ICOOKIE: 5c 24 23 06 49 5c ec a0 >Apr 16 02:15:56 eal3 pluto[26516]: | RCOOKIE: 00 00 00 00 00 00 00 00 >Apr 16 02:15:56 eal3 pluto[26516]: | state hash entry 2 >Apr 16 02:15:56 eal3 pluto[26516]: | v2 state object not found >Apr 16 02:15:56 eal3 pluto[26516]: | ICOOKIE: 5c 24 23 06 49 5c ec a0 >Apr 16 02:15:56 eal3 pluto[26516]: | RCOOKIE: 00 00 00 00 00 00 00 00 >Apr 16 02:15:56 eal3 pluto[26516]: | state hash entry 2 >Apr 16 02:15:56 eal3 pluto[26516]: | v2 state object not found >Apr 16 02:15:56 eal3 pluto[26516]: | ***parse IKEv2 Security Association Payload: >Apr 16 02:15:56 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2KE >Apr 16 02:15:56 eal3 pluto[26516]: | critical bit: Payload-Critical >Apr 16 02:15:56 eal3 pluto[26516]: | length: 404 >Apr 16 02:15:56 eal3 pluto[26516]: | processing payload: ISAKMP_NEXT_v2SA (len=404) >Apr 16 02:15:56 eal3 pluto[26516]: | ***parse IKEv2 Key Exchange Payload: >Apr 16 02:15:57 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2Ni >Apr 16 02:15:57 eal3 pluto[26516]: | length: 200 >Apr 16 02:15:57 eal3 pluto[26516]: | transform type: 5 >Apr 16 02:15:57 eal3 pluto[26516]: | processing payload: ISAKMP_NEXT_v2KE (len=200) >Apr 16 02:15:57 eal3 pluto[26516]: | ***parse IKEv2 Nonce Payload: >Apr 16 02:15:57 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2V >Apr 16 02:15:57 eal3 pluto[26516]: | critical bit: Payload-Critical >Apr 16 02:15:57 eal3 pluto[26516]: | length: 20 >Apr 16 02:15:57 eal3 pluto[26516]: | processing payload: ISAKMP_NEXT_v2Ni (len=20) >Apr 16 02:15:57 eal3 pluto[26516]: | ***parse IKEv2 Vendor ID Payload: >Apr 16 02:15:57 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_NONE >Apr 16 02:15:57 eal3 pluto[26516]: | critical bit: Payload-Non-Critical >Apr 16 02:15:57 eal3 pluto[26516]: | length: 16 >Apr 16 02:15:57 eal3 pluto[26516]: | processing payload: ISAKMP_NEXT_v2V (len=16) >Apr 16 02:15:57 eal3 pluto[26516]: | find_host_connection called from ikev2parent_inI1outR1, me=fc00::105:0:0:0:24:500 him=fc00::105:0:0:0:22:500 policy=IKEv2ALLOW >Apr 16 02:15:57 eal3 pluto[26516]: | find_host_pair: comparing to fc00::105:0:0:0:24:500 fc00::105:0:0:0:22:500 >Apr 16 02:15:57 eal3 pluto[26516]: | find_host_pair_conn (find_host_connection2): fc00::105:0:0:0:24:500 fc00::105:0:0:0:22:500 -> hp:i386-i386-v6 >Apr 16 02:15:57 eal3 pluto[26516]: | searching for policy=IKEv2ALLOW, found=IKEv2ALLOW (i386-i386-v6) >Apr 16 02:15:57 eal3 pluto[26516]: | find_host_connection returns i386-i386-v6 >Apr 16 02:15:57 eal3 pluto[26516]: | found connection: i386-i386-v6 >Apr 16 02:15:57 eal3 pluto[26516]: | creating state object #1 at 0x995bf18 >Apr 16 02:15:57 eal3 pluto[26516]: | processing connection i386-i386-v6 >Apr 16 02:15:57 eal3 pluto[26516]: | ICOOKIE: 5c 24 23 06 49 5c ec a0 >Apr 16 02:15:57 eal3 pluto[26516]: | RCOOKIE: 00 00 00 00 00 00 00 00 >Apr 16 02:15:57 eal3 pluto[26516]: | state hash entry 2 >Apr 16 02:15:57 eal3 pluto[26516]: | inserting state object #1 on chain 2 >Apr 16 02:15:57 eal3 pluto[26516]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #1 >Apr 16 02:15:57 eal3 pluto[26516]: | event added at head of queue >Apr 16 02:15:57 eal3 pluto[26516]: | processing connection i386-i386-v6 >Apr 16 02:15:57 eal3 pluto[26516]: | will not send/process a dcookie >Apr 16 02:15:57 eal3 pluto[26516]: | 1: w->pcw_dead: 0 w->pcw_work: 0 cnt: 3 >Apr 16 02:15:57 eal3 pluto[26516]: | asking helper 1 to do build_kenonce op on seq: 1 (len=2668, pcw_work=1) >Apr 16 02:15:57 eal3 pluto[26516]: | crypto helper write of request: cnt=2668<wlen=2668. >Apr 16 02:15:57 eal3 pluto[26528]: ! helper 1 read 2664+4/2668 bytesfd: 9 >Apr 16 02:15:57 eal3 pluto[26516]: | deleting event for #1 >Apr 16 02:15:57 eal3 pluto[26528]: ! helper 1 doing build_kenonce op id: 1 >Apr 16 02:15:57 eal3 pluto[26516]: | inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #1 >Apr 16 02:15:57 eal3 pluto[26528]: ! Local DH secret: >Apr 16 02:15:57 eal3 pluto[26516]: | event added after event EVENT_PENDING_PHASE2 >Apr 16 02:15:57 eal3 pluto[26528]: ! 00 71 cf 31 69 11 4a 66 ec 6a bd 3d 66 36 1c 81 >Apr 16 02:15:57 eal3 pluto[26516]: | complete v2 state transition with STF_SUSPEND >Apr 16 02:15:57 eal3 pluto[26528]: ! 17 e1 da 92 f2 59 1f 99 97 27 96 b0 d6 ef 3c da >Apr 16 02:15:57 eal3 pluto[26516]: | * processed 0 messages from cryptographic helpers >Apr 16 02:15:57 eal3 pluto[26528]: ! Public DH value sent: >Apr 16 02:15:57 eal3 pluto[26516]: | next event EVENT_PENDING_PHASE2 in 87 seconds >Apr 16 02:15:57 eal3 pluto[26528]: ! 65 5d e0 e3 89 d8 e4 7f 46 72 b5 dc f2 60 8b d8 >Apr 16 02:15:57 eal3 pluto[26528]: ! 45 34 61 b2 89 04 3a aa 2e 77 3a 11 40 67 da b2 >Apr 16 02:15:57 eal3 pluto[26528]: ! d7 74 c0 1d 14 0e f7 a2 8a c8 ad 30 83 b3 48 1b >Apr 16 02:15:58 eal3 pluto[26528]: ! 45 78 dc d5 0b 8d b7 43 a7 a6 68 ae 68 80 66 49 >Apr 16 02:15:58 eal3 pluto[26528]: ! 2b 19 91 9f 15 1a 95 cf 0e 17 ae 83 8a 20 21 b4 >Apr 16 02:15:58 eal3 pluto[26528]: ! 33 79 40 01 e6 77 2c 77 69 f7 da 44 03 2f 82 98 >Apr 16 02:15:58 eal3 pluto[26528]: ! 2e df cc 7e d2 42 f9 65 d6 00 66 68 ae 34 7a 7a >Apr 16 02:15:58 eal3 pluto[26528]: ! b1 2a 9d a8 12 f3 a9 f0 db 68 d5 f0 91 66 d1 d7 >Apr 16 02:15:58 eal3 pluto[26528]: ! e7 37 13 da a0 4b 06 35 c3 25 bf 31 4b 4d 8c dc >Apr 16 02:15:58 eal3 pluto[26528]: ! f8 88 95 96 59 33 d1 a3 09 41 27 38 16 64 bd 70 >Apr 16 02:15:58 eal3 pluto[26528]: ! 0d 02 d5 c7 36 cb e3 73 98 05 af 00 08 5d f7 7e >Apr 16 02:15:58 eal3 pluto[26528]: ! 46 08 d5 cb 76 97 f5 4d 8f 17 2d f1 cc b6 62 74 >Apr 16 02:15:58 eal3 pluto[26528]: ! Generated nonce: >Apr 16 02:15:58 eal3 pluto[26528]: ! 90 ff 77 d4 84 58 4e a9 02 0e 98 2b c7 31 9b f5 >Apr 16 02:15:58 eal3 pluto[26516]: | >Apr 16 02:15:58 eal3 pluto[26516]: | helper 1 has finished work (cnt now 1) >Apr 16 02:15:58 eal3 pluto[26516]: | helper 1 replies to id: q#1 >Apr 16 02:15:58 eal3 pluto[26516]: | calling callback function 0x4d8f70 >Apr 16 02:15:58 eal3 pluto[26516]: | ikev2 parent inI1outR1: calculated ke+nonce, sending R1 >Apr 16 02:15:58 eal3 pluto[26516]: | processing connection i386-i386-v6 >Apr 16 02:15:58 eal3 pluto[26516]: | **emit ISAKMP Message: >Apr 16 02:15:58 eal3 pluto[26516]: | initiator cookie: >Apr 16 02:15:58 eal3 pluto[26516]: | 5c 24 23 06 49 5c ec a0 >Apr 16 02:15:58 eal3 pluto[26516]: | responder cookie: >Apr 16 02:15:58 eal3 pluto[26516]: | 89 c8 26 22 22 18 41 c2 >Apr 16 02:15:58 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2SA >Apr 16 02:15:58 eal3 pluto[26516]: | ISAKMP version: IKEv2 version 2.0 (rfc4306) >Apr 16 02:15:58 eal3 pluto[26516]: | exchange type: ISAKMP_v2_SA_INIT >Apr 16 02:15:58 eal3 pluto[26516]: | flags: ISAKMP_FLAG_RESPONSE >Apr 16 02:15:58 eal3 pluto[26516]: | message ID: 00 00 00 00 >Apr 16 02:15:58 eal3 pluto[26516]: | ***emit IKEv2 Security Association Payload: >Apr 16 02:15:58 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2KE >Apr 16 02:15:58 eal3 pluto[26516]: | critical bit: Payload-Critical >Apr 16 02:15:58 eal3 pluto[26516]: | no IKE algorithms for this connection >Apr 16 02:15:58 eal3 pluto[26516]: | ****parse IKEv2 Proposal Substructure Payload: >Apr 16 02:15:58 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_P >Apr 16 02:15:58 eal3 pluto[26516]: | length: 40 >Apr 16 02:15:58 eal3 pluto[26516]: | prop #: 1 >Apr 16 02:15:58 eal3 pluto[26516]: | proto ID: 1 >Apr 16 02:15:58 eal3 pluto[26516]: | spi size: 0 >Apr 16 02:15:58 eal3 pluto[26516]: | # transforms: 4 >Apr 16 02:15:58 eal3 pluto[26516]: | *****parse IKEv2 Transform Substructure Payload: >Apr 16 02:15:58 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_T >Apr 16 02:15:58 eal3 pluto[26516]: | length: 8 >Apr 16 02:15:58 eal3 pluto[26516]: | transform type: 1 >Apr 16 02:15:58 eal3 pluto[26516]: | transform ID: 12 >Apr 16 02:15:58 eal3 pluto[26516]: | *****parse IKEv2 Transform Substructure Payload: >Apr 16 02:15:58 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_T >Apr 16 02:15:58 eal3 pluto[26516]: | length: 8 >Apr 16 02:15:58 eal3 pluto[26516]: | transform type: 3 >Apr 16 02:15:58 eal3 pluto[26516]: | transform ID: 2 >Apr 16 02:15:58 eal3 pluto[26516]: | *****parse IKEv2 Transform Substructure Payload: >Apr 16 02:15:59 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_T >Apr 16 02:15:59 eal3 pluto[26516]: | length: 8 >Apr 16 02:15:59 eal3 pluto[26516]: | transform type: 2 >Apr 16 02:15:59 eal3 pluto[26516]: | transform ID: 2 >Apr 16 02:15:59 eal3 pluto[26516]: | *****parse IKEv2 Transform Substructure Payload: >Apr 16 02:15:59 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_NONE >Apr 16 02:15:59 eal3 pluto[26516]: | length: 8 >Apr 16 02:15:59 eal3 pluto[26516]: | transform type: 4 >Apr 16 02:15:59 eal3 pluto[26516]: | transform ID: 5 >Apr 16 02:15:59 eal3 pluto[26516]: | ****parse IKEv2 Proposal Substructure Payload: >Apr 16 02:15:59 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_P >Apr 16 02:15:59 eal3 pluto[26516]: | length: 40 >Apr 16 02:15:59 eal3 pluto[26516]: | prop #: 2 >Apr 16 02:15:59 eal3 pluto[26516]: | proto ID: 1 >Apr 16 02:15:59 eal3 pluto[26516]: | spi size: 0 >Apr 16 02:15:59 eal3 pluto[26516]: | # transforms: 4 >Apr 16 02:15:59 eal3 pluto[26516]: | ****emit IKEv2 Proposal Substructure Payload: >Apr 16 02:15:59 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_NONE >Apr 16 02:15:59 eal3 pluto[26516]: | prop #: 1 >Apr 16 02:15:59 eal3 pluto[26516]: | proto ID: 1 >Apr 16 02:15:59 eal3 pluto[26516]: | spi size: 0 >Apr 16 02:15:59 eal3 pluto[26516]: | # transforms: 4 >Apr 16 02:15:59 eal3 pluto[26516]: | *****emit IKEv2 Transform Substructure Payload: >Apr 16 02:15:59 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_T >Apr 16 02:15:59 eal3 pluto[26516]: | transform type: 1 >Apr 16 02:15:59 eal3 pluto[26516]: | transform ID: 12 >Apr 16 02:15:59 eal3 pluto[26516]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 16 02:15:59 eal3 pluto[26516]: | *****emit IKEv2 Transform Substructure Payload: >Apr 16 02:15:59 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_T >Apr 16 02:15:59 eal3 pluto[26516]: | transform type: 3 >Apr 16 02:15:59 eal3 pluto[26516]: | transform ID: 2 >Apr 16 02:15:59 eal3 pluto[26516]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 16 02:15:59 eal3 pluto[26516]: | *****emit IKEv2 Transform Substructure Payload: >Apr 16 02:15:59 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_T >Apr 16 02:15:59 eal3 pluto[26516]: | transform type: 2 >Apr 16 02:15:59 eal3 pluto[26516]: | transform ID: 2 >Apr 16 02:15:59 eal3 pluto[26516]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 16 02:15:59 eal3 pluto[26516]: | *****emit IKEv2 Transform Substructure Payload: >Apr 16 02:15:59 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_NONE >Apr 16 02:15:59 eal3 pluto[26516]: | transform type: 4 >Apr 16 02:15:59 eal3 pluto[26516]: | transform ID: 5 >Apr 16 02:15:59 eal3 pluto[26516]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 16 02:15:59 eal3 pluto[26516]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Apr 16 02:15:59 eal3 pluto[26516]: | emitting length of IKEv2 Security Association Payload: 44 >Apr 16 02:15:59 eal3 pluto[26516]: | DH public value received: >Apr 16 02:16:00 eal3 pluto[26516]: | 59 99 03 df ac 62 97 2b 1c 91 f4 c2 8b 3b 6f 14 >Apr 16 02:16:00 eal3 pluto[26516]: | d3 35 52 22 b8 0f 8e ab c2 b0 b3 b2 39 41 69 47 >Apr 16 02:16:00 eal3 pluto[26516]: | 4c f5 c0 aa b3 b6 99 92 6f 53 1a b5 0a 9a 9a 10 >Apr 16 02:16:00 eal3 pluto[26516]: | df 28 31 5d 26 ee 5c 19 e8 bd 97 f7 9a 22 5c 7a >Apr 16 02:16:00 eal3 pluto[26516]: | 43 30 2d 5c 3b d7 ea da 54 23 26 05 8a 34 a8 93 >Apr 16 02:16:00 eal3 pluto[26516]: | cf 81 26 f0 89 04 c3 ab ec 05 a7 9b 8d 0b 77 9f >Apr 16 02:16:00 eal3 pluto[26516]: | 78 ec 81 da c0 70 9d 1c 33 1d dc fe 2c 86 0c cd >Apr 16 02:16:00 eal3 pluto[26516]: | e6 5f 34 bf bd c6 76 9e 20 42 e2 30 c3 17 22 68 >Apr 16 02:16:00 eal3 pluto[26516]: | 71 64 b1 c5 52 c4 1a 09 2d 2a bd 6c a2 00 dd 68 >Apr 16 02:16:00 eal3 pluto[26516]: | f7 11 b8 60 8d ca 5d 7f b8 49 a4 07 22 29 c1 11 >Apr 16 02:16:00 eal3 pluto[26516]: | bf e4 2f e7 08 85 7c cd 3e 73 5d 09 94 61 a9 8d >Apr 16 02:16:00 eal3 pluto[26516]: | ff b8 10 0a 29 73 bb 3e 79 ed 14 b3 07 8b e9 4a >Apr 16 02:16:00 eal3 pluto[26516]: | ***emit IKEv2 Key Exchange Payload: >Apr 16 02:16:00 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2Ni >Apr 16 02:16:00 eal3 pluto[26516]: | transform type: 5 >Apr 16 02:16:00 eal3 pluto[26516]: | emitting 192 raw bytes of ikev2 g^x into IKEv2 Key Exchange Payload >Apr 16 02:16:00 eal3 pluto[26516]: | ikev2 g^x 65 5d e0 e3 89 d8 e4 7f 46 72 b5 dc f2 60 8b d8 >Apr 16 02:16:00 eal3 pluto[26516]: | ikev2 g^x 45 34 61 b2 89 04 3a aa 2e 77 3a 11 40 67 da b2 >Apr 16 02:16:00 eal3 pluto[26516]: | ikev2 g^x d7 74 c0 1d 14 0e f7 a2 8a c8 ad 30 83 b3 48 1b >Apr 16 02:16:00 eal3 pluto[26516]: | ikev2 g^x 45 78 dc d5 0b 8d b7 43 a7 a6 68 ae 68 80 66 49 >Apr 16 02:16:00 eal3 pluto[26516]: | ikev2 g^x 2b 19 91 9f 15 1a 95 cf 0e 17 ae 83 8a 20 21 b4 >Apr 16 02:16:00 eal3 pluto[26516]: | ikev2 g^x 33 79 40 01 e6 77 2c 77 69 f7 da 44 03 2f 82 98 >Apr 16 02:16:00 eal3 pluto[26516]: | ikev2 g^x 2e df cc 7e d2 42 f9 65 d6 00 66 68 ae 34 7a 7a >Apr 16 02:16:00 eal3 pluto[26516]: | ikev2 g^x b1 2a 9d a8 12 f3 a9 f0 db 68 d5 f0 91 66 d1 d7 >Apr 16 02:16:00 eal3 pluto[26516]: | ikev2 g^x e7 37 13 da a0 4b 06 35 c3 25 bf 31 4b 4d 8c dc >Apr 16 02:16:00 eal3 pluto[26516]: | ikev2 g^x f8 88 95 96 59 33 d1 a3 09 41 27 38 16 64 bd 70 >Apr 16 02:16:00 eal3 pluto[26516]: | ikev2 g^x 0d 02 d5 c7 36 cb e3 73 98 05 af 00 08 5d f7 7e >Apr 16 02:16:00 eal3 pluto[26516]: | ikev2 g^x 46 08 d5 cb 76 97 f5 4d 8f 17 2d f1 cc b6 62 74 >Apr 16 02:16:00 eal3 pluto[26516]: | emitting length of IKEv2 Key Exchange Payload: 200 >Apr 16 02:16:00 eal3 pluto[26516]: | ***emit IKEv2 Nonce Payload: >Apr 16 02:16:00 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2V >Apr 16 02:16:00 eal3 pluto[26516]: | critical bit: Payload-Critical >Apr 16 02:16:00 eal3 pluto[26516]: | emitting 16 raw bytes of IKEv2 nonce into IKEv2 Nonce Payload >Apr 16 02:16:00 eal3 pluto[26516]: | IKEv2 nonce 90 ff 77 d4 84 58 4e a9 02 0e 98 2b c7 31 9b f5 >Apr 16 02:16:00 eal3 pluto[26516]: | emitting length of IKEv2 Nonce Payload: 20 >Apr 16 02:16:00 eal3 pluto[26516]: | ***emit ISAKMP Vendor ID Payload: >Apr 16 02:16:00 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_NONE >Apr 16 02:16:00 eal3 pluto[26516]: | emitting 12 raw bytes of Vendor ID into ISAKMP Vendor ID Payload >Apr 16 02:16:00 eal3 pluto[26516]: | Vendor ID 4f 45 5e 7f 4c 79 57 4b 43 45 54 65 >Apr 16 02:16:00 eal3 pluto[26516]: | emitting length of ISAKMP Vendor ID Payload: 16 >Apr 16 02:16:00 eal3 pluto[26516]: | emitting length of ISAKMP Message: 308 >Apr 16 02:16:00 eal3 pluto[26516]: | complete v2 state transition with STF_OK >Apr 16 02:16:00 eal3 pluto[26516]: "i386-i386-v6" #1: transition from state STATE_IKEv2_START to state STATE_PARENT_R1 >Apr 16 02:16:00 eal3 pluto[26516]: "i386-i386-v6" #1: STATE_PARENT_R1: received v2I1, sent v2R1 {auth=IKEv2 cipher=aes_128 integ=sha1 prf=oakley_sha group=modp1536} >Apr 16 02:16:00 eal3 pluto[26516]: | sending reply packet to fc00::105:0:0:0:22:500 (from port 500) >Apr 16 02:16:00 eal3 pluto[26516]: | sending 308 bytes for STATE_IKEv2_START through eth0:500 to fc00::105:0:0:0:22:500 (using #1) >Apr 16 02:16:01 eal3 pluto[26516]: | 5c 24 23 06 49 5c ec a0 89 c8 26 22 22 18 41 c2 >Apr 16 02:16:01 eal3 pluto[26516]: | 21 20 22 20 00 00 00 00 00 00 01 34 22 80 00 2c >Apr 16 02:16:01 eal3 pluto[26516]: | 00 00 00 28 01 01 00 04 03 00 00 08 01 00 00 0c >Apr 16 02:16:01 eal3 pluto[26516]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 16 02:16:01 eal3 pluto[26516]: | 00 00 00 08 04 00 00 05 28 00 00 c8 00 05 00 00 >Apr 16 02:16:01 eal3 pluto[26516]: | 65 5d e0 e3 89 d8 e4 7f 46 72 b5 dc f2 60 8b d8 >Apr 16 02:16:01 eal3 pluto[26516]: | 45 34 61 b2 89 04 3a aa 2e 77 3a 11 40 67 da b2 >Apr 16 02:16:01 eal3 pluto[26516]: | d7 74 c0 1d 14 0e f7 a2 8a c8 ad 30 83 b3 48 1b >Apr 16 02:16:01 eal3 pluto[26516]: | 45 78 dc d5 0b 8d b7 43 a7 a6 68 ae 68 80 66 49 >Apr 16 02:16:01 eal3 pluto[26516]: | 2b 19 91 9f 15 1a 95 cf 0e 17 ae 83 8a 20 21 b4 >Apr 16 02:16:01 eal3 pluto[26516]: | 33 79 40 01 e6 77 2c 77 69 f7 da 44 03 2f 82 98 >Apr 16 02:16:01 eal3 pluto[26516]: | 2e df cc 7e d2 42 f9 65 d6 00 66 68 ae 34 7a 7a >Apr 16 02:16:01 eal3 pluto[26516]: | b1 2a 9d a8 12 f3 a9 f0 db 68 d5 f0 91 66 d1 d7 >Apr 16 02:16:01 eal3 pluto[26516]: | e7 37 13 da a0 4b 06 35 c3 25 bf 31 4b 4d 8c dc >Apr 16 02:16:01 eal3 pluto[26516]: | f8 88 95 96 59 33 d1 a3 09 41 27 38 16 64 bd 70 >Apr 16 02:16:01 eal3 pluto[26516]: | 0d 02 d5 c7 36 cb e3 73 98 05 af 00 08 5d f7 7e >Apr 16 02:16:01 eal3 pluto[26516]: | 46 08 d5 cb 76 97 f5 4d 8f 17 2d f1 cc b6 62 74 >Apr 16 02:16:01 eal3 pluto[26516]: | 2b 80 00 14 90 ff 77 d4 84 58 4e a9 02 0e 98 2b >Apr 16 02:16:01 eal3 pluto[26516]: | c7 31 9b f5 00 00 00 10 4f 45 5e 7f 4c 79 57 4b >Apr 16 02:16:01 eal3 pluto[26516]: | 43 45 54 65 >Apr 16 02:16:01 eal3 pluto[26516]: | * processed 1 messages from cryptographic helpers >Apr 16 02:16:01 eal3 pluto[26516]: | next event EVENT_PENDING_PHASE2 in 83 seconds >Apr 16 02:16:15 eal3 pluto[26516]: | >Apr 16 02:16:15 eal3 pluto[26516]: | *received 364 bytes from fc00::105:0:0:0:22:500 on eth0 (port=500) >Apr 16 02:16:15 eal3 pluto[26516]: | 5c 24 23 06 49 5c ec a0 89 c8 26 22 22 18 41 c2 >Apr 16 02:16:15 eal3 pluto[26516]: | 2e 20 23 08 00 00 00 01 00 00 01 6c 23 80 01 50 >Apr 16 02:16:15 eal3 pluto[26516]: | 6d 92 55 59 c0 73 e6 6b c6 85 19 31 cc b8 80 cb >Apr 16 02:16:15 eal3 pluto[26516]: | 7d b6 55 83 9c 4b 0a 6b c9 31 04 0e 48 32 32 25 >Apr 16 02:16:15 eal3 pluto[26516]: | 5d 9e dd 18 0b 19 d9 72 ec 92 84 4e 41 a4 9d 16 >Apr 16 02:16:15 eal3 pluto[26516]: | b6 a6 6c f2 94 89 38 74 3b 00 a1 87 5f ce 6e ba >Apr 16 02:16:15 eal3 pluto[26516]: | 41 7e a5 f3 0e 5e 0f ca c2 c4 f9 00 7e aa 5a e8 >Apr 16 02:16:15 eal3 pluto[26516]: | 24 66 6e 17 9e 3a c3 e1 0c 10 c0 df 64 7d 38 8a >Apr 16 02:16:15 eal3 pluto[26516]: | 8b 4a ff 95 cd 16 0e 1f d1 21 71 95 b2 eb b6 ce >Apr 16 02:16:15 eal3 pluto[26516]: | 4e 64 9a 4d 53 fa 66 3d 8e 2c bd 5a 6a 5b 57 df >Apr 16 02:16:15 eal3 pluto[26516]: | d8 81 2f d9 4b cc e3 e9 80 8e 23 ff 27 e8 b4 a7 >Apr 16 02:16:15 eal3 pluto[26516]: | cc 88 4b f7 8d 45 74 1f b5 51 68 66 62 14 96 ea >Apr 16 02:16:15 eal3 pluto[26516]: | 81 67 61 62 68 89 29 47 40 9d fb be 68 86 1b ed >Apr 16 02:16:15 eal3 pluto[26516]: | ab 42 95 db ca ec ef 17 47 c2 14 03 36 d9 65 af >Apr 16 02:16:15 eal3 pluto[26516]: | bc b9 7b 51 a8 2d 49 f5 42 2a 15 5b e6 3f 5c 61 >Apr 16 02:16:15 eal3 pluto[26516]: | 17 af b4 f9 21 ca 61 e0 3a e7 77 a3 58 64 61 c4 >Apr 16 02:16:15 eal3 pluto[26516]: | 15 27 d4 13 7e 5d 36 5e 6f 1d d7 e4 8d 8b 60 0c >Apr 16 02:16:15 eal3 pluto[26516]: | 91 ba 79 38 9a 97 27 f4 d0 81 b6 aa a7 77 81 78 >Apr 16 02:16:15 eal3 pluto[26516]: | 59 98 bb eb 76 28 f7 11 9e f3 09 c9 50 39 af e1 >Apr 16 02:16:15 eal3 pluto[26516]: | ea 2d 05 2e 12 0a 40 29 da 45 64 81 af 53 c4 52 >Apr 16 02:16:15 eal3 pluto[26516]: | bc 7f 57 15 bd 52 1b d5 71 54 39 64 b5 44 58 b3 >Apr 16 02:16:15 eal3 pluto[26516]: | fd 18 84 38 a7 bc 47 13 01 93 d8 bb ef e2 a4 6d >Apr 16 02:16:15 eal3 pluto[26516]: | a3 79 b7 2b c3 ab da 6e 24 95 64 a2 >Apr 16 02:16:15 eal3 pluto[26516]: | **parse ISAKMP Message: >Apr 16 02:16:15 eal3 pluto[26516]: | initiator cookie: >Apr 16 02:16:15 eal3 pluto[26516]: | 5c 24 23 06 49 5c ec a0 >Apr 16 02:16:15 eal3 pluto[26516]: | responder cookie: >Apr 16 02:16:15 eal3 pluto[26516]: | 89 c8 26 22 22 18 41 c2 >Apr 16 02:16:15 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2E >Apr 16 02:16:15 eal3 pluto[26516]: | ISAKMP version: IKEv2 version 2.0 (rfc4306) >Apr 16 02:16:15 eal3 pluto[26516]: | exchange type: ISAKMP_v2_AUTH >Apr 16 02:16:15 eal3 pluto[26516]: | flags: ISAKMP_FLAG_INIT >Apr 16 02:16:15 eal3 pluto[26516]: | message ID: 00 00 00 01 >Apr 16 02:16:15 eal3 pluto[26516]: | length: 364 >Apr 16 02:16:15 eal3 pluto[26516]: | processing version=2.0 packet with exchange type=ISAKMP_v2_AUTH (35) >Apr 16 02:16:15 eal3 pluto[26516]: | ICOOKIE: 5c 24 23 06 49 5c ec a0 >Apr 16 02:16:15 eal3 pluto[26516]: | RCOOKIE: 89 c8 26 22 22 18 41 c2 >Apr 16 02:16:16 eal3 pluto[26516]: | state hash entry 20 >Apr 16 02:16:16 eal3 pluto[26516]: | v2 state object not found >Apr 16 02:16:16 eal3 pluto[26516]: | ICOOKIE: 5c 24 23 06 49 5c ec a0 >Apr 16 02:16:16 eal3 pluto[26516]: | RCOOKIE: 00 00 00 00 00 00 00 00 >Apr 16 02:16:16 eal3 pluto[26516]: | state hash entry 2 >Apr 16 02:16:16 eal3 pluto[26516]: | v2 peer and cookies match on #1 >Apr 16 02:16:16 eal3 pluto[26516]: | v2 state object #1 found, in STATE_PARENT_R1 >Apr 16 02:16:16 eal3 pluto[26516]: | ***parse IKEv2 Encryption Payload: >Apr 16 02:16:16 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2IDi >Apr 16 02:16:16 eal3 pluto[26516]: | critical bit: Payload-Critical >Apr 16 02:16:16 eal3 pluto[26516]: | length: 336 >Apr 16 02:16:16 eal3 pluto[26516]: | processing payload: ISAKMP_NEXT_v2E (len=336) >Apr 16 02:16:16 eal3 pluto[26516]: | ikev2 parent inI2outR2: calculating g^{xy} in order to decrypt I2 >Apr 16 02:16:16 eal3 pluto[26516]: | calculating skeyseed using prf=prf-hmac-sha1 integ=auth-hmac-sha1-96 cipherkey=aes-cbc >Apr 16 02:16:16 eal3 pluto[26516]: | 2: w->pcw_dead: 0 w->pcw_work: 0 cnt: 3 >Apr 16 02:16:16 eal3 pluto[26516]: | asking helper 2 to do compute dh(v2) op on seq: 2 (len=2668, pcw_work=1) >Apr 16 02:16:16 eal3 pluto[26516]: | crypto helper write of request: cnt=2668<wlen=2668. >Apr 16 02:16:16 eal3 pluto[26530]: ! helper 2 read 2664+4/2668 bytesfd: 10 >Apr 16 02:16:16 eal3 pluto[26516]: | deleting event for #1 >Apr 16 02:16:16 eal3 pluto[26530]: ! helper 2 doing compute dh(v2) op id: 2 >Apr 16 02:16:16 eal3 pluto[26516]: | inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #1 >Apr 16 02:16:16 eal3 pluto[26530]: ! long term secret: 00 71 cf 31 69 11 4a 66 ec 6a bd 3d 66 36 1c 81 >Apr 16 02:16:16 eal3 pluto[26516]: | event added after event EVENT_PENDING_PHASE2 >Apr 16 02:16:16 eal3 pluto[26530]: ! long term secret: 17 e1 da 92 f2 59 1f 99 97 27 96 b0 d6 ef 3c da >Apr 16 02:16:16 eal3 pluto[26516]: | complete v2 state transition with STF_SUSPEND >Apr 16 02:16:16 eal3 pluto[26530]: ! calc_dh_shared(): time elapsed (OAKLEY_GROUP_MODP1536): 9506 usec >Apr 16 02:16:16 eal3 pluto[26516]: | * processed 0 messages from cryptographic helpers >Apr 16 02:16:16 eal3 pluto[26530]: ! DH shared-secret: >Apr 16 02:16:16 eal3 pluto[26516]: | next event EVENT_PENDING_PHASE2 in 68 seconds >Apr 16 02:16:16 eal3 pluto[26530]: ! 54 c1 a3 e5 97 52 c4 13 f9 8c 37 be 2a f5 4c c9 >Apr 16 02:16:16 eal3 pluto[26530]: ! bd 2b 04 b8 fb a9 4d 52 1d 5d 7a 90 5a ba 46 4b >Apr 16 02:16:16 eal3 pluto[26530]: ! 74 99 e4 f4 cf ab a5 a2 b0 2b a9 aa 63 bb e9 44 >Apr 16 02:16:16 eal3 pluto[26530]: ! c7 05 45 9c 50 79 9e 5c 6d a1 b2 20 91 fd da 25 >Apr 16 02:16:16 eal3 pluto[26530]: ! cf 9c cd d0 6c 9b fb b6 bd 1b 01 d6 39 bf 75 17 >Apr 16 02:16:16 eal3 pluto[26530]: ! 1d 14 ff 86 b7 2f 73 3a 14 c1 23 72 9c 80 27 f0 >Apr 16 02:16:16 eal3 pluto[26530]: ! 53 ee 46 a5 03 ba 41 78 c1 d3 e6 d4 8c 84 79 8e >Apr 16 02:16:16 eal3 pluto[26530]: ! a4 6f 27 ea 72 3b f0 b4 01 1c bb bb 89 0c cb 72 >Apr 16 02:16:16 eal3 pluto[26530]: ! 83 07 90 64 ab 20 9f 74 e8 f4 68 5c 4e 4b fb b9 >Apr 16 02:16:16 eal3 pluto[26530]: ! 72 b5 3b 06 e4 65 cd ba 5c ad 0f 75 f8 cb ac cd >Apr 16 02:16:16 eal3 pluto[26530]: ! 08 ba e7 6f ee 76 5c dd ca a0 83 ee 73 32 39 d0 >Apr 16 02:16:16 eal3 pluto[26530]: ! b1 a1 5c d8 ba 54 85 31 8c 3b 62 fa b7 af 3e 98 >Apr 16 02:16:16 eal3 pluto[26530]: ! calculating skeyseed using prf=prf-hmac-sha1 integ=auth-hmac-sha1-96 cipherkey=16 >Apr 16 02:16:16 eal3 pluto[26530]: ! Input to SKEYSEED: 42 2f 14 f6 2a 16 73 a4 80 7c fc e7 e8 63 bb d2 >Apr 16 02:16:16 eal3 pluto[26530]: ! Input to SKEYSEED: 90 ff 77 d4 84 58 4e a9 02 0e 98 2b c7 31 9b f5 >Apr 16 02:16:16 eal3 pluto[26530]: ! PRF+ input >Apr 16 02:16:16 eal3 pluto[26530]: ! Ni 42 2f 14 f6 2a 16 73 a4 80 7c fc e7 e8 63 bb d2 >Apr 16 02:16:16 eal3 pluto[26530]: ! Nr 90 ff 77 d4 84 58 4e a9 02 0e 98 2b c7 31 9b f5 >Apr 16 02:16:17 eal3 pluto[26530]: ! SPIi 5c 24 23 06 49 5c ec a0 >Apr 16 02:16:17 eal3 pluto[26530]: ! SPIr 89 c8 26 22 22 18 41 c2 >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[1]: ad cf 4c 83 c3 37 03 63 ec 65 24 a8 ba 26 ea d4 >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[1]: 19 0f e7 93 >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[2]: 9a 20 29 7c 6f be d1 b9 9a e9 0c bf 66 c7 77 47 >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[2]: cc 20 12 a5 >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[3]: 9f 96 18 e8 a9 42 a8 12 00 af 8c ec 4b f8 cd 18 >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[3]: 3e fe 07 33 >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[4]: ae dc 9e a9 06 10 a2 b1 e8 79 af 41 e9 43 ad 27 >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[4]: b2 b6 a6 8b >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[5]: 2e 09 0a 0d 3a 1b ad f0 d4 9f 20 6b fd 72 2b b2 >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[5]: 63 22 7a e5 >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[6]: 11 3b 9c cc cb 91 a6 e8 13 5b e8 d7 6e e2 3b b0 >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[6]: fd 7d 18 78 >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[7]: 55 72 f7 96 35 4f 7b d6 bc 5a fb 57 a4 03 4b ab >Apr 16 02:16:17 eal3 pluto[26530]: ! prf+[7]: 47 7c 7d a3 >Apr 16 02:16:17 eal3 pluto[26530]: ! shared: 54 c1 a3 e5 97 52 c4 13 f9 8c 37 be 2a f5 4c c9 >Apr 16 02:16:17 eal3 pluto[26530]: ! shared: bd 2b 04 b8 fb a9 4d 52 1d 5d 7a 90 5a ba 46 4b >Apr 16 02:16:17 eal3 pluto[26530]: ! shared: 74 99 e4 f4 cf ab a5 a2 b0 2b a9 aa 63 bb e9 44 >Apr 16 02:16:17 eal3 pluto[26530]: ! shared: c7 05 45 9c 50 79 9e 5c 6d a1 b2 20 91 fd da 25 >Apr 16 02:16:17 eal3 pluto[26530]: ! shared: cf 9c cd d0 6c 9b fb b6 bd 1b 01 d6 39 bf 75 17 >Apr 16 02:16:17 eal3 pluto[26530]: ! shared: 1d 14 ff 86 b7 2f 73 3a 14 c1 23 72 9c 80 27 f0 >Apr 16 02:16:17 eal3 pluto[26530]: ! shared: 53 ee 46 a5 03 ba 41 78 c1 d3 e6 d4 8c 84 79 8e >Apr 16 02:16:17 eal3 pluto[26530]: ! shared: a4 6f 27 ea 72 3b f0 b4 01 1c bb bb 89 0c cb 72 >Apr 16 02:16:17 eal3 pluto[26530]: ! shared: 83 07 90 64 ab 20 9f 74 e8 f4 68 5c 4e 4b fb b9 >Apr 16 02:16:17 eal3 pluto[26530]: ! shared: 72 b5 3b 06 e4 65 cd ba 5c ad 0f 75 f8 cb ac cd >Apr 16 02:16:17 eal3 pluto[26530]: ! shared: 08 ba e7 6f ee 76 5c dd ca a0 83 ee 73 32 39 d0 >Apr 16 02:16:17 eal3 pluto[26530]: ! shared: b1 a1 5c d8 ba 54 85 31 8c 3b 62 fa b7 af 3e 98 >Apr 16 02:16:17 eal3 pluto[26530]: ! skeyseed: e1 68 07 b8 bb 4a 3c fe 9f a8 7f 96 e7 2c b9 c8 >Apr 16 02:16:17 eal3 pluto[26530]: ! skeyseed: bf 46 60 6f >Apr 16 02:16:17 eal3 pluto[26530]: ! SK_d: ad cf 4c 83 c3 37 03 63 ec 65 24 a8 ba 26 ea d4 >Apr 16 02:16:17 eal3 pluto[26530]: ! SK_d: 19 0f e7 93 >Apr 16 02:16:17 eal3 pluto[26530]: ! SK_ai: 9a 20 29 7c 6f be d1 b9 9a e9 0c bf 66 c7 77 47 >Apr 16 02:16:17 eal3 pluto[26530]: ! SK_ai: cc 20 12 a5 >Apr 16 02:16:17 eal3 pluto[26530]: ! SK_ar: 9f 96 18 e8 a9 42 a8 12 00 af 8c ec 4b f8 cd 18 >Apr 16 02:16:17 eal3 pluto[26530]: ! SK_ar: 3e fe 07 33 >Apr 16 02:16:17 eal3 pluto[26530]: ! SK_ei: ae dc 9e a9 06 10 a2 b1 e8 79 af 41 e9 43 ad 27 >Apr 16 02:16:17 eal3 pluto[26530]: ! SK_er: b2 b6 a6 8b 2e 09 0a 0d 3a 1b ad f0 d4 9f 20 6b >Apr 16 02:16:17 eal3 pluto[26530]: ! SK_pi: fd 72 2b b2 63 22 7a e5 11 3b 9c cc cb 91 a6 e8 >Apr 16 02:16:17 eal3 pluto[26530]: ! SK_pi: 13 5b e8 d7 >Apr 16 02:16:17 eal3 pluto[26530]: ! SK_pr: 6e e2 3b b0 fd 7d 18 78 55 72 f7 96 35 4f 7b d6 >Apr 16 02:16:17 eal3 pluto[26530]: ! SK_pr: bc 5a fb 57 >Apr 16 02:16:17 eal3 pluto[26516]: | >Apr 16 02:16:17 eal3 pluto[26516]: | helper 2 has finished work (cnt now 1) >Apr 16 02:16:17 eal3 pluto[26516]: | helper 2 replies to id: q#2 >Apr 16 02:16:17 eal3 pluto[26516]: | calling callback function 0x4d71e0 >Apr 16 02:16:17 eal3 pluto[26516]: | ikev2 parent inI2outR2: calculating g^{xy}, sending R2 >Apr 16 02:16:18 eal3 pluto[26516]: | processing connection i386-i386-v6 >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 5c 24 23 06 49 5c ec a0 89 c8 26 22 22 18 41 c2 >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 2e 20 23 08 00 00 00 01 00 00 01 6c 23 80 01 50 >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 6d 92 55 59 c0 73 e6 6b c6 85 19 31 cc b8 80 cb >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 7d b6 55 83 9c 4b 0a 6b c9 31 04 0e 48 32 32 25 >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 5d 9e dd 18 0b 19 d9 72 ec 92 84 4e 41 a4 9d 16 >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: b6 a6 6c f2 94 89 38 74 3b 00 a1 87 5f ce 6e ba >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 41 7e a5 f3 0e 5e 0f ca c2 c4 f9 00 7e aa 5a e8 >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 24 66 6e 17 9e 3a c3 e1 0c 10 c0 df 64 7d 38 8a >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 8b 4a ff 95 cd 16 0e 1f d1 21 71 95 b2 eb b6 ce >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 4e 64 9a 4d 53 fa 66 3d 8e 2c bd 5a 6a 5b 57 df >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: d8 81 2f d9 4b cc e3 e9 80 8e 23 ff 27 e8 b4 a7 >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: cc 88 4b f7 8d 45 74 1f b5 51 68 66 62 14 96 ea >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 81 67 61 62 68 89 29 47 40 9d fb be 68 86 1b ed >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: ab 42 95 db ca ec ef 17 47 c2 14 03 36 d9 65 af >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: bc b9 7b 51 a8 2d 49 f5 42 2a 15 5b e6 3f 5c 61 >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 17 af b4 f9 21 ca 61 e0 3a e7 77 a3 58 64 61 c4 >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 15 27 d4 13 7e 5d 36 5e 6f 1d d7 e4 8d 8b 60 0c >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 91 ba 79 38 9a 97 27 f4 d0 81 b6 aa a7 77 81 78 >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: 59 98 bb eb 76 28 f7 11 9e f3 09 c9 50 39 af e1 >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: ea 2d 05 2e 12 0a 40 29 da 45 64 81 af 53 c4 52 >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: bc 7f 57 15 bd 52 1b d5 71 54 39 64 b5 44 58 b3 >Apr 16 02:16:18 eal3 pluto[26516]: | data being hmac: fd 18 84 38 a7 bc 47 13 01 93 d8 bb ef e2 a4 6d >Apr 16 02:16:18 eal3 pluto[26516]: | R2 calculated auth: a3 79 b7 2b c3 ab da 6e 24 95 64 a2 >Apr 16 02:16:18 eal3 pluto[26516]: | R2 provided auth: a3 79 b7 2b c3 ab da 6e 24 95 64 a2 >Apr 16 02:16:18 eal3 pluto[26516]: | authenticator matched >Apr 16 02:16:18 eal3 pluto[26516]: | data before decryption: >Apr 16 02:16:18 eal3 pluto[26516]: | 7d b6 55 83 9c 4b 0a 6b c9 31 04 0e 48 32 32 25 >Apr 16 02:16:18 eal3 pluto[26516]: | 5d 9e dd 18 0b 19 d9 72 ec 92 84 4e 41 a4 9d 16 >Apr 16 02:16:18 eal3 pluto[26516]: | b6 a6 6c f2 94 89 38 74 3b 00 a1 87 5f ce 6e ba >Apr 16 02:16:18 eal3 pluto[26516]: | 41 7e a5 f3 0e 5e 0f ca c2 c4 f9 00 7e aa 5a e8 >Apr 16 02:16:18 eal3 pluto[26516]: | 24 66 6e 17 9e 3a c3 e1 0c 10 c0 df 64 7d 38 8a >Apr 16 02:16:18 eal3 pluto[26516]: | 8b 4a ff 95 cd 16 0e 1f d1 21 71 95 b2 eb b6 ce >Apr 16 02:16:18 eal3 pluto[26516]: | 4e 64 9a 4d 53 fa 66 3d 8e 2c bd 5a 6a 5b 57 df >Apr 16 02:16:18 eal3 pluto[26516]: | d8 81 2f d9 4b cc e3 e9 80 8e 23 ff 27 e8 b4 a7 >Apr 16 02:16:18 eal3 pluto[26516]: | cc 88 4b f7 8d 45 74 1f b5 51 68 66 62 14 96 ea >Apr 16 02:16:18 eal3 pluto[26516]: | 81 67 61 62 68 89 29 47 40 9d fb be 68 86 1b ed >Apr 16 02:16:18 eal3 pluto[26516]: | ab 42 95 db ca ec ef 17 47 c2 14 03 36 d9 65 af >Apr 16 02:16:18 eal3 pluto[26516]: | bc b9 7b 51 a8 2d 49 f5 42 2a 15 5b e6 3f 5c 61 >Apr 16 02:16:18 eal3 pluto[26516]: | 17 af b4 f9 21 ca 61 e0 3a e7 77 a3 58 64 61 c4 >Apr 16 02:16:18 eal3 pluto[26516]: | 15 27 d4 13 7e 5d 36 5e 6f 1d d7 e4 8d 8b 60 0c >Apr 16 02:16:18 eal3 pluto[26516]: | 91 ba 79 38 9a 97 27 f4 d0 81 b6 aa a7 77 81 78 >Apr 16 02:16:18 eal3 pluto[26516]: | 59 98 bb eb 76 28 f7 11 9e f3 09 c9 50 39 af e1 >Apr 16 02:16:18 eal3 pluto[26516]: | ea 2d 05 2e 12 0a 40 29 da 45 64 81 af 53 c4 52 >Apr 16 02:16:18 eal3 pluto[26516]: | bc 7f 57 15 bd 52 1b d5 71 54 39 64 b5 44 58 b3 >Apr 16 02:16:18 eal3 pluto[26516]: | fd 18 84 38 a7 bc 47 13 01 93 d8 bb ef e2 a4 6d >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 27 00 00 18 05 00 00 00 fc 00 00 00 00 00 01 05 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 00 00 00 00 00 00 00 22 21 00 00 1c 02 00 00 00 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: be 26 7a d2 c5 75 58 49 0a 6d 4c 5c d8 07 d8 ff >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 5c 3c 27 26 2c 80 00 94 02 00 00 24 01 03 04 03 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 8c 62 3a f7 03 00 00 08 01 00 00 0c 03 00 00 08 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 03 00 00 02 00 00 00 08 05 00 00 00 02 00 00 24 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 02 03 04 03 8c 62 3a f7 03 00 00 08 01 00 00 0c >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 03 00 00 08 03 00 00 02 00 00 00 08 05 00 00 00 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 02 00 00 24 03 03 04 03 8c 62 3a f7 03 00 00 08 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 01 00 00 03 03 00 00 08 03 00 00 02 00 00 00 08 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 05 00 00 00 00 00 00 24 04 03 04 03 8c 62 3a f7 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 00 00 00 08 05 00 00 00 2d 00 00 30 01 00 00 00 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 08 00 00 28 00 00 ff ff fc 00 00 00 00 00 01 05 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 00 00 00 00 00 00 00 22 fc 00 00 00 00 00 01 05 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 00 00 00 00 00 00 00 22 00 00 00 30 01 00 00 00 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 08 00 00 28 00 00 ff ff fc 00 00 00 00 00 01 05 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 00 00 00 00 00 00 00 24 fc 00 00 00 00 00 01 05 >Apr 16 02:16:19 eal3 pluto[26516]: | decrypted payload: 00 00 00 00 00 00 00 24 00 01 02 03 04 05 06 07 >Apr 16 02:16:19 eal3 pluto[26516]: | striping 8 bytes as pad >Apr 16 02:16:19 eal3 pluto[26516]: | **parse IKEv2 Identification Payload: >Apr 16 02:16:19 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2AUTH >Apr 16 02:16:19 eal3 pluto[26516]: | length: 24 >Apr 16 02:16:19 eal3 pluto[26516]: | id_type: ID_IPV6_ADDR >Apr 16 02:16:19 eal3 pluto[26516]: | processing payload: ISAKMP_NEXT_v2IDi (len=24) >Apr 16 02:16:19 eal3 pluto[26516]: | **parse IKEv2 Authentication Payload: >Apr 16 02:16:19 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2SA >Apr 16 02:16:19 eal3 pluto[26516]: | length: 28 >Apr 16 02:16:19 eal3 pluto[26516]: | auth method: v2_AUTH_SHARED >Apr 16 02:16:19 eal3 pluto[26516]: | processing payload: ISAKMP_NEXT_v2AUTH (len=28) >Apr 16 02:16:19 eal3 pluto[26516]: | **parse IKEv2 Security Association Payload: >Apr 16 02:16:19 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2TSi >Apr 16 02:16:19 eal3 pluto[26516]: | critical bit: Payload-Critical >Apr 16 02:16:19 eal3 pluto[26516]: | length: 148 >Apr 16 02:16:19 eal3 pluto[26516]: | processing payload: ISAKMP_NEXT_v2SA (len=148) >Apr 16 02:16:19 eal3 pluto[26516]: | **parse IKEv2 Traffic Selectors: >Apr 16 02:16:19 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2TSr >Apr 16 02:16:19 eal3 pluto[26516]: | length: 48 >Apr 16 02:16:19 eal3 pluto[26516]: | number of TS: 1 >Apr 16 02:16:19 eal3 pluto[26516]: | processing payload: ISAKMP_NEXT_v2TSi (len=48) >Apr 16 02:16:19 eal3 pluto[26516]: | **parse IKEv2 Traffic Selectors: >Apr 16 02:16:19 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_NONE >Apr 16 02:16:19 eal3 pluto[26516]: | length: 48 >Apr 16 02:16:19 eal3 pluto[26516]: | number of TS: 1 >Apr 16 02:16:19 eal3 pluto[26516]: | processing payload: ISAKMP_NEXT_v2TSr (len=48) >Apr 16 02:16:19 eal3 pluto[26516]: | ICOOKIE: 5c 24 23 06 49 5c ec a0 >Apr 16 02:16:19 eal3 pluto[26516]: | RCOOKIE: 00 00 00 00 00 00 00 00 >Apr 16 02:16:19 eal3 pluto[26516]: | state hash entry 2 >Apr 16 02:16:19 eal3 pluto[26516]: | rehashing state object #1, removed from chain 2 >Apr 16 02:16:20 eal3 pluto[26516]: | ICOOKIE: 5c 24 23 06 49 5c ec a0 >Apr 16 02:16:20 eal3 pluto[26516]: | RCOOKIE: 89 c8 26 22 22 18 41 c2 >Apr 16 02:16:20 eal3 pluto[26516]: | state hash entry 20 >Apr 16 02:16:20 eal3 pluto[26516]: | inserting state object #1 on chain 20 >Apr 16 02:16:20 eal3 pluto[26516]: "i386-i386-v6" #1: IKEv2 mode peer ID is ID_IPV6_ADDR: 'fc00::105:0:0:0:22' >Apr 16 02:16:20 eal3 pluto[26516]: | idhash verify pi fd 72 2b b2 63 22 7a e5 11 3b 9c cc cb 91 a6 e8 >Apr 16 02:16:20 eal3 pluto[26516]: | idhash verify pi 13 5b e8 d7 >Apr 16 02:16:20 eal3 pluto[26516]: | idhash verify I2 05 00 00 00 fc 00 00 00 00 00 01 05 00 00 00 00 >Apr 16 02:16:20 eal3 pluto[26516]: | idhash verify I2 00 00 00 22 >Apr 16 02:16:20 eal3 pluto[26516]: | started looking for secret for fc00::105:0:0:0:24->fc00::105:0:0:0:22 of kind PPK_PSK >Apr 16 02:16:20 eal3 pluto[26516]: | actually looking for secret for fc00::105:0:0:0:24->fc00::105:0:0:0:22 of kind PPK_PSK >Apr 16 02:16:20 eal3 pluto[26516]: | line 1: key type PPK_PSK(fc00::105:0:0:0:24) to type PPK_PSK >Apr 16 02:16:20 eal3 pluto[26516]: | 1: compared key fc00::105:0:0:0:24 to fc00::105:0:0:0:24 / fc00::105:0:0:0:22 -> 4 >Apr 16 02:16:20 eal3 pluto[26516]: | 2: compared key fc00::105:0:0:0:23 to fc00::105:0:0:0:24 / fc00::105:0:0:0:22 -> 4 >Apr 16 02:16:20 eal3 pluto[26516]: | 3: compared key fc00::105:0:0:0:22 to fc00::105:0:0:0:24 / fc00::105:0:0:0:22 -> 6 >Apr 16 02:16:20 eal3 pluto[26516]: | line 1: match=6 >Apr 16 02:16:20 eal3 pluto[26516]: | best_match 0>6 best=0x995aef8 (line=1) >Apr 16 02:16:20 eal3 pluto[26516]: | concluding with best_match=6 best=0x995aef8 (lineno=1) >Apr 16 02:16:20 eal3 pluto[26516]: | negotiated prf: oakley_sha hash length: 20 >Apr 16 02:16:20 eal3 pluto[26516]: | inner prf ouput 24 bb 6a e6 51 6d 7b 9e 39 6b ee c5 c5 0b a3 46 >Apr 16 02:16:20 eal3 pluto[26516]: | inner prf ouput 00 bb ea 17 >Apr 16 02:16:20 eal3 pluto[26516]: | inputs to hash1 (first packet) >Apr 16 02:16:20 eal3 pluto[26516]: | 5c 24 23 06 49 5c ec a0 00 00 00 00 00 00 00 00 >Apr 16 02:16:20 eal3 pluto[26516]: | 21 20 22 08 00 00 00 00 00 00 02 9c 22 80 01 94 >Apr 16 02:16:20 eal3 pluto[26516]: | 02 00 00 28 01 01 00 04 03 00 00 08 01 00 00 0c >Apr 16 02:16:20 eal3 pluto[26516]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 16 02:16:20 eal3 pluto[26516]: | 00 00 00 08 04 00 00 05 02 00 00 28 02 01 00 04 >Apr 16 02:16:20 eal3 pluto[26516]: | 03 00 00 08 01 00 00 0c 03 00 00 08 03 00 00 02 >Apr 16 02:16:20 eal3 pluto[26516]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 05 >Apr 16 02:16:20 eal3 pluto[26516]: | 02 00 00 28 03 01 00 04 03 00 00 08 01 00 00 03 >Apr 16 02:16:20 eal3 pluto[26516]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 16 02:16:20 eal3 pluto[26516]: | 00 00 00 08 04 00 00 05 02 00 00 28 04 01 00 04 >Apr 16 02:16:20 eal3 pluto[26516]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Apr 16 02:16:20 eal3 pluto[26516]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 05 >Apr 16 02:16:20 eal3 pluto[26516]: | 02 00 00 28 05 01 00 04 03 00 00 08 01 00 00 03 >Apr 16 02:16:20 eal3 pluto[26516]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 16 02:16:20 eal3 pluto[26516]: | 00 00 00 08 04 00 00 02 02 00 00 28 06 01 00 04 >Apr 16 02:16:20 eal3 pluto[26516]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Apr 16 02:16:20 eal3 pluto[26516]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 02 >Apr 16 02:16:20 eal3 pluto[26516]: | 02 00 00 28 07 01 00 04 03 00 00 08 01 00 00 0c >Apr 16 02:16:20 eal3 pluto[26516]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 16 02:16:20 eal3 pluto[26516]: | 00 00 00 08 04 00 00 0e 02 00 00 28 08 01 00 04 >Apr 16 02:16:20 eal3 pluto[26516]: | 03 00 00 08 01 00 00 0c 03 00 00 08 03 00 00 02 >Apr 16 02:16:20 eal3 pluto[26516]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 0e >Apr 16 02:16:20 eal3 pluto[26516]: | 02 00 00 28 09 01 00 04 03 00 00 08 01 00 00 03 >Apr 16 02:16:20 eal3 pluto[26516]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 16 02:16:20 eal3 pluto[26516]: | 00 00 00 08 04 00 00 0e 00 00 00 28 0a 01 00 04 >Apr 16 02:16:21 eal3 pluto[26516]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Apr 16 02:16:21 eal3 pluto[26516]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 0e >Apr 16 02:16:21 eal3 pluto[26516]: | 28 00 00 c8 00 05 00 00 59 99 03 df ac 62 97 2b >Apr 16 02:16:21 eal3 pluto[26516]: | 1c 91 f4 c2 8b 3b 6f 14 d3 35 52 22 b8 0f 8e ab >Apr 16 02:16:21 eal3 pluto[26516]: | c2 b0 b3 b2 39 41 69 47 4c f5 c0 aa b3 b6 99 92 >Apr 16 02:16:21 eal3 pluto[26516]: | 6f 53 1a b5 0a 9a 9a 10 df 28 31 5d 26 ee 5c 19 >Apr 16 02:16:21 eal3 pluto[26516]: | e8 bd 97 f7 9a 22 5c 7a 43 30 2d 5c 3b d7 ea da >Apr 16 02:16:21 eal3 pluto[26516]: | 54 23 26 05 8a 34 a8 93 cf 81 26 f0 89 04 c3 ab >Apr 16 02:16:21 eal3 pluto[26516]: | ec 05 a7 9b 8d 0b 77 9f 78 ec 81 da c0 70 9d 1c >Apr 16 02:16:21 eal3 pluto[26516]: | 33 1d dc fe 2c 86 0c cd e6 5f 34 bf bd c6 76 9e >Apr 16 02:16:21 eal3 pluto[26516]: | 20 42 e2 30 c3 17 22 68 71 64 b1 c5 52 c4 1a 09 >Apr 16 02:16:21 eal3 pluto[26516]: | 2d 2a bd 6c a2 00 dd 68 f7 11 b8 60 8d ca 5d 7f >Apr 16 02:16:21 eal3 pluto[26516]: | b8 49 a4 07 22 29 c1 11 bf e4 2f e7 08 85 7c cd >Apr 16 02:16:21 eal3 pluto[26516]: | 3e 73 5d 09 94 61 a9 8d ff b8 10 0a 29 73 bb 3e >Apr 16 02:16:21 eal3 pluto[26516]: | 79 ed 14 b3 07 8b e9 4a 2b 80 00 14 42 2f 14 f6 >Apr 16 02:16:21 eal3 pluto[26516]: | 2a 16 73 a4 80 7c fc e7 e8 63 bb d2 00 00 00 10 >Apr 16 02:16:21 eal3 pluto[26516]: | 4f 45 5e 7f 4c 79 57 4b 43 45 54 65 >Apr 16 02:16:21 eal3 pluto[26516]: | inputs to hash2 (responder nonce) >Apr 16 02:16:21 eal3 pluto[26516]: | 90 ff 77 d4 84 58 4e a9 02 0e 98 2b c7 31 9b f5 >Apr 16 02:16:21 eal3 pluto[26516]: | idhash 2d 72 c3 af ed 5f 21 e2 1e 0e 6c f8 d4 b8 4b e3 >Apr 16 02:16:21 eal3 pluto[26516]: | idhash 81 0d 4a 32 >Apr 16 02:16:21 eal3 pluto[26516]: | Received PSK auth octets >Apr 16 02:16:21 eal3 pluto[26516]: | be 26 7a d2 c5 75 58 49 0a 6d 4c 5c d8 07 d8 ff >Apr 16 02:16:21 eal3 pluto[26516]: | 5c 3c 27 26 >Apr 16 02:16:21 eal3 pluto[26516]: | Calculated PSK auth octets >Apr 16 02:16:21 eal3 pluto[26516]: | be 26 7a d2 c5 75 58 49 0a 6d 4c 5c d8 07 d8 ff >Apr 16 02:16:21 eal3 pluto[26516]: | 5c 3c 27 26 >Apr 16 02:16:21 eal3 pluto[26516]: | **emit ISAKMP Message: >Apr 16 02:16:21 eal3 pluto[26516]: | initiator cookie: >Apr 16 02:16:21 eal3 pluto[26516]: | 5c 24 23 06 49 5c ec a0 >Apr 16 02:16:21 eal3 pluto[26516]: | responder cookie: >Apr 16 02:16:21 eal3 pluto[26516]: | 89 c8 26 22 22 18 41 c2 >Apr 16 02:16:21 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2E >Apr 16 02:16:21 eal3 pluto[26516]: | ISAKMP version: IKEv2 version 2.0 (rfc4306) >Apr 16 02:16:21 eal3 pluto[26516]: | exchange type: ISAKMP_v2_AUTH >Apr 16 02:16:21 eal3 pluto[26516]: | flags: ISAKMP_FLAG_RESPONSE >Apr 16 02:16:21 eal3 pluto[26516]: | message ID: 00 00 00 01 >Apr 16 02:16:21 eal3 pluto[26516]: | ***emit IKEv2 Encryption Payload: >Apr 16 02:16:21 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2IDr >Apr 16 02:16:21 eal3 pluto[26516]: | critical bit: Payload-Critical >Apr 16 02:16:21 eal3 pluto[26516]: | emitting 16 zero bytes of iv into IKEv2 Encryption Payload >Apr 16 02:16:21 eal3 pluto[26516]: | IKEv2 thinking whether to send my certificate: >Apr 16 02:16:21 eal3 pluto[26516]: | my policy has no RSASIG, the policy is : PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Apr 16 02:16:21 eal3 pluto[26516]: | sendcert: CERT_ALWAYSSEND and I did not get a certificate request >Apr 16 02:16:21 eal3 pluto[26516]: | so do not send cert. >Apr 16 02:16:21 eal3 pluto[26516]: | I did not send a certificate because digital signatures are not being used. (PSK) >Apr 16 02:16:22 eal3 pluto[26516]: | *****emit IKEv2 Identification Payload: >Apr 16 02:16:22 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2AUTH >Apr 16 02:16:22 eal3 pluto[26516]: | id_type: ID_IPV6_ADDR >Apr 16 02:16:22 eal3 pluto[26516]: | emitting 16 raw bytes of my identity into IKEv2 Identification Payload >Apr 16 02:16:22 eal3 pluto[26516]: | my identity fc 00 00 00 00 00 01 05 00 00 00 00 00 00 00 24 >Apr 16 02:16:22 eal3 pluto[26516]: | emitting length of IKEv2 Identification Payload: 24 >Apr 16 02:16:22 eal3 pluto[26516]: | idhash calc pr 6e e2 3b b0 fd 7d 18 78 55 72 f7 96 35 4f 7b d6 >Apr 16 02:16:22 eal3 pluto[26516]: | idhash calc pr bc 5a fb 57 >Apr 16 02:16:22 eal3 pluto[26516]: | idhash calc R2 05 00 00 00 fc 00 00 00 00 00 01 05 00 00 00 00 >Apr 16 02:16:22 eal3 pluto[26516]: | idhash calc R2 00 00 00 24 >Apr 16 02:16:22 eal3 pluto[26516]: | assembled IDr payload -- CERT next >Apr 16 02:16:22 eal3 pluto[26516]: | going to assemble AUTH payload >Apr 16 02:16:22 eal3 pluto[26516]: | *****emit IKEv2 Authentication Payload: >Apr 16 02:16:22 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2SA >Apr 16 02:16:22 eal3 pluto[26516]: | auth method: v2_AUTH_SHARED >Apr 16 02:16:22 eal3 pluto[26516]: | started looking for secret for fc00::105:0:0:0:24->fc00::105:0:0:0:22 of kind PPK_PSK >Apr 16 02:16:22 eal3 pluto[26516]: | actually looking for secret for fc00::105:0:0:0:24->fc00::105:0:0:0:22 of kind PPK_PSK >Apr 16 02:16:22 eal3 pluto[26516]: | line 1: key type PPK_PSK(fc00::105:0:0:0:24) to type PPK_PSK >Apr 16 02:16:22 eal3 pluto[26516]: | 1: compared key fc00::105:0:0:0:24 to fc00::105:0:0:0:24 / fc00::105:0:0:0:22 -> 4 >Apr 16 02:16:22 eal3 pluto[26516]: | 2: compared key fc00::105:0:0:0:23 to fc00::105:0:0:0:24 / fc00::105:0:0:0:22 -> 4 >Apr 16 02:16:22 eal3 pluto[26516]: | 3: compared key fc00::105:0:0:0:22 to fc00::105:0:0:0:24 / fc00::105:0:0:0:22 -> 6 >Apr 16 02:16:22 eal3 pluto[26516]: | line 1: match=6 >Apr 16 02:16:22 eal3 pluto[26516]: | best_match 0>6 best=0x995aef8 (line=1) >Apr 16 02:16:22 eal3 pluto[26516]: | concluding with best_match=6 best=0x995aef8 (lineno=1) >Apr 16 02:16:22 eal3 pluto[26516]: | negotiated prf: oakley_sha hash length: 20 >Apr 16 02:16:22 eal3 pluto[26516]: | inner prf ouput 24 bb 6a e6 51 6d 7b 9e 39 6b ee c5 c5 0b a3 46 >Apr 16 02:16:22 eal3 pluto[26516]: | inner prf ouput 00 bb ea 17 >Apr 16 02:16:22 eal3 pluto[26516]: | inputs to hash1 (first packet) >Apr 16 02:16:22 eal3 pluto[26516]: | 5c 24 23 06 49 5c ec a0 89 c8 26 22 22 18 41 c2 >Apr 16 02:16:22 eal3 pluto[26516]: | 21 20 22 20 00 00 00 00 00 00 01 34 22 80 00 2c >Apr 16 02:16:22 eal3 pluto[26516]: | 00 00 00 28 01 01 00 04 03 00 00 08 01 00 00 0c >Apr 16 02:16:22 eal3 pluto[26516]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 16 02:16:22 eal3 pluto[26516]: | 00 00 00 08 04 00 00 05 28 00 00 c8 00 05 00 00 >Apr 16 02:16:22 eal3 pluto[26516]: | 65 5d e0 e3 89 d8 e4 7f 46 72 b5 dc f2 60 8b d8 >Apr 16 02:16:22 eal3 pluto[26516]: | 45 34 61 b2 89 04 3a aa 2e 77 3a 11 40 67 da b2 >Apr 16 02:16:22 eal3 pluto[26516]: | d7 74 c0 1d 14 0e f7 a2 8a c8 ad 30 83 b3 48 1b >Apr 16 02:16:22 eal3 pluto[26516]: | 45 78 dc d5 0b 8d b7 43 a7 a6 68 ae 68 80 66 49 >Apr 16 02:16:22 eal3 pluto[26516]: | 2b 19 91 9f 15 1a 95 cf 0e 17 ae 83 8a 20 21 b4 >Apr 16 02:16:22 eal3 pluto[26516]: | 33 79 40 01 e6 77 2c 77 69 f7 da 44 03 2f 82 98 >Apr 16 02:16:22 eal3 pluto[26516]: | 2e df cc 7e d2 42 f9 65 d6 00 66 68 ae 34 7a 7a >Apr 16 02:16:22 eal3 pluto[26516]: | b1 2a 9d a8 12 f3 a9 f0 db 68 d5 f0 91 66 d1 d7 >Apr 16 02:16:22 eal3 pluto[26516]: | e7 37 13 da a0 4b 06 35 c3 25 bf 31 4b 4d 8c dc >Apr 16 02:16:22 eal3 pluto[26516]: | f8 88 95 96 59 33 d1 a3 09 41 27 38 16 64 bd 70 >Apr 16 02:16:22 eal3 pluto[26516]: | 0d 02 d5 c7 36 cb e3 73 98 05 af 00 08 5d f7 7e >Apr 16 02:16:22 eal3 pluto[26516]: | 46 08 d5 cb 76 97 f5 4d 8f 17 2d f1 cc b6 62 74 >Apr 16 02:16:22 eal3 pluto[26516]: | 2b 80 00 14 90 ff 77 d4 84 58 4e a9 02 0e 98 2b >Apr 16 02:16:22 eal3 pluto[26516]: | c7 31 9b f5 00 00 00 10 4f 45 5e 7f 4c 79 57 4b >Apr 16 02:16:22 eal3 pluto[26516]: | 43 45 54 65 >Apr 16 02:16:22 eal3 pluto[26516]: | inputs to hash2 (initiator nonce) >Apr 16 02:16:23 eal3 pluto[26516]: | 42 2f 14 f6 2a 16 73 a4 80 7c fc e7 e8 63 bb d2 >Apr 16 02:16:23 eal3 pluto[26516]: | idhash 02 c9 9b dc 6b 67 f4 e6 05 ad 5a fe ab fd 43 65 >Apr 16 02:16:23 eal3 pluto[26516]: | idhash 10 2b 0c 94 >Apr 16 02:16:23 eal3 pluto[26516]: | PSK auth octets 0c d6 8c 14 87 eb 83 8e 6c 0e 39 34 d7 95 13 36 >Apr 16 02:16:23 eal3 pluto[26516]: | PSK auth octets 47 18 a6 84 >Apr 16 02:16:23 eal3 pluto[26516]: | emitting 20 raw bytes of PSK auth into IKEv2 Authentication Payload >Apr 16 02:16:23 eal3 pluto[26516]: | PSK auth 0c d6 8c 14 87 eb 83 8e 6c 0e 39 34 d7 95 13 36 >Apr 16 02:16:23 eal3 pluto[26516]: | PSK auth 47 18 a6 84 >Apr 16 02:16:23 eal3 pluto[26516]: | emitting length of IKEv2 Authentication Payload: 28 >Apr 16 02:16:23 eal3 pluto[26516]: | duplicating state object #1 >Apr 16 02:16:23 eal3 pluto[26516]: | creating state object #2 at 0x995d108 >Apr 16 02:16:23 eal3 pluto[26516]: | ICOOKIE: 5c 24 23 06 49 5c ec a0 >Apr 16 02:16:23 eal3 pluto[26516]: | RCOOKIE: 89 c8 26 22 22 18 41 c2 >Apr 16 02:16:23 eal3 pluto[26516]: | state hash entry 20 >Apr 16 02:16:23 eal3 pluto[26516]: | inserting state object #2 on chain 20 >Apr 16 02:16:23 eal3 pluto[26516]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #2 >Apr 16 02:16:23 eal3 pluto[26516]: | event added at head of queue >Apr 16 02:16:23 eal3 pluto[26516]: | *****emit IKEv2 Security Association Payload: >Apr 16 02:16:23 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2TSi >Apr 16 02:16:23 eal3 pluto[26516]: | critical bit: Payload-Critical >Apr 16 02:16:23 eal3 pluto[26516]: | empty esp_info, returning defaults >Apr 16 02:16:23 eal3 pluto[26516]: | ***parse IKEv2 Proposal Substructure Payload: >Apr 16 02:16:23 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_P >Apr 16 02:16:23 eal3 pluto[26516]: | length: 36 >Apr 16 02:16:23 eal3 pluto[26516]: | prop #: 1 >Apr 16 02:16:23 eal3 pluto[26516]: | proto ID: 3 >Apr 16 02:16:23 eal3 pluto[26516]: | spi size: 4 >Apr 16 02:16:23 eal3 pluto[26516]: | # transforms: 3 >Apr 16 02:16:23 eal3 pluto[26516]: | parsing 4 raw bytes of IKEv2 Proposal Substructure Payload into CHILD SA SPI >Apr 16 02:16:23 eal3 pluto[26516]: | CHILD SA SPI 8c 62 3a f7 >Apr 16 02:16:23 eal3 pluto[26516]: | SPI received: 8c623af7 >Apr 16 02:16:23 eal3 pluto[26516]: | ****parse IKEv2 Transform Substructure Payload: >Apr 16 02:16:23 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_T >Apr 16 02:16:23 eal3 pluto[26516]: | length: 8 >Apr 16 02:16:23 eal3 pluto[26516]: | transform type: 1 >Apr 16 02:16:23 eal3 pluto[26516]: | transform ID: 12 >Apr 16 02:16:23 eal3 pluto[26516]: | ****parse IKEv2 Transform Substructure Payload: >Apr 16 02:16:23 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_T >Apr 16 02:16:23 eal3 pluto[26516]: | length: 8 >Apr 16 02:16:23 eal3 pluto[26516]: | transform type: 3 >Apr 16 02:16:23 eal3 pluto[26516]: | transform ID: 2 >Apr 16 02:16:23 eal3 pluto[26516]: | ****parse IKEv2 Transform Substructure Payload: >Apr 16 02:16:23 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_NONE >Apr 16 02:16:23 eal3 pluto[26516]: | length: 8 >Apr 16 02:16:23 eal3 pluto[26516]: | transform type: 5 >Apr 16 02:16:23 eal3 pluto[26516]: | transform ID: 0 >Apr 16 02:16:23 eal3 pluto[26516]: | ***parse IKEv2 Proposal Substructure Payload: >Apr 16 02:16:23 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_P >Apr 16 02:16:24 eal3 pluto[26516]: | length: 36 >Apr 16 02:16:24 eal3 pluto[26516]: | prop #: 2 >Apr 16 02:16:24 eal3 pluto[26516]: | proto ID: 3 >Apr 16 02:16:24 eal3 pluto[26516]: | spi size: 4 >Apr 16 02:16:24 eal3 pluto[26516]: | # transforms: 3 >Apr 16 02:16:24 eal3 pluto[26516]: | parsing 4 raw bytes of IKEv2 Proposal Substructure Payload into CHILD SA SPI >Apr 16 02:16:24 eal3 pluto[26516]: | CHILD SA SPI 8c 62 3a f7 >Apr 16 02:16:24 eal3 pluto[26516]: | SPI received: 8c623af7 >Apr 16 02:16:24 eal3 pluto[26516]: | netlink_get_spi: allocated 0x2c879d9c for esp:0@fc00::105:0:0:0:24 >Apr 16 02:16:24 eal3 pluto[26516]: | ******emit IKEv2 Proposal Substructure Payload: >Apr 16 02:16:24 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_NONE >Apr 16 02:16:24 eal3 pluto[26516]: | prop #: 1 >Apr 16 02:16:24 eal3 pluto[26516]: | proto ID: 3 >Apr 16 02:16:24 eal3 pluto[26516]: | spi size: 4 >Apr 16 02:16:24 eal3 pluto[26516]: | # transforms: 3 >Apr 16 02:16:24 eal3 pluto[26516]: | emitting 4 raw bytes of our spi into IKEv2 Proposal Substructure Payload >Apr 16 02:16:24 eal3 pluto[26516]: | our spi 2c 87 9d 9c >Apr 16 02:16:24 eal3 pluto[26516]: | *******emit IKEv2 Transform Substructure Payload: >Apr 16 02:16:24 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_T >Apr 16 02:16:24 eal3 pluto[26516]: | transform type: 1 >Apr 16 02:16:24 eal3 pluto[26516]: | transform ID: 12 >Apr 16 02:16:24 eal3 pluto[26516]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 16 02:16:24 eal3 pluto[26516]: | *******emit IKEv2 Transform Substructure Payload: >Apr 16 02:16:24 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_T >Apr 16 02:16:24 eal3 pluto[26516]: | transform type: 3 >Apr 16 02:16:24 eal3 pluto[26516]: | transform ID: 2 >Apr 16 02:16:24 eal3 pluto[26516]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 16 02:16:24 eal3 pluto[26516]: | *******emit IKEv2 Transform Substructure Payload: >Apr 16 02:16:24 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_NONE >Apr 16 02:16:24 eal3 pluto[26516]: | transform type: 5 >Apr 16 02:16:24 eal3 pluto[26516]: | transform ID: 0 >Apr 16 02:16:24 eal3 pluto[26516]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 16 02:16:24 eal3 pluto[26516]: | emitting length of IKEv2 Proposal Substructure Payload: 36 >Apr 16 02:16:24 eal3 pluto[26516]: | emitting length of IKEv2 Security Association Payload: 40 >Apr 16 02:16:24 eal3 pluto[26516]: | ***parse IKEv2 Traffic Selectors: >Apr 16 02:16:24 eal3 pluto[26516]: | TS type: ID_IPV6_ADDR_RANGE >Apr 16 02:16:24 eal3 pluto[26516]: | IP Protocol ID: 0 >Apr 16 02:16:24 eal3 pluto[26516]: | length: 40 >Apr 16 02:16:24 eal3 pluto[26516]: | start port: 0 >Apr 16 02:16:24 eal3 pluto[26516]: | end port: 65535 >Apr 16 02:16:24 eal3 pluto[26516]: | parsing 16 raw bytes of IKEv2 Traffic Selectors into ipv6 ts >Apr 16 02:16:24 eal3 pluto[26516]: | ipv6 ts fc 00 00 00 00 00 01 05 00 00 00 00 00 00 00 22 >Apr 16 02:16:24 eal3 pluto[26516]: | parsing 16 raw bytes of IKEv2 Traffic Selectors into ipv6 ts >Apr 16 02:16:24 eal3 pluto[26516]: | ipv6 ts fc 00 00 00 00 00 01 05 00 00 00 00 00 00 00 22 >Apr 16 02:16:24 eal3 pluto[26516]: | ***parse IKEv2 Traffic Selectors: >Apr 16 02:16:24 eal3 pluto[26516]: | TS type: ID_IPV6_ADDR_RANGE >Apr 16 02:16:25 eal3 pluto[26516]: | IP Protocol ID: 0 >Apr 16 02:16:25 eal3 pluto[26516]: | length: 40 >Apr 16 02:16:25 eal3 pluto[26516]: | start port: 0 >Apr 16 02:16:25 eal3 pluto[26516]: | end port: 65535 >Apr 16 02:16:25 eal3 pluto[26516]: | parsing 16 raw bytes of IKEv2 Traffic Selectors into ipv6 ts >Apr 16 02:16:25 eal3 pluto[26516]: | ipv6 ts fc 00 00 00 00 00 01 05 00 00 00 00 00 00 00 24 >Apr 16 02:16:25 eal3 pluto[26516]: | parsing 16 raw bytes of IKEv2 Traffic Selectors into ipv6 ts >Apr 16 02:16:25 eal3 pluto[26516]: | ipv6 ts fc 00 00 00 00 00 01 05 00 00 00 00 00 00 00 24 >Apr 16 02:16:25 eal3 pluto[26516]: | ikev2_eval_conn evaluating I=i386-i386-v6:fc00::105:0:0:0:22/128:0/0 R=fc00::105:0:0:0:24/128:0/0 >Apr 16 02:16:25 eal3 pluto[26516]: | tsi[0]=fc00::105:0:0:0:22/fc00::105:0:0:0:22 tsr[0]=fc00::105:0:0:0:24/fc00::105:0:0:0:24 >Apr 16 02:16:25 eal3 pluto[26516]: | has ts_range1=0 maskbits1=128 ts_range2=0 maskbits2=128 fitbits=32896 <> -1 >Apr 16 02:16:25 eal3 pluto[26516]: | find_host_pair: comparing to fc00::105:0:0:0:24:500 fc00::105:0:0:0:22:500 >Apr 16 02:16:25 eal3 pluto[26516]: | checking hostpair fc00::105:0:0:0:24/128 -> fc00::105:0:0:0:22/128 is not found >Apr 16 02:16:25 eal3 pluto[26516]: | *****emit IKEv2 Traffic Selectors: >Apr 16 02:16:25 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_v2TSr >Apr 16 02:16:25 eal3 pluto[26516]: | number of TS: 1 >Apr 16 02:16:25 eal3 pluto[26516]: | ******emit IKEv2 Traffic Selectors: >Apr 16 02:16:25 eal3 pluto[26516]: | TS type: ID_IPV6_ADDR_RANGE >Apr 16 02:16:25 eal3 pluto[26516]: | IP Protocol ID: 0 >Apr 16 02:16:25 eal3 pluto[26516]: | start port: 0 >Apr 16 02:16:25 eal3 pluto[26516]: | end port: 65535 >Apr 16 02:16:25 eal3 pluto[26516]: | emitting 16 raw bytes of ipv6 low into IKEv2 Traffic Selectors >Apr 16 02:16:25 eal3 pluto[26516]: | ipv6 low fc 00 00 00 00 00 01 05 00 00 00 00 00 00 00 22 >Apr 16 02:16:25 eal3 pluto[26516]: | emitting 16 raw bytes of ipv6 high into IKEv2 Traffic Selectors >Apr 16 02:16:25 eal3 pluto[26516]: | ipv6 high fc 00 00 00 00 00 01 05 00 00 00 00 00 00 00 22 >Apr 16 02:16:25 eal3 pluto[26516]: | emitting length of IKEv2 Traffic Selectors: 40 >Apr 16 02:16:25 eal3 pluto[26516]: | emitting length of IKEv2 Traffic Selectors: 48 >Apr 16 02:16:25 eal3 pluto[26516]: | *****emit IKEv2 Traffic Selectors: >Apr 16 02:16:25 eal3 pluto[26516]: | next payload type: ISAKMP_NEXT_NONE >Apr 16 02:16:25 eal3 pluto[26516]: | number of TS: 1 >Apr 16 02:16:25 eal3 pluto[26516]: | ******emit IKEv2 Traffic Selectors: >Apr 16 02:16:25 eal3 pluto[26516]: | TS type: ID_IPV6_ADDR_RANGE >Apr 16 02:16:25 eal3 pluto[26516]: | IP Protocol ID: 0 >Apr 16 02:16:25 eal3 pluto[26516]: | start port: 0 >Apr 16 02:16:25 eal3 pluto[26516]: | end port: 65535 >Apr 16 02:16:25 eal3 pluto[26516]: | emitting 16 raw bytes of ipv6 low into IKEv2 Traffic Selectors >Apr 16 02:16:25 eal3 pluto[26516]: | ipv6 low fc 00 00 00 00 00 01 05 00 00 00 00 00 00 00 24 >Apr 16 02:16:25 eal3 pluto[26516]: | emitting 16 raw bytes of ipv6 high into IKEv2 Traffic Selectors >Apr 16 02:16:25 eal3 pluto[26516]: | ipv6 high fc 00 00 00 00 00 01 05 00 00 00 00 00 00 00 24 >Apr 16 02:16:25 eal3 pluto[26516]: | emitting length of IKEv2 Traffic Selectors: 40 >Apr 16 02:16:25 eal3 pluto[26516]: | emitting length of IKEv2 Traffic Selectors: 48 >Apr 16 02:16:25 eal3 pluto[26516]: | kernel_alg_esp_info():transid=12, auth=2, ei=0x5921e8, enckeylen=16, authkeylen=20, encryptalg=12, authalg=3 >Apr 16 02:16:25 eal3 pluto[26516]: | prf+[1]: ba 99 64 57 c6 ae 5a 7b 53 b6 06 6e 99 ad 26 b6 >Apr 16 02:16:25 eal3 pluto[26516]: | prf+[1]: d5 8c c8 5b >Apr 16 02:16:25 eal3 pluto[26516]: | prf+[2]: 03 53 bb 76 b4 9e 56 48 8e 04 0b 69 c3 6e 70 04 >Apr 16 02:16:26 eal3 pluto[26516]: | prf+[2]: 5a 96 74 08 >Apr 16 02:16:26 eal3 pluto[26516]: | prf+[3]: 79 d6 81 5f 2e 15 c5 5b 14 d8 66 2a e9 45 47 bf >Apr 16 02:16:26 eal3 pluto[26516]: | prf+[3]: 92 cc 03 77 >Apr 16 02:16:26 eal3 pluto[26516]: | prf+[4]: db 24 25 f9 02 23 05 22 a9 77 cb 2a 71 3f 63 93 >Apr 16 02:16:26 eal3 pluto[26516]: | prf+[4]: 4d 65 9f 0c >Apr 16 02:16:26 eal3 pluto[26516]: | our keymat ba 99 64 57 c6 ae 5a 7b 53 b6 06 6e 99 ad 26 b6 >Apr 16 02:16:26 eal3 pluto[26516]: | our keymat d5 8c c8 5b 03 53 bb 76 b4 9e 56 48 8e 04 0b 69 >Apr 16 02:16:26 eal3 pluto[26516]: | our keymat c3 6e 70 04 >Apr 16 02:16:26 eal3 pluto[26516]: | peer keymat 5a 96 74 08 79 d6 81 5f 2e 15 c5 5b 14 d8 66 2a >Apr 16 02:16:26 eal3 pluto[26516]: | peer keymat e9 45 47 bf 92 cc 03 77 db 24 25 f9 02 23 05 22 >Apr 16 02:16:26 eal3 pluto[26516]: | peer keymat a9 77 cb 2a >Apr 16 02:16:26 eal3 pluto[26516]: | install_ipsec_sa() for #2: inbound and outbound >Apr 16 02:16:26 eal3 pluto[26516]: | route owner of "i386-i386-v6" unrouted: NULL; eroute owner: NULL >Apr 16 02:16:26 eal3 pluto[26516]: | could_route called for i386-i386-v6 (kind=CK_PERMANENT) >Apr 16 02:16:26 eal3 pluto[26516]: | looking for alg with transid: 12 keylen: 128 auth: 2 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 11 keylen: 0 auth: 1 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 11 keylen: 0 auth: 2 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 2 keylen: 8 auth: 0 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 2 keylen: 8 auth: 1 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 2 keylen: 8 auth: 2 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 3 keylen: 24 auth: 0 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 3 keylen: 24 auth: 1 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 3 keylen: 24 auth: 2 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 12 keylen: 16 auth: 0 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 12 keylen: 16 auth: 1 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 12 keylen: 16 auth: 2 >Apr 16 02:16:26 eal3 pluto[26516]: | set up outoing SA, ref=0/4294901761 >Apr 16 02:16:26 eal3 pluto[26516]: | looking for alg with transid: 12 keylen: 128 auth: 2 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 11 keylen: 0 auth: 1 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 11 keylen: 0 auth: 2 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 2 keylen: 8 auth: 0 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 2 keylen: 8 auth: 1 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 2 keylen: 8 auth: 2 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 3 keylen: 24 auth: 0 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 3 keylen: 24 auth: 1 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 3 keylen: 24 auth: 2 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 12 keylen: 16 auth: 0 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 12 keylen: 16 auth: 1 >Apr 16 02:16:26 eal3 pluto[26516]: | checking transid: 12 keylen: 16 auth: 2 >Apr 16 02:16:26 eal3 pluto[26516]: | add inbound eroute fc00::105:0:0:0:22/128:0 --0-> fc00::105:0:0:0:24/128:0 => tun:10000@fc00::105:0:0:0:24 (raw_eroute) >Apr 16 02:16:26 eal3 pluto[26516]: | raw_eroute result=1 >Apr 16 02:16:26 eal3 pluto[26516]: | set up incoming SA, ref=0/4294901761 >Apr 16 02:16:26 eal3 pluto[26516]: | sr for #2: unrouted >Apr 16 02:16:26 eal3 pluto[26516]: | route owner of "i386-i386-v6" unrouted: NULL; eroute owner: NULL >Apr 16 02:16:26 eal3 pluto[26516]: | route_and_eroute with c: i386-i386-v6 (next: none) ero:null esr:{(nil)} ro:null rosr:{(nil)} and state: 2 >Apr 16 02:16:26 eal3 pluto[26516]: | eroute_connection add eroute fc00::105:0:0:0:24/128:0 --0-> fc00::105:0:0:0:22/128:0 => tun:0@fc00::105:0:0:0:22 (raw_eroute) >Apr 16 02:16:26 eal3 pluto[26516]: | raw_eroute result=1 >Apr 16 02:16:27 eal3 pluto[26516]: | command executing up-host-v6 >Apr 16 02:16:27 eal3 pluto[26516]: | executing up-host-v6: 2>&1 PLUTO_VERSION='2.0' PLUTO_VERB='up-host-v6' PLUTO_CONNECTION='i386-i386-v6' PLUTO_NEXT_HOP='fc00::105:0:0:0:22' PLUTO_INTERFACE='eth0' PLUTO_ME='fc00::105:0:0:0:24' PLUTO_MY_ID='fc00::105:0:0:0:24' PLUTO_MY_CLIENT='fc00::105:0:0:0:24/128' PLUTO_MY_CLIENT_NET='fc00::105:0:0:0:24' PLUTO_MY_CLIENT_MASK='ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff' PLUTO_MY_PORT='0' PLUTO_MY_PROTOCOL='0' PLUTO_PEER='fc00::105:0:0:0:22' PLUTO_PEER_ID='fc00::105:0:0:0:22' PLUTO_PEER_CLIENT='fc00::105:0:0:0:22/128' PLUTO_PEER_CLIENT_NET='fc00::105:0:0:0:22' PLUTO_PEER_CLIENT_MASK='ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff' PLUTO_PEER_PORT='0' PLUTO_PEER_PROTOCOL='0' PLUTO_PEER_CA='' PLUTO_STACK='netkey' PLUTO_CONN_POLICY='PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init' PLUTO_XAUTH_USERNAME='' ipsec _updown >Apr 16 02:16:27 eal3 pluto[26516]: | route_and_eroute: firewall_notified: true >Apr 16 02:16:27 eal3 pluto[26516]: | command executing prepare-host-v6 >Apr 16 02:16:27 eal3 pluto[26516]: | executing prepare-host-v6: 2>&1 PLUTO_VERSION='2.0' PLUTO_VERB='prepare-host-v6' PLUTO_CONNECTION='i386-i386-v6' PLUTO_NEXT_HOP='fc00::105:0:0:0:22' PLUTO_INTERFACE='eth0' PLUTO_ME='fc00::105:0:0:0:24' PLUTO_MY_ID='fc00::105:0:0:0:24' PLUTO_MY_CLIENT='fc00::105:0:0:0:24/128' PLUTO_MY_CLIENT_NET='fc00::105:0:0:0:24' PLUTO_MY_CLIENT_MASK='ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff' PLUTO_MY_PORT='0' PLUTO_MY_PROTOCOL='0' PLUTO_PEER='fc00::105:0:0:0:22' PLUTO_PEER_ID='fc00::105:0:0:0:22' PLUTO_PEER_CLIENT='fc00::105:0:0:0:22/128' PLUTO_PEER_CLIENT_NET='fc00::105:0:0:0:22' PLUTO_PEER_CLIENT_MASK='ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff' PLUTO_PEER_PORT='0' PLUTO_PEER_PROTOCOL='0' PLUTO_PEER_CA='' PLUTO_STACK='netkey' PLUTO_CONN_POLICY='PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init' PLUTO_XAUTH_USERNAME='' ipsec _updown >Apr 16 02:16:27 eal3 pluto[26516]: | command executing route-host-v6 >Apr 16 02:16:27 eal3 pluto[26516]: | executing route-host-v6: 2>&1 PLUTO_VERSION='2.0' PLUTO_VERB='route-host-v6' PLUTO_CONNECTION='i386-i386-v6' PLUTO_NEXT_HOP='fc00::105:0:0:0:22' PLUTO_INTERFACE='eth0' PLUTO_ME='fc00::105:0:0:0:24' PLUTO_MY_ID='fc00::105:0:0:0:24' PLUTO_MY_CLIENT='fc00::105:0:0:0:24/128' PLUTO_MY_CLIENT_NET='fc00::105:0:0:0:24' PLUTO_MY_CLIENT_MASK='ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff' PLUTO_MY_PORT='0' PLUTO_MY_PROTOCOL='0' PLUTO_PEER='fc00::105:0:0:0:22' PLUTO_PEER_ID='fc00::105:0:0:0:22' PLUTO_PEER_CLIENT='fc00::105:0:0:0:22/128' PLUTO_PEER_CLIENT_NET='fc00::105:0:0:0:22' PLUTO_PEER_CLIENT_MASK='ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff' PLUTO_PEER_PORT='0' PLUTO_PEER_PROTOCOL='0' PLUTO_PEER_CA='' PLUTO_STACK='netkey' PLUTO_CONN_POLICY='PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init' PLUTO_XAUTH_USERNAME='' ipsec _updown >Apr 16 02:16:27 eal3 pluto[26516]: | route_and_eroute: instance "i386-i386-v6", setting eroute_owner {spd=0x9959f58,sr=0x9959f58} to #2 (was #0) (newest_ipsec_sa=#0) >Apr 16 02:16:27 eal3 pluto[26516]: | emitting 4 raw bytes of padding and length into cleartext >Apr 16 02:16:27 eal3 pluto[26516]: | padding and length 00 01 02 03 >Apr 16 02:16:27 eal3 pluto[26516]: | emitting 12 zero bytes of 96-bits of truncated HMAC into IKEv2 Encryption Payload >Apr 16 02:16:27 eal3 pluto[26516]: | emitting length of IKEv2 Encryption Payload: 224 >Apr 16 02:16:27 eal3 pluto[26516]: | emitting length of ISAKMP Message: 252 >Apr 16 02:16:27 eal3 pluto[26516]: | data before encryption: >Apr 16 02:16:27 eal3 pluto[26516]: | 27 00 00 18 05 00 00 00 fc 00 00 00 00 00 01 05 >Apr 16 02:16:27 eal3 pluto[26516]: | 00 00 00 00 00 00 00 24 21 00 00 1c 02 00 00 00 >Apr 16 02:16:27 eal3 pluto[26516]: | 0c d6 8c 14 87 eb 83 8e 6c 0e 39 34 d7 95 13 36 >Apr 16 02:16:27 eal3 pluto[26516]: | 47 18 a6 84 2c 80 00 28 00 00 00 24 01 03 04 03 >Apr 16 02:16:27 eal3 pluto[26516]: | 2c 87 9d 9c 03 00 00 08 01 00 00 0c 03 00 00 08 >Apr 16 02:16:27 eal3 pluto[26516]: | 03 00 00 02 00 00 00 08 05 00 00 00 2d 00 00 30 >Apr 16 02:16:27 eal3 pluto[26516]: | 01 00 00 00 08 00 00 28 00 00 ff ff fc 00 00 00 >Apr 16 02:16:27 eal3 pluto[26516]: | 00 00 01 05 00 00 00 00 00 00 00 22 fc 00 00 00 >Apr 16 02:16:27 eal3 pluto[26516]: | 00 00 01 05 00 00 00 00 00 00 00 22 00 00 00 30 >Apr 16 02:16:27 eal3 pluto[26516]: | 01 00 00 00 08 00 00 28 00 00 ff ff fc 00 00 00 >Apr 16 02:16:27 eal3 pluto[26516]: | 00 00 01 05 00 00 00 00 00 00 00 24 fc 00 00 00 >Apr 16 02:16:27 eal3 pluto[26516]: | 00 00 01 05 00 00 00 00 00 00 00 24 00 01 02 03 >Apr 16 02:16:27 eal3 pluto[26516]: | data after encryption: >Apr 16 02:16:27 eal3 pluto[26516]: | 09 a0 2f c7 ad e8 dc b5 2c 54 6a b2 3f 7e ae 2e >Apr 16 02:16:27 eal3 pluto[26516]: | f8 55 3f 6b 75 17 ae d9 70 5e 4c 64 c1 7e 34 e5 >Apr 16 02:16:27 eal3 pluto[26516]: | 73 32 cc 31 50 c6 b0 a0 a4 7a 8e 14 36 b1 d7 40 >Apr 16 02:16:27 eal3 pluto[26516]: | d7 42 81 8d ba 7e 6c c6 e7 92 c3 37 de 94 34 3e >Apr 16 02:16:27 eal3 pluto[26516]: | 1d 58 5d 8b a7 20 58 40 6d 75 78 43 98 71 8b 25 >Apr 16 02:16:27 eal3 pluto[26516]: | 0e 85 c8 77 b5 39 6a 56 d4 35 7c d0 2b 84 7d 18 >Apr 16 02:16:27 eal3 pluto[26516]: | f5 17 cc 73 76 d2 1a f3 b1 b2 a6 85 52 0e 6e 7e >Apr 16 02:16:27 eal3 pluto[26516]: | 55 bb f8 ec 1d f6 50 74 9d 25 13 01 d1 aa fb d4 >Apr 16 02:16:27 eal3 pluto[26516]: | 2c 04 5a 8a dc da e6 9b 33 cc a2 2d 38 c3 c4 2a >Apr 16 02:16:27 eal3 pluto[26516]: | c4 e6 fd 64 fd a4 36 50 08 0c 12 38 e7 c3 04 dc >Apr 16 02:16:27 eal3 pluto[26516]: | 9c b6 0e 1d 84 73 3c c8 31 7b 76 f9 91 07 dd 3e >Apr 16 02:16:27 eal3 pluto[26516]: | e7 0c 6b 25 ac 33 b8 74 ad c9 06 b8 05 7e c0 24 >Apr 16 02:16:27 eal3 pluto[26516]: | data being hmac: 5c 24 23 06 49 5c ec a0 89 c8 26 22 22 18 41 c2 >Apr 16 02:16:27 eal3 pluto[26516]: | data being hmac: 2e 20 23 20 00 00 00 01 00 00 00 fc 24 80 00 e0 >Apr 16 02:16:27 eal3 pluto[26516]: | data being hmac: f1 1f 96 2c cd 39 c9 9d 78 6c 5d 35 ad 4e c7 1b >Apr 16 02:16:27 eal3 pluto[26516]: | data being hmac: 09 a0 2f c7 ad e8 dc b5 2c 54 6a b2 3f 7e ae 2e >Apr 16 02:16:27 eal3 pluto[26516]: | data being hmac: f8 55 3f 6b 75 17 ae d9 70 5e 4c 64 c1 7e 34 e5 >Apr 16 02:16:27 eal3 pluto[26516]: | data being hmac: 73 32 cc 31 50 c6 b0 a0 a4 7a 8e 14 36 b1 d7 40 >Apr 16 02:16:27 eal3 pluto[26516]: | data being hmac: d7 42 81 8d ba 7e 6c c6 e7 92 c3 37 de 94 34 3e >Apr 16 02:16:27 eal3 pluto[26516]: | data being hmac: 1d 58 5d 8b a7 20 58 40 6d 75 78 43 98 71 8b 25 >Apr 16 02:16:27 eal3 pluto[26516]: | data being hmac: 0e 85 c8 77 b5 39 6a 56 d4 35 7c d0 2b 84 7d 18 >Apr 16 02:16:28 eal3 pluto[26516]: | data being hmac: f5 17 cc 73 76 d2 1a f3 b1 b2 a6 85 52 0e 6e 7e >Apr 16 02:16:28 eal3 pluto[26516]: | data being hmac: 55 bb f8 ec 1d f6 50 74 9d 25 13 01 d1 aa fb d4 >Apr 16 02:16:28 eal3 pluto[26516]: | data being hmac: 2c 04 5a 8a dc da e6 9b 33 cc a2 2d 38 c3 c4 2a >Apr 16 02:16:28 eal3 pluto[26516]: | data being hmac: c4 e6 fd 64 fd a4 36 50 08 0c 12 38 e7 c3 04 dc >Apr 16 02:16:28 eal3 pluto[26516]: | data being hmac: 9c b6 0e 1d 84 73 3c c8 31 7b 76 f9 91 07 dd 3e >Apr 16 02:16:28 eal3 pluto[26516]: | data being hmac: e7 0c 6b 25 ac 33 b8 74 ad c9 06 b8 05 7e c0 24 >Apr 16 02:16:28 eal3 pluto[26516]: | out calculated auth: >Apr 16 02:16:28 eal3 pluto[26516]: | a6 d7 43 cd 1f f9 b3 8a 81 e1 d5 8b >Apr 16 02:16:28 eal3 pluto[26516]: | complete v2 state transition with STF_OK >Apr 16 02:16:28 eal3 pluto[26516]: "i386-i386-v6" #2: transition from state STATE_PARENT_R1 to state STATE_PARENT_R2 >Apr 16 02:16:28 eal3 pluto[26516]: "i386-i386-v6" #2: negotiated tunnel [fc00::105:0:0:0:24,fc00::105:0:0:0:24] -> [fc00::105:0:0:0:22,fc00::105:0:0:0:22] >Apr 16 02:16:28 eal3 pluto[26516]: "i386-i386-v6" #2: STATE_PARENT_R2: received v2I2, PARENT SA established tunnel mode {ESP=>0x8c623af7 <0x2c879d9c xfrm=AES_128-HMAC_SHA1 NATOA=none NATD=none DPD=none} >Apr 16 02:16:28 eal3 pluto[26516]: | sending reply packet to fc00::105:0:0:0:22:500 (from port 500) >Apr 16 02:16:28 eal3 pluto[26516]: | sending 252 bytes for STATE_PARENT_R1 through eth0:500 to fc00::105:0:0:0:22:500 (using #2) >Apr 16 02:16:28 eal3 pluto[26516]: | 5c 24 23 06 49 5c ec a0 89 c8 26 22 22 18 41 c2 >Apr 16 02:16:28 eal3 pluto[26516]: | 2e 20 23 20 00 00 00 01 00 00 00 fc 24 80 00 e0 >Apr 16 02:16:28 eal3 pluto[26516]: | f1 1f 96 2c cd 39 c9 9d 78 6c 5d 35 ad 4e c7 1b >Apr 16 02:16:28 eal3 pluto[26516]: | 09 a0 2f c7 ad e8 dc b5 2c 54 6a b2 3f 7e ae 2e >Apr 16 02:16:28 eal3 pluto[26516]: | f8 55 3f 6b 75 17 ae d9 70 5e 4c 64 c1 7e 34 e5 >Apr 16 02:16:28 eal3 pluto[26516]: | 73 32 cc 31 50 c6 b0 a0 a4 7a 8e 14 36 b1 d7 40 >Apr 16 02:16:28 eal3 pluto[26516]: | d7 42 81 8d ba 7e 6c c6 e7 92 c3 37 de 94 34 3e >Apr 16 02:16:28 eal3 pluto[26516]: | 1d 58 5d 8b a7 20 58 40 6d 75 78 43 98 71 8b 25 >Apr 16 02:16:28 eal3 pluto[26516]: | 0e 85 c8 77 b5 39 6a 56 d4 35 7c d0 2b 84 7d 18 >Apr 16 02:16:28 eal3 pluto[26516]: | f5 17 cc 73 76 d2 1a f3 b1 b2 a6 85 52 0e 6e 7e >Apr 16 02:16:28 eal3 pluto[26516]: | 55 bb f8 ec 1d f6 50 74 9d 25 13 01 d1 aa fb d4 >Apr 16 02:16:28 eal3 pluto[26516]: | 2c 04 5a 8a dc da e6 9b 33 cc a2 2d 38 c3 c4 2a >Apr 16 02:16:28 eal3 pluto[26516]: | c4 e6 fd 64 fd a4 36 50 08 0c 12 38 e7 c3 04 dc >Apr 16 02:16:28 eal3 pluto[26516]: | 9c b6 0e 1d 84 73 3c c8 31 7b 76 f9 91 07 dd 3e >Apr 16 02:16:28 eal3 pluto[26516]: | e7 0c 6b 25 ac 33 b8 74 ad c9 06 b8 05 7e c0 24 >Apr 16 02:16:28 eal3 pluto[26516]: | a6 d7 43 cd 1f f9 b3 8a 81 e1 d5 8b >Apr 16 02:16:28 eal3 pluto[26516]: | releasing whack for #2 (sock=-1) >Apr 16 02:16:28 eal3 pluto[26516]: | releasing whack for #1 (sock=-1) >Apr 16 02:16:28 eal3 pluto[26516]: | deleting event for #2 >Apr 16 02:16:28 eal3 pluto[26516]: | inserting event EVENT_SA_REPLACE, timeout in 28530 seconds for #2 >Apr 16 02:16:28 eal3 pluto[26516]: | event added after event EVENT_REINIT_SECRET >Apr 16 02:16:28 eal3 pluto[26516]: | * processed 1 messages from cryptographic helpers >Apr 16 02:16:28 eal3 pluto[26516]: | next event EVENT_PENDING_PHASE2 in 56 seconds >Apr 16 02:17:24 eal3 pluto[26516]: | >Apr 16 02:17:24 eal3 pluto[26516]: | *time to handle event >Apr 16 02:17:24 eal3 pluto[26516]: | handling event EVENT_PENDING_PHASE2 >Apr 16 02:17:24 eal3 pluto[26516]: | event after this is EVENT_CRYPTO_FAILED in 232 seconds >Apr 16 02:17:24 eal3 pluto[26516]: | inserting event EVENT_PENDING_PHASE2, timeout in 120 seconds >Apr 16 02:17:24 eal3 pluto[26516]: | event added at head of queue >Apr 16 02:17:24 eal3 pluto[26516]: | pending review: connection "i386-i386-v6" was not up, skipped >Apr 16 02:17:24 eal3 pluto[26516]: | next event EVENT_PENDING_PHASE2 in 120 seconds
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=302685">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 442821
: 302685 |
302686