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 299704 Details for
Bug 439771
[IPv6-DoD] openswan and strongswan fail to interoperate with IKEv2
[?]
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.
Openswan initiator logs
open_init-strong_resp.openswan.log (text/plain), 106.29 KB, created by
IBM Bug Proxy
on 2008-03-31 11:18:01 UTC
(
hide
)
Description:
Openswan initiator logs
Filename:
MIME Type:
Creator:
IBM Bug Proxy
Created:
2008-03-31 11:18:01 UTC
Size:
106.29 KB
patch
obsolete
> >Mar 28 17:14:03 eal5 ipsec__plutorun: Starting Pluto subsystem... >Mar 28 17:14:03 eal5 pluto[24248]: Starting Pluto (Openswan Version 2.6.09; Vendor ID OEZD~MT}mAnl) pid:24248 >Mar 28 17:14:03 eal5 pluto[24248]: Setting NAT-Traversal port-4500 floating to on >Mar 28 17:14:03 eal5 pluto[24248]: port floating activation criteria nat_t=1/port_float=1 >Mar 28 17:14:03 eal5 pluto[24248]: including NAT-Traversal patch (Version 0.6c) >Mar 28 17:14:03 eal5 pluto[24248]: | opening /dev/urandom >Mar 28 17:14:03 eal5 pluto[24248]: using /dev/urandom as source of random entropy >Mar 28 17:14:03 eal5 pluto[24248]: | inserting event EVENT_REINIT_SECRET, timeout in 3600 seconds >Mar 28 17:14:03 eal5 pluto[24248]: | inserting event EVENT_PENDING_PHASE2, timeout in 120 seconds >Mar 28 17:14:03 eal5 pluto[24248]: ike_alg_register_enc(): Activating OAKLEY_TWOFISH_CBC_SSH: Ok (ret=0) >Mar 28 17:14:03 eal5 pluto[24248]: ike_alg_register_enc(): Activating OAKLEY_TWOFISH_CBC: Ok (ret=0) >Mar 28 17:14:03 eal5 pluto[24248]: ike_alg_register_enc(): Activating OAKLEY_SERPENT_CBC: Ok (ret=0) >Mar 28 17:14:03 eal5 pluto[24248]: ike_alg_register_enc(): Activating OAKLEY_AES_CBC: Ok (ret=0) >Mar 28 17:14:03 eal5 pluto[24248]: ike_alg_register_enc(): Activating OAKLEY_BLOWFISH_CBC: Ok (ret=0) >Mar 28 17:14:03 eal5 pluto[24248]: ike_alg_register_hash(): Activating OAKLEY_SHA2_512: Ok (ret=0) >Mar 28 17:14:03 eal5 pluto[24248]: ike_alg_register_hash(): Activating OAKLEY_SHA2_256: Ok (ret=0) >Mar 28 17:14:03 eal5 pluto[24248]: starting up 3 cryptographic helpers >Mar 28 17:14:03 eal5 pluto[24248]: started helper pid=24259 (fd:7) >Mar 28 17:14:03 eal5 pluto[24259]: | opening /dev/urandom >Mar 28 17:14:03 eal5 pluto[24261]: | opening /dev/urandom >Mar 28 17:14:03 eal5 pluto[24248]: started helper pid=24261 (fd:8) >Mar 28 17:14:03 eal5 pluto[24259]: using /dev/urandom as source of random entropy >Mar 28 17:14:03 eal5 pluto[24261]: using /dev/urandom as source of random entropy >Mar 28 17:14:03 eal5 pluto[24248]: started helper pid=24262 (fd:9) >Mar 28 17:14:03 eal5 pluto[24262]: | opening /dev/urandom >Mar 28 17:14:03 eal5 pluto[24259]: ! helper 0 waiting on fd: 8 >Mar 28 17:14:03 eal5 pluto[24261]: ! helper 1 waiting on fd: 9 >Mar 28 17:14:03 eal5 pluto[24248]: Using Linux 2.6 IPsec interface code on 2.6.18-86.el5 (experimental code) >Mar 28 17:14:03 eal5 pluto[24262]: using /dev/urandom as source of random entropy >Mar 28 17:14:03 eal5 pluto[24248]: | process 24248 listening for PF_KEY_V2 on file descriptor 12 >Mar 28 17:14:03 eal5 pluto[24262]: ! helper 2 waiting on fd: 10 >Mar 28 17:14:03 eal5 pluto[24248]: | finish_pfkey_msg: SADB_REGISTER message 1 for AH >Mar 28 17:14:03 eal5 pluto[24248]: | 02 07 00 02 02 00 00 00 01 00 00 00 b8 5e 00 00 >Mar 28 17:14:03 eal5 pluto[24248]: | pfkey_get: SADB_REGISTER message 1 >Mar 28 17:14:03 eal5 pluto[24248]: | AH registered with kernel. >Mar 28 17:14:03 eal5 pluto[24248]: | finish_pfkey_msg: SADB_REGISTER message 2 for ESP >Mar 28 17:14:04 eal5 pluto[24248]: | 02 07 00 03 02 00 00 00 02 00 00 00 b8 5e 00 00 >Mar 28 17:14:04 eal5 pluto[24248]: | pfkey_get: SADB_REGISTER message 2 >Mar 28 17:14:04 eal5 pluto[24248]: | alg_init():memset(0xc80ec0, 0, 2016) memset(0xc816a0, 0, 2048) >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: sadb_msg_len=19 sadb_supported_len=56 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=14, alg_id=251 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=14, alg_id=2 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=14, alg_id=3 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=14, alg_id=5 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=14, alg_id=8 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=14, alg_id=9 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: sadb_msg_len=19 sadb_supported_len=80 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=15, alg_id=11 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=15, alg_id=2 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=15, alg_id=3 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=15, alg_id=6 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=15, alg_id=7 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=15, alg_id=12 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=15, alg_id=252 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=15, alg_id=253 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | kernel_alg_add():satype=3, exttype=15, alg_id=13 >Mar 28 17:14:04 eal5 pluto[24248]: | 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 >Mar 28 17:14:04 eal5 pluto[24248]: | ESP registered with kernel. >Mar 28 17:14:04 eal5 pluto[24248]: | finish_pfkey_msg: SADB_REGISTER message 3 for IPCOMP >Mar 28 17:14:04 eal5 pluto[24248]: | 02 07 00 09 02 00 00 00 03 00 00 00 b8 5e 00 00 >Mar 28 17:14:05 eal5 pluto[24248]: | pfkey_get: SADB_REGISTER message 3 >Mar 28 17:14:05 eal5 pluto[24248]: | IPCOMP registered with kernel. >Mar 28 17:14:05 eal5 pluto[24248]: Could not change to directory '/etc/ipsec.d/cacerts': / >Mar 28 17:14:05 eal5 pluto[24248]: Could not change to directory '/etc/ipsec.d/aacerts': / >Mar 28 17:14:05 eal5 pluto[24248]: Could not change to directory '/etc/ipsec.d/ocspcerts': / >Mar 28 17:14:05 eal5 pluto[24248]: Could not change to directory '/etc/ipsec.d/crls' >Mar 28 17:14:05 eal5 pluto[24248]: | inserting event EVENT_LOG_DAILY, timeout in 24355 seconds >Mar 28 17:14:05 eal5 pluto[24248]: | next event EVENT_PENDING_PHASE2 in 118 seconds >Mar 28 17:14:05 eal5 pluto[24248]: | >Mar 28 17:14:05 eal5 pluto[24248]: | *received whack message >Mar 28 17:14:05 eal5 pluto[24248]: | Added new connection openswan-strongswan with policy PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Mar 28 17:14:05 eal5 pluto[24248]: | counting wild cards for 9.3.190.198 is 0 >Mar 28 17:14:05 eal5 pluto[24248]: | counting wild cards for 9.47.66.68 is 0 >Mar 28 17:14:05 eal5 pluto[24248]: added connection description "openswan-strongswan" >Mar 28 17:14:05 eal5 pluto[24248]: | 9.3.190.198<9.3.190.198>[S=C]...9.47.66.68<9.47.66.68>[S=C] >Mar 28 17:14:05 eal5 pluto[24248]: | ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 3; policy: PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Mar 28 17:14:05 eal5 pluto[24248]: | * processed 0 messages from cryptographic helpers >Mar 28 17:14:05 eal5 pluto[24248]: | next event EVENT_PENDING_PHASE2 in 118 seconds >Mar 28 17:14:05 eal5 pluto[24248]: | >Mar 28 17:14:05 eal5 pluto[24248]: | *received whack message >Mar 28 17:14:05 eal5 pluto[24248]: listening for IKE messages >Mar 28 17:14:05 eal5 pluto[24248]: | found lo with address 127.0.0.1 >Mar 28 17:14:05 eal5 pluto[24248]: | found eth0 with address 9.3.190.198 >Mar 28 17:14:05 eal5 pluto[24248]: adding interface eth0/eth0 9.3.190.198:500 >Mar 28 17:14:05 eal5 pluto[24248]: adding interface eth0/eth0 9.3.190.198:4500 >Mar 28 17:14:05 eal5 pluto[24248]: adding interface lo/lo 127.0.0.1:500 >Mar 28 17:14:05 eal5 pluto[24248]: adding interface lo/lo 127.0.0.1:4500 >Mar 28 17:14:05 eal5 pluto[24248]: | found lo with address 0000:0000:0000:0000:0000:0000:0000:0001 >Mar 28 17:14:05 eal5 pluto[24248]: adding interface lo/lo ::1:500 >Mar 28 17:14:05 eal5 pluto[24248]: | connect_to_host_pair: 9.3.190.198:500 9.47.66.68:500 -> hp:none >Mar 28 17:14:05 eal5 pluto[24248]: loading secrets from "/etc/ipsec.secrets" >Mar 28 17:14:05 eal5 pluto[24248]: | id type added to secret(0x84c3e78) 1: 9.3.190.198 >Mar 28 17:14:05 eal5 pluto[24248]: | id type added to secret(0x84c3e78) 1: 9.47.66.68 >Mar 28 17:14:05 eal5 pluto[24248]: | Processing PSK at line 1: passed >Mar 28 17:14:05 eal5 pluto[24248]: | * processed 0 messages from cryptographic helpers >Mar 28 17:14:05 eal5 pluto[24248]: | next event EVENT_PENDING_PHASE2 in 118 seconds >Mar 28 17:14:22 eal5 pluto[24248]: | >Mar 28 17:14:22 eal5 pluto[24248]: | *received whack message >Mar 28 17:14:22 eal5 pluto[24248]: | processing connection openswan-strongswan >Mar 28 17:14:22 eal5 pluto[24248]: | empty esp_info, returning defaults >Mar 28 17:14:22 eal5 pluto[24248]: | creating state object #1 at 0x84c4958 >Mar 28 17:14:22 eal5 pluto[24248]: | processing connection openswan-strongswan >Mar 28 17:14:22 eal5 pluto[24248]: | ICOOKIE: 2c e3 d4 e5 cd b0 69 89 >Mar 28 17:14:22 eal5 pluto[24248]: | RCOOKIE: 00 00 00 00 00 00 00 00 >Mar 28 17:14:22 eal5 pluto[24248]: | state hash entry 27 >Mar 28 17:14:22 eal5 pluto[24248]: | inserting state object #1 on chain 27 >Mar 28 17:14:22 eal5 pluto[24248]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #1 >Mar 28 17:14:22 eal5 pluto[24248]: | processing connection openswan-strongswan >Mar 28 17:14:22 eal5 pluto[24248]: | Queuing pending Quick Mode with 9.47.66.68 "openswan-strongswan" >Mar 28 17:14:22 eal5 pluto[24248]: "openswan-strongswan" #1: initiating v2 parent SA >Mar 28 17:14:22 eal5 pluto[24248]: | no IKE algorithms for this connection >Mar 28 17:14:22 eal5 pluto[24248]: | 1: w->pcw_dead: 0 w->pcw_work: 0 cnt: 3 >Mar 28 17:14:22 eal5 pluto[24248]: | asking helper 1 to do build_kenonce op on seq: 1 (len=2668, pcw_work=1) >Mar 28 17:14:22 eal5 pluto[24248]: | crypto helper write of request: cnt=2668<wlen=2668. >Mar 28 17:14:22 eal5 pluto[24261]: ! helper 1 read 2664+4/2668 bytesfd: 9 >Mar 28 17:14:22 eal5 pluto[24248]: | inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #1 >Mar 28 17:14:22 eal5 pluto[24261]: ! helper 1 doing build_kenonce op id: 1 >Mar 28 17:14:22 eal5 pluto[24248]: | * processed 0 messages from cryptographic helpers >Mar 28 17:14:22 eal5 pluto[24261]: ! Local DH secret: >Mar 28 17:14:22 eal5 pluto[24248]: | next event EVENT_PENDING_PHASE2 in 101 seconds >Mar 28 17:14:22 eal5 pluto[24261]: ! eb cc f1 f8 a5 89 0f e7 e0 f4 d6 e2 53 6b 61 bb >Mar 28 17:14:23 eal5 pluto[24261]: ! 48 36 5c 1a 89 c5 6e 56 f1 3c b4 f2 d9 0d 3d bf >Mar 28 17:14:23 eal5 pluto[24261]: ! Public DH value sent: >Mar 28 17:14:23 eal5 pluto[24261]: ! 26 58 63 dd 8e 38 d8 3d 67 c0 fe d9 c7 4e 62 40 >Mar 28 17:14:23 eal5 pluto[24261]: ! 57 15 fe e5 39 0a 4b 0a 49 46 e2 1d 2b 74 8d df >Mar 28 17:14:23 eal5 pluto[24261]: ! cc d1 98 f5 4f 90 f0 b4 dc 04 97 9d 6b 99 91 d8 >Mar 28 17:14:23 eal5 pluto[24261]: ! ba 4f c2 2d d0 43 0b f7 f2 97 0a 4d 06 4b 24 3a >Mar 28 17:14:23 eal5 pluto[24261]: ! 00 d7 ba 60 f8 ae 81 76 b0 4a 05 2d f6 4e 66 12 >Mar 28 17:14:23 eal5 pluto[24261]: ! bc d3 d9 cd 98 66 38 f0 89 68 96 7a ac d6 f1 99 >Mar 28 17:14:23 eal5 pluto[24261]: ! 54 ea b0 4c c0 75 b3 89 da 6d a7 81 b2 a2 12 f7 >Mar 28 17:14:23 eal5 pluto[24261]: ! a3 ae 4c 7e b9 72 a5 b3 3d 0b 39 ee 08 89 ef 91 >Mar 28 17:14:23 eal5 pluto[24261]: ! e6 48 11 5f 4d 96 4f d9 07 bc 0a 9a f1 20 3b b2 >Mar 28 17:14:23 eal5 pluto[24261]: ! f5 99 8a 28 db 1c a0 5b 89 24 cd a0 51 72 08 83 >Mar 28 17:14:23 eal5 pluto[24261]: ! e4 10 8a 1e cb da df bd db b5 13 8e 0a df 94 07 >Mar 28 17:14:23 eal5 pluto[24261]: ! cf e7 a7 29 49 53 cb f6 33 fe 93 a8 b5 22 95 d3 >Mar 28 17:14:23 eal5 pluto[24261]: ! Generated nonce: >Mar 28 17:14:23 eal5 pluto[24261]: ! 85 dc 71 8e bb 53 a0 79 8c 08 5f 02 7f e0 4e 9f >Mar 28 17:14:23 eal5 pluto[24248]: | >Mar 28 17:14:23 eal5 pluto[24248]: | helper 1 has finished work (cnt now 1) >Mar 28 17:14:23 eal5 pluto[24248]: | helper 1 replies to id: q#1 >Mar 28 17:14:23 eal5 pluto[24248]: | calling callback function 0xbb6960 >Mar 28 17:14:23 eal5 pluto[24248]: | ikev2 parent outI1: calculated ke+nonce, sending I1 >Mar 28 17:14:23 eal5 pluto[24248]: | processing connection openswan-strongswan >Mar 28 17:14:23 eal5 pluto[24248]: | **emit ISAKMP Message: >Mar 28 17:14:23 eal5 pluto[24248]: | initiator cookie: >Mar 28 17:14:23 eal5 pluto[24248]: | 2c e3 d4 e5 cd b0 69 89 >Mar 28 17:14:23 eal5 pluto[24248]: | responder cookie: >Mar 28 17:14:23 eal5 pluto[24248]: | 00 00 00 00 00 00 00 00 >Mar 28 17:14:23 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2SA >Mar 28 17:14:23 eal5 pluto[24248]: | ISAKMP version: IKEv2 version 2.0 (rfc4306) >Mar 28 17:14:23 eal5 pluto[24248]: | exchange type: ISAKMP_v2_SA_INIT >Mar 28 17:14:23 eal5 pluto[24248]: | flags: ISAKMP_FLAG_INIT >Mar 28 17:14:23 eal5 pluto[24248]: | message ID: 00 00 00 00 >Mar 28 17:14:23 eal5 pluto[24248]: | ***emit IKEv2 Security Association Payload: >Mar 28 17:14:23 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2KE >Mar 28 17:14:23 eal5 pluto[24248]: | critical bit: Payload-Critical >Mar 28 17:14:23 eal5 pluto[24248]: | ****emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:23 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_P >Mar 28 17:14:23 eal5 pluto[24248]: | prop #: 1 >Mar 28 17:14:24 eal5 pluto[24248]: | proto ID: 1 >Mar 28 17:14:24 eal5 pluto[24248]: | spi size: 0 >Mar 28 17:14:24 eal5 pluto[24248]: | # transforms: 4 >Mar 28 17:14:24 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:24 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:24 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:24 eal5 pluto[24248]: | transform ID: 12 >Mar 28 17:14:24 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:24 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:24 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:24 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:24 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:24 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:24 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:24 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:24 eal5 pluto[24248]: | transform type: 2 >Mar 28 17:14:24 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:24 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:24 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:24 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:24 eal5 pluto[24248]: | transform type: 4 >Mar 28 17:14:24 eal5 pluto[24248]: | transform ID: 5 >Mar 28 17:14:24 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:24 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Mar 28 17:14:24 eal5 pluto[24248]: | ****emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:24 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_P >Mar 28 17:14:24 eal5 pluto[24248]: | prop #: 2 >Mar 28 17:14:24 eal5 pluto[24248]: | proto ID: 1 >Mar 28 17:14:24 eal5 pluto[24248]: | spi size: 0 >Mar 28 17:14:24 eal5 pluto[24248]: | # transforms: 4 >Mar 28 17:14:24 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:24 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:24 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:24 eal5 pluto[24248]: | transform ID: 12 >Mar 28 17:14:24 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:24 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:24 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:24 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:24 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:24 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:25 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:25 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:25 eal5 pluto[24248]: | transform type: 2 >Mar 28 17:14:25 eal5 pluto[24248]: | transform ID: 1 >Mar 28 17:14:25 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:25 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:25 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:25 eal5 pluto[24248]: | transform type: 4 >Mar 28 17:14:25 eal5 pluto[24248]: | transform ID: 5 >Mar 28 17:14:25 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:25 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Mar 28 17:14:25 eal5 pluto[24248]: | ****emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:25 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_P >Mar 28 17:14:25 eal5 pluto[24248]: | prop #: 3 >Mar 28 17:14:25 eal5 pluto[24248]: | proto ID: 1 >Mar 28 17:14:25 eal5 pluto[24248]: | spi size: 0 >Mar 28 17:14:25 eal5 pluto[24248]: | # transforms: 4 >Mar 28 17:14:25 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:25 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:25 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:25 eal5 pluto[24248]: | transform ID: 3 >Mar 28 17:14:25 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:25 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:25 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:25 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:25 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:25 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:25 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:25 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:25 eal5 pluto[24248]: | transform type: 2 >Mar 28 17:14:25 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:25 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:25 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:25 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:25 eal5 pluto[24248]: | transform type: 4 >Mar 28 17:14:25 eal5 pluto[24248]: | transform ID: 5 >Mar 28 17:14:25 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:25 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Mar 28 17:14:26 eal5 pluto[24248]: | ****emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:26 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_P >Mar 28 17:14:26 eal5 pluto[24248]: | prop #: 4 >Mar 28 17:14:26 eal5 pluto[24248]: | proto ID: 1 >Mar 28 17:14:26 eal5 pluto[24248]: | spi size: 0 >Mar 28 17:14:26 eal5 pluto[24248]: | # transforms: 4 >Mar 28 17:14:26 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:26 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:26 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:26 eal5 pluto[24248]: | transform ID: 3 >Mar 28 17:14:26 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:26 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:26 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:26 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:26 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:26 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:26 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:26 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:26 eal5 pluto[24248]: | transform type: 2 >Mar 28 17:14:26 eal5 pluto[24248]: | transform ID: 1 >Mar 28 17:14:26 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:26 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:26 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:26 eal5 pluto[24248]: | transform type: 4 >Mar 28 17:14:26 eal5 pluto[24248]: | transform ID: 5 >Mar 28 17:14:26 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:26 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Mar 28 17:14:26 eal5 pluto[24248]: | ****emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:26 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_P >Mar 28 17:14:26 eal5 pluto[24248]: | prop #: 5 >Mar 28 17:14:26 eal5 pluto[24248]: | proto ID: 1 >Mar 28 17:14:26 eal5 pluto[24248]: | spi size: 0 >Mar 28 17:14:26 eal5 pluto[24248]: | # transforms: 4 >Mar 28 17:14:26 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:26 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:26 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:26 eal5 pluto[24248]: | transform ID: 3 >Mar 28 17:14:26 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:27 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:27 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:27 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:27 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:27 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:27 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:27 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:27 eal5 pluto[24248]: | transform type: 2 >Mar 28 17:14:27 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:27 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:27 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:27 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:27 eal5 pluto[24248]: | transform type: 4 >Mar 28 17:14:27 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:27 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:27 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Mar 28 17:14:27 eal5 pluto[24248]: | ****emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:27 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_P >Mar 28 17:14:27 eal5 pluto[24248]: | prop #: 6 >Mar 28 17:14:27 eal5 pluto[24248]: | proto ID: 1 >Mar 28 17:14:27 eal5 pluto[24248]: | spi size: 0 >Mar 28 17:14:27 eal5 pluto[24248]: | # transforms: 4 >Mar 28 17:14:27 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:27 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:27 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:27 eal5 pluto[24248]: | transform ID: 3 >Mar 28 17:14:27 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:27 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:27 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:27 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:27 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:27 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:27 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:27 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:27 eal5 pluto[24248]: | transform type: 2 >Mar 28 17:14:27 eal5 pluto[24248]: | transform ID: 1 >Mar 28 17:14:27 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:27 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:28 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:28 eal5 pluto[24248]: | transform type: 4 >Mar 28 17:14:28 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:28 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:28 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Mar 28 17:14:28 eal5 pluto[24248]: | ****emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:28 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_P >Mar 28 17:14:28 eal5 pluto[24248]: | prop #: 7 >Mar 28 17:14:28 eal5 pluto[24248]: | proto ID: 1 >Mar 28 17:14:28 eal5 pluto[24248]: | spi size: 0 >Mar 28 17:14:28 eal5 pluto[24248]: | # transforms: 4 >Mar 28 17:14:28 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:28 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:28 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:28 eal5 pluto[24248]: | transform ID: 12 >Mar 28 17:14:28 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:28 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:28 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:28 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:28 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:28 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:28 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:28 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:28 eal5 pluto[24248]: | transform type: 2 >Mar 28 17:14:28 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:28 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:28 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:28 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:28 eal5 pluto[24248]: | transform type: 4 >Mar 28 17:14:28 eal5 pluto[24248]: | transform ID: 14 >Mar 28 17:14:28 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:28 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Mar 28 17:14:28 eal5 pluto[24248]: | ****emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:28 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_P >Mar 28 17:14:28 eal5 pluto[24248]: | prop #: 8 >Mar 28 17:14:28 eal5 pluto[24248]: | proto ID: 1 >Mar 28 17:14:28 eal5 pluto[24248]: | spi size: 0 >Mar 28 17:14:28 eal5 pluto[24248]: | # transforms: 4 >Mar 28 17:14:29 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:29 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:29 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:29 eal5 pluto[24248]: | transform ID: 12 >Mar 28 17:14:29 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:29 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:29 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:29 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:29 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:29 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:29 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:29 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:29 eal5 pluto[24248]: | transform type: 2 >Mar 28 17:14:29 eal5 pluto[24248]: | transform ID: 1 >Mar 28 17:14:29 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:29 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:29 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:29 eal5 pluto[24248]: | transform type: 4 >Mar 28 17:14:29 eal5 pluto[24248]: | transform ID: 14 >Mar 28 17:14:29 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:29 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Mar 28 17:14:29 eal5 pluto[24248]: | ****emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:29 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_P >Mar 28 17:14:29 eal5 pluto[24248]: | prop #: 9 >Mar 28 17:14:29 eal5 pluto[24248]: | proto ID: 1 >Mar 28 17:14:29 eal5 pluto[24248]: | spi size: 0 >Mar 28 17:14:29 eal5 pluto[24248]: | # transforms: 4 >Mar 28 17:14:29 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:29 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:29 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:29 eal5 pluto[24248]: | transform ID: 3 >Mar 28 17:14:29 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:29 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:29 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:29 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:29 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:29 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:29 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:29 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:30 eal5 pluto[24248]: | transform type: 2 >Mar 28 17:14:30 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:30 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:30 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:30 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:30 eal5 pluto[24248]: | transform type: 4 >Mar 28 17:14:30 eal5 pluto[24248]: | transform ID: 14 >Mar 28 17:14:30 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:30 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Mar 28 17:14:30 eal5 pluto[24248]: | ****emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:30 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:30 eal5 pluto[24248]: | prop #: 10 >Mar 28 17:14:30 eal5 pluto[24248]: | proto ID: 1 >Mar 28 17:14:30 eal5 pluto[24248]: | spi size: 0 >Mar 28 17:14:30 eal5 pluto[24248]: | # transforms: 4 >Mar 28 17:14:30 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:30 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:30 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:30 eal5 pluto[24248]: | transform ID: 3 >Mar 28 17:14:30 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:30 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:30 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:30 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:30 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:30 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:30 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:30 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:30 eal5 pluto[24248]: | transform type: 2 >Mar 28 17:14:30 eal5 pluto[24248]: | transform ID: 1 >Mar 28 17:14:30 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:30 eal5 pluto[24248]: | *****emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:30 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:30 eal5 pluto[24248]: | transform type: 4 >Mar 28 17:14:30 eal5 pluto[24248]: | transform ID: 14 >Mar 28 17:14:30 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:30 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Mar 28 17:14:30 eal5 pluto[24248]: | emitting length of IKEv2 Security Association Payload: 404 >Mar 28 17:14:30 eal5 pluto[24248]: | ***emit IKEv2 Key Exchange Payload: >Mar 28 17:14:31 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2Ni >Mar 28 17:14:31 eal5 pluto[24248]: | transform type: 5 >Mar 28 17:14:31 eal5 pluto[24248]: | emitting 192 raw bytes of ikev2 g^x into IKEv2 Key Exchange Payload >Mar 28 17:14:31 eal5 pluto[24248]: | ikev2 g^x 26 58 63 dd 8e 38 d8 3d 67 c0 fe d9 c7 4e 62 40 >Mar 28 17:14:31 eal5 pluto[24248]: | ikev2 g^x 57 15 fe e5 39 0a 4b 0a 49 46 e2 1d 2b 74 8d df >Mar 28 17:14:31 eal5 pluto[24248]: | ikev2 g^x cc d1 98 f5 4f 90 f0 b4 dc 04 97 9d 6b 99 91 d8 >Mar 28 17:14:31 eal5 pluto[24248]: | ikev2 g^x ba 4f c2 2d d0 43 0b f7 f2 97 0a 4d 06 4b 24 3a >Mar 28 17:14:31 eal5 pluto[24248]: | ikev2 g^x 00 d7 ba 60 f8 ae 81 76 b0 4a 05 2d f6 4e 66 12 >Mar 28 17:14:31 eal5 pluto[24248]: | ikev2 g^x bc d3 d9 cd 98 66 38 f0 89 68 96 7a ac d6 f1 99 >Mar 28 17:14:31 eal5 pluto[24248]: | ikev2 g^x 54 ea b0 4c c0 75 b3 89 da 6d a7 81 b2 a2 12 f7 >Mar 28 17:14:31 eal5 pluto[24248]: | ikev2 g^x a3 ae 4c 7e b9 72 a5 b3 3d 0b 39 ee 08 89 ef 91 >Mar 28 17:14:31 eal5 pluto[24248]: | ikev2 g^x e6 48 11 5f 4d 96 4f d9 07 bc 0a 9a f1 20 3b b2 >Mar 28 17:14:31 eal5 pluto[24248]: | ikev2 g^x f5 99 8a 28 db 1c a0 5b 89 24 cd a0 51 72 08 83 >Mar 28 17:14:31 eal5 pluto[24248]: | ikev2 g^x e4 10 8a 1e cb da df bd db b5 13 8e 0a df 94 07 >Mar 28 17:14:31 eal5 pluto[24248]: | ikev2 g^x cf e7 a7 29 49 53 cb f6 33 fe 93 a8 b5 22 95 d3 >Mar 28 17:14:31 eal5 pluto[24248]: | emitting length of IKEv2 Key Exchange Payload: 200 >Mar 28 17:14:31 eal5 pluto[24248]: | ***emit IKEv2 Nonce Payload: >Mar 28 17:14:31 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2V >Mar 28 17:14:31 eal5 pluto[24248]: | critical bit: Payload-Critical >Mar 28 17:14:31 eal5 pluto[24248]: | emitting 16 raw bytes of IKEv2 nonce into IKEv2 Nonce Payload >Mar 28 17:14:31 eal5 pluto[24248]: | IKEv2 nonce 85 dc 71 8e bb 53 a0 79 8c 08 5f 02 7f e0 4e 9f >Mar 28 17:14:31 eal5 pluto[24248]: | emitting length of IKEv2 Nonce Payload: 20 >Mar 28 17:14:31 eal5 pluto[24248]: | ***emit ISAKMP Vendor ID Payload: >Mar 28 17:14:31 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:31 eal5 pluto[24248]: | emitting 12 raw bytes of Vendor ID into ISAKMP Vendor ID Payload >Mar 28 17:14:31 eal5 pluto[24248]: | Vendor ID 4f 45 5a 44 7e 4d 54 7d 6d 41 6e 6c >Mar 28 17:14:31 eal5 pluto[24248]: | emitting length of ISAKMP Vendor ID Payload: 16 >Mar 28 17:14:31 eal5 pluto[24248]: | emitting length of ISAKMP Message: 668 >Mar 28 17:14:31 eal5 pluto[24248]: | sending 668 bytes for ikev2_parent_outI1_common through eth0:500 to 9.47.66.68:500 (using #1) >Mar 28 17:14:31 eal5 pluto[24248]: | 2c e3 d4 e5 cd b0 69 89 00 00 00 00 00 00 00 00 >Mar 28 17:14:31 eal5 pluto[24248]: | 21 20 22 08 00 00 00 00 00 00 02 9c 22 80 01 94 >Mar 28 17:14:31 eal5 pluto[24248]: | 02 00 00 28 01 01 00 04 03 00 00 08 01 00 00 0c >Mar 28 17:14:31 eal5 pluto[24248]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Mar 28 17:14:31 eal5 pluto[24248]: | 00 00 00 08 04 00 00 05 02 00 00 28 02 01 00 04 >Mar 28 17:14:31 eal5 pluto[24248]: | 03 00 00 08 01 00 00 0c 03 00 00 08 03 00 00 02 >Mar 28 17:14:31 eal5 pluto[24248]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 05 >Mar 28 17:14:32 eal5 pluto[24248]: | 02 00 00 28 03 01 00 04 03 00 00 08 01 00 00 03 >Mar 28 17:14:32 eal5 pluto[24248]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Mar 28 17:14:32 eal5 pluto[24248]: | 00 00 00 08 04 00 00 05 02 00 00 28 04 01 00 04 >Mar 28 17:14:32 eal5 pluto[24248]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Mar 28 17:14:32 eal5 pluto[24248]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 05 >Mar 28 17:14:32 eal5 pluto[24248]: | 02 00 00 28 05 01 00 04 03 00 00 08 01 00 00 03 >Mar 28 17:14:32 eal5 pluto[24248]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Mar 28 17:14:32 eal5 pluto[24248]: | 00 00 00 08 04 00 00 02 02 00 00 28 06 01 00 04 >Mar 28 17:14:32 eal5 pluto[24248]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Mar 28 17:14:32 eal5 pluto[24248]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 02 >Mar 28 17:14:32 eal5 pluto[24248]: | 02 00 00 28 07 01 00 04 03 00 00 08 01 00 00 0c >Mar 28 17:14:32 eal5 pluto[24248]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Mar 28 17:14:32 eal5 pluto[24248]: | 00 00 00 08 04 00 00 0e 02 00 00 28 08 01 00 04 >Mar 28 17:14:32 eal5 pluto[24248]: | 03 00 00 08 01 00 00 0c 03 00 00 08 03 00 00 02 >Mar 28 17:14:32 eal5 pluto[24248]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 0e >Mar 28 17:14:32 eal5 pluto[24248]: | 02 00 00 28 09 01 00 04 03 00 00 08 01 00 00 03 >Mar 28 17:14:32 eal5 pluto[24248]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Mar 28 17:14:32 eal5 pluto[24248]: | 00 00 00 08 04 00 00 0e 00 00 00 28 0a 01 00 04 >Mar 28 17:14:32 eal5 pluto[24248]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Mar 28 17:14:32 eal5 pluto[24248]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 0e >Mar 28 17:14:32 eal5 pluto[24248]: | 28 00 00 c8 00 05 00 00 26 58 63 dd 8e 38 d8 3d >Mar 28 17:14:32 eal5 pluto[24248]: | 67 c0 fe d9 c7 4e 62 40 57 15 fe e5 39 0a 4b 0a >Mar 28 17:14:32 eal5 pluto[24248]: | 49 46 e2 1d 2b 74 8d df cc d1 98 f5 4f 90 f0 b4 >Mar 28 17:14:32 eal5 pluto[24248]: | dc 04 97 9d 6b 99 91 d8 ba 4f c2 2d d0 43 0b f7 >Mar 28 17:14:32 eal5 pluto[24248]: | f2 97 0a 4d 06 4b 24 3a 00 d7 ba 60 f8 ae 81 76 >Mar 28 17:14:32 eal5 pluto[24248]: | b0 4a 05 2d f6 4e 66 12 bc d3 d9 cd 98 66 38 f0 >Mar 28 17:14:32 eal5 pluto[24248]: | 89 68 96 7a ac d6 f1 99 54 ea b0 4c c0 75 b3 89 >Mar 28 17:14:32 eal5 pluto[24248]: | da 6d a7 81 b2 a2 12 f7 a3 ae 4c 7e b9 72 a5 b3 >Mar 28 17:14:32 eal5 pluto[24248]: | 3d 0b 39 ee 08 89 ef 91 e6 48 11 5f 4d 96 4f d9 >Mar 28 17:14:32 eal5 pluto[24248]: | 07 bc 0a 9a f1 20 3b b2 f5 99 8a 28 db 1c a0 5b >Mar 28 17:14:32 eal5 pluto[24248]: | 89 24 cd a0 51 72 08 83 e4 10 8a 1e cb da df bd >Mar 28 17:14:32 eal5 pluto[24248]: | db b5 13 8e 0a df 94 07 cf e7 a7 29 49 53 cb f6 >Mar 28 17:14:32 eal5 pluto[24248]: | 33 fe 93 a8 b5 22 95 d3 2b 80 00 14 85 dc 71 8e >Mar 28 17:14:32 eal5 pluto[24248]: | bb 53 a0 79 8c 08 5f 02 7f e0 4e 9f 00 00 00 10 >Mar 28 17:14:32 eal5 pluto[24248]: | 4f 45 5a 44 7e 4d 54 7d 6d 41 6e 6c >Mar 28 17:14:32 eal5 pluto[24248]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #1 >Mar 28 17:14:32 eal5 pluto[24248]: | complete v2 state transition with STF_OK >Mar 28 17:14:32 eal5 pluto[24248]: "openswan-strongswan" #1: transition from state STATE_IKEv2_START to state STATE_PARENT_I1 >Mar 28 17:14:33 eal5 pluto[24248]: "openswan-strongswan" #1: STATE_PARENT_I1: sent v2I1, expected v2R1 >Mar 28 17:14:33 eal5 pluto[24248]: | * processed 1 messages from cryptographic helpers >Mar 28 17:14:33 eal5 pluto[24248]: | next event EVENT_PENDING_PHASE2 in 90 seconds >Mar 28 17:14:33 eal5 pluto[24248]: | >Mar 28 17:14:33 eal5 pluto[24248]: | *received 292 bytes from 9.47.66.68:500 on eth0 (port=500) >Mar 28 17:14:33 eal5 pluto[24248]: | 2c e3 d4 e5 cd b0 69 89 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:33 eal5 pluto[24248]: | 21 20 22 20 00 00 00 00 00 00 01 24 22 00 00 2c >Mar 28 17:14:33 eal5 pluto[24248]: | 00 00 00 28 01 01 00 04 03 00 00 08 01 00 00 03 >Mar 28 17:14:33 eal5 pluto[24248]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Mar 28 17:14:33 eal5 pluto[24248]: | 00 00 00 08 04 00 00 05 28 00 00 c8 00 05 00 00 >Mar 28 17:14:33 eal5 pluto[24248]: | 87 5e d0 b4 48 92 a2 ac 0e b2 6b 12 84 42 52 92 >Mar 28 17:14:33 eal5 pluto[24248]: | 92 59 0c 6a d8 e8 e6 82 6b 26 09 f5 17 2a 68 d7 >Mar 28 17:14:33 eal5 pluto[24248]: | ea aa d7 ee 8e 00 22 fd 6e d1 0e 1f 10 b3 de 15 >Mar 28 17:14:33 eal5 pluto[24248]: | fb a7 0f b7 e2 60 7f e2 f5 f4 25 cf 95 dc f4 a4 >Mar 28 17:14:33 eal5 pluto[24248]: | 87 d4 3d e7 b6 30 11 42 7e b3 9a ea bb 28 f2 26 >Mar 28 17:14:33 eal5 pluto[24248]: | 10 21 06 9b 79 0c 7d b6 bc 3a e5 97 ac ad ca 07 >Mar 28 17:14:33 eal5 pluto[24248]: | 6a fa f7 e0 cf b1 37 67 5e 5d 30 6c fa 9a f2 4b >Mar 28 17:14:33 eal5 pluto[24248]: | bc c6 e5 82 b3 94 49 d3 9c 14 b0 d2 6a 0e f4 2c >Mar 28 17:14:33 eal5 pluto[24248]: | 04 de 6b 9d da 5c 32 14 17 ba 55 84 1e b4 35 21 >Mar 28 17:14:33 eal5 pluto[24248]: | f5 41 f4 39 7c da 39 65 c0 ed af 17 16 39 16 fd >Mar 28 17:14:33 eal5 pluto[24248]: | f6 45 ce 3d f0 03 e9 d5 fb 07 1a 83 2a 1d ed 29 >Mar 28 17:14:33 eal5 pluto[24248]: | 33 36 9c 34 cb b0 51 03 a8 14 9a 52 8d 41 56 56 >Mar 28 17:14:33 eal5 pluto[24248]: | 00 00 00 14 fc 31 85 5f 00 87 97 36 e3 76 05 eb >Mar 28 17:14:33 eal5 pluto[24248]: | b8 f9 9c e8 >Mar 28 17:14:33 eal5 pluto[24248]: | **parse ISAKMP Message: >Mar 28 17:14:33 eal5 pluto[24248]: | initiator cookie: >Mar 28 17:14:33 eal5 pluto[24248]: | 2c e3 d4 e5 cd b0 69 89 >Mar 28 17:14:33 eal5 pluto[24248]: | responder cookie: >Mar 28 17:14:33 eal5 pluto[24248]: | 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:33 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2SA >Mar 28 17:14:33 eal5 pluto[24248]: | ISAKMP version: IKEv2 version 2.0 (rfc4306) >Mar 28 17:14:33 eal5 pluto[24248]: | exchange type: ISAKMP_v2_SA_INIT >Mar 28 17:14:33 eal5 pluto[24248]: | flags: ISAKMP_FLAG_RESPONSE >Mar 28 17:14:33 eal5 pluto[24248]: | message ID: 00 00 00 00 >Mar 28 17:14:33 eal5 pluto[24248]: | length: 292 >Mar 28 17:14:33 eal5 pluto[24248]: | processing version=2.0 packet with exchange type=ISAKMP_v2_SA_INIT (34) >Mar 28 17:14:33 eal5 pluto[24248]: | ICOOKIE: 2c e3 d4 e5 cd b0 69 89 >Mar 28 17:14:33 eal5 pluto[24248]: | RCOOKIE: 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:33 eal5 pluto[24248]: | state hash entry 15 >Mar 28 17:14:34 eal5 pluto[24248]: | v2 state object not found >Mar 28 17:14:34 eal5 pluto[24248]: | ICOOKIE: 2c e3 d4 e5 cd b0 69 89 >Mar 28 17:14:34 eal5 pluto[24248]: | RCOOKIE: 00 00 00 00 00 00 00 00 >Mar 28 17:14:34 eal5 pluto[24248]: | state hash entry 27 >Mar 28 17:14:34 eal5 pluto[24248]: | v2 peer and cookies match on #1 >Mar 28 17:14:34 eal5 pluto[24248]: | v2 state object #1 found, in STATE_PARENT_I1 >Mar 28 17:14:34 eal5 pluto[24248]: | ICOOKIE: 2c e3 d4 e5 cd b0 69 89 >Mar 28 17:14:34 eal5 pluto[24248]: | RCOOKIE: 00 00 00 00 00 00 00 00 >Mar 28 17:14:34 eal5 pluto[24248]: | state hash entry 27 >Mar 28 17:14:34 eal5 pluto[24248]: | ICOOKIE: 2c e3 d4 e5 cd b0 69 89 >Mar 28 17:14:34 eal5 pluto[24248]: | RCOOKIE: 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:34 eal5 pluto[24248]: | state hash entry 15 >Mar 28 17:14:34 eal5 pluto[24248]: | inserting state object #1 on chain 15 >Mar 28 17:14:34 eal5 pluto[24248]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #1 >Mar 28 17:14:34 eal5 pluto[24248]: | ***parse IKEv2 Security Association Payload: >Mar 28 17:14:34 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2KE >Mar 28 17:14:34 eal5 pluto[24248]: | critical bit: Payload-Non-Critical >Mar 28 17:14:34 eal5 pluto[24248]: | length: 44 >Mar 28 17:14:34 eal5 pluto[24248]: | processing payload: ISAKMP_NEXT_v2SA (len=44) >Mar 28 17:14:34 eal5 pluto[24248]: | ***parse IKEv2 Key Exchange Payload: >Mar 28 17:14:34 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2Ni >Mar 28 17:14:34 eal5 pluto[24248]: | length: 200 >Mar 28 17:14:34 eal5 pluto[24248]: | transform type: 5 >Mar 28 17:14:34 eal5 pluto[24248]: | processing payload: ISAKMP_NEXT_v2KE (len=200) >Mar 28 17:14:34 eal5 pluto[24248]: | ***parse IKEv2 Nonce Payload: >Mar 28 17:14:34 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:34 eal5 pluto[24248]: | critical bit: Payload-Non-Critical >Mar 28 17:14:34 eal5 pluto[24248]: | length: 20 >Mar 28 17:14:34 eal5 pluto[24248]: | processing payload: ISAKMP_NEXT_v2Ni (len=20) >Mar 28 17:14:34 eal5 pluto[24248]: | ikev2 parent inR1: calculating g^{xy} in order to send I2 >Mar 28 17:14:34 eal5 pluto[24248]: | DH public value received: >Mar 28 17:14:34 eal5 pluto[24248]: | 87 5e d0 b4 48 92 a2 ac 0e b2 6b 12 84 42 52 92 >Mar 28 17:14:34 eal5 pluto[24248]: | 92 59 0c 6a d8 e8 e6 82 6b 26 09 f5 17 2a 68 d7 >Mar 28 17:14:34 eal5 pluto[24248]: | ea aa d7 ee 8e 00 22 fd 6e d1 0e 1f 10 b3 de 15 >Mar 28 17:14:34 eal5 pluto[24248]: | fb a7 0f b7 e2 60 7f e2 f5 f4 25 cf 95 dc f4 a4 >Mar 28 17:14:34 eal5 pluto[24248]: | 87 d4 3d e7 b6 30 11 42 7e b3 9a ea bb 28 f2 26 >Mar 28 17:14:34 eal5 pluto[24248]: | 10 21 06 9b 79 0c 7d b6 bc 3a e5 97 ac ad ca 07 >Mar 28 17:14:34 eal5 pluto[24248]: | 6a fa f7 e0 cf b1 37 67 5e 5d 30 6c fa 9a f2 4b >Mar 28 17:14:34 eal5 pluto[24248]: | bc c6 e5 82 b3 94 49 d3 9c 14 b0 d2 6a 0e f4 2c >Mar 28 17:14:34 eal5 pluto[24248]: | 04 de 6b 9d da 5c 32 14 17 ba 55 84 1e b4 35 21 >Mar 28 17:14:35 eal5 pluto[24248]: | f5 41 f4 39 7c da 39 65 c0 ed af 17 16 39 16 fd >Mar 28 17:14:35 eal5 pluto[24248]: | f6 45 ce 3d f0 03 e9 d5 fb 07 1a 83 2a 1d ed 29 >Mar 28 17:14:35 eal5 pluto[24248]: | 33 36 9c 34 cb b0 51 03 a8 14 9a 52 8d 41 56 56 >Mar 28 17:14:35 eal5 pluto[24248]: | ****parse IKEv2 Proposal Substructure Payload: >Mar 28 17:14:35 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:35 eal5 pluto[24248]: | length: 40 >Mar 28 17:14:35 eal5 pluto[24248]: | prop #: 1 >Mar 28 17:14:35 eal5 pluto[24248]: | proto ID: 1 >Mar 28 17:14:35 eal5 pluto[24248]: | spi size: 0 >Mar 28 17:14:35 eal5 pluto[24248]: | # transforms: 4 >Mar 28 17:14:35 eal5 pluto[24248]: | *****parse IKEv2 Transform Substructure Payload: >Mar 28 17:14:35 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:35 eal5 pluto[24248]: | length: 8 >Mar 28 17:14:35 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:35 eal5 pluto[24248]: | transform ID: 3 >Mar 28 17:14:35 eal5 pluto[24248]: | *****parse IKEv2 Transform Substructure Payload: >Mar 28 17:14:35 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:35 eal5 pluto[24248]: | length: 8 >Mar 28 17:14:35 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:35 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:35 eal5 pluto[24248]: | *****parse IKEv2 Transform Substructure Payload: >Mar 28 17:14:35 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:35 eal5 pluto[24248]: | length: 8 >Mar 28 17:14:35 eal5 pluto[24248]: | transform type: 2 >Mar 28 17:14:35 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:35 eal5 pluto[24248]: | *****parse IKEv2 Transform Substructure Payload: >Mar 28 17:14:35 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:35 eal5 pluto[24248]: | length: 8 >Mar 28 17:14:35 eal5 pluto[24248]: | transform type: 4 >Mar 28 17:14:35 eal5 pluto[24248]: | transform ID: 5 >Mar 28 17:14:35 eal5 pluto[24248]: | proposal 1 encr= (policy:aes-cbc vs offered:3des) >Mar 28 17:14:35 eal5 pluto[24248]: | failed integ=(policy:auth-hmac-sha1-96 vs offered:auth-hmac-sha1-96) >Mar 28 17:14:35 eal5 pluto[24248]: | failed prf= (policy:prf-hmac-sha1 vs offered:prf-hmac-sha1) >Mar 28 17:14:35 eal5 pluto[24248]: | failed dh= (policy:OAKLEY_GROUP_MODP1536 vs offered:OAKLEY_GROUP_MODP1536) >Mar 28 17:14:35 eal5 pluto[24248]: | proposal 1 encr= (policy:aes-cbc vs offered:3des) >Mar 28 17:14:35 eal5 pluto[24248]: | failed integ=(policy:auth-hmac-sha1-96 vs offered:auth-hmac-sha1-96) >Mar 28 17:14:35 eal5 pluto[24248]: | prf= (policy:prf-hmac-md5 vs offered:prf-hmac-sha1) >Mar 28 17:14:35 eal5 pluto[24248]: | failed dh= (policy:OAKLEY_GROUP_MODP1536 vs offered:OAKLEY_GROUP_MODP1536) >Mar 28 17:14:36 eal5 pluto[24248]: | calculating skeyseed using prf=prf-hmac-sha1 integ=auth-hmac-sha1-96 cipherkey=3des >Mar 28 17:14:36 eal5 pluto[24248]: | 2: w->pcw_dead: 0 w->pcw_work: 0 cnt: 3 >Mar 28 17:14:36 eal5 pluto[24248]: | asking helper 2 to do compute dh(v2) op on seq: 2 (len=2668, pcw_work=1) >Mar 28 17:14:36 eal5 pluto[24248]: | crypto helper write of request: cnt=2668<wlen=2668. >Mar 28 17:14:36 eal5 pluto[24262]: ! helper 2 read 2664+4/2668 bytesfd: 10 >Mar 28 17:14:36 eal5 pluto[24248]: | inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #1 >Mar 28 17:14:36 eal5 pluto[24262]: ! helper 2 doing compute dh(v2) op id: 2 >Mar 28 17:14:36 eal5 pluto[24248]: | complete v2 state transition with STF_SUSPEND >Mar 28 17:14:36 eal5 pluto[24262]: ! long term secret: eb cc f1 f8 a5 89 0f e7 e0 f4 d6 e2 53 6b 61 bb >Mar 28 17:14:36 eal5 pluto[24248]: | * processed 0 messages from cryptographic helpers >Mar 28 17:14:36 eal5 pluto[24262]: ! long term secret: 48 36 5c 1a 89 c5 6e 56 f1 3c b4 f2 d9 0d 3d bf >Mar 28 17:14:36 eal5 pluto[24248]: | next event EVENT_PENDING_PHASE2 in 87 seconds >Mar 28 17:14:36 eal5 pluto[24262]: ! calc_dh_shared(): time elapsed (OAKLEY_GROUP_MODP1536): 10003 usec >Mar 28 17:14:36 eal5 pluto[24262]: ! DH shared-secret: >Mar 28 17:14:36 eal5 pluto[24262]: ! fd c3 3c b5 f0 9b cb d4 a1 4b 89 c2 e2 14 90 de >Mar 28 17:14:36 eal5 pluto[24262]: ! f6 59 11 3a 42 1e 14 c3 9a 30 46 ca a2 ed 02 39 >Mar 28 17:14:36 eal5 pluto[24262]: ! fc 90 cb 43 dd 8c b2 a3 03 dc 3b 1f ac 13 61 23 >Mar 28 17:14:36 eal5 pluto[24262]: ! f4 37 9d 2c 14 4e f7 ec a3 80 a9 fd 2b 00 78 5a >Mar 28 17:14:36 eal5 pluto[24262]: ! dc 17 a9 c9 e2 49 fc 60 57 fe 30 6a 4e b7 50 ac >Mar 28 17:14:36 eal5 pluto[24262]: ! c9 99 d5 8f 8b ef 8a d7 46 28 cb 93 7f e0 ad e7 >Mar 28 17:14:36 eal5 pluto[24262]: ! 84 31 99 5d ec 94 79 9d 44 bf 3e 6a e8 7e 06 90 >Mar 28 17:14:36 eal5 pluto[24262]: ! 02 9b 12 fc c2 17 87 a3 73 b6 6d 48 09 6b 6d bb >Mar 28 17:14:36 eal5 pluto[24262]: ! 4c d2 2f 15 05 25 ee 62 dd 64 c7 cc f9 48 cc da >Mar 28 17:14:36 eal5 pluto[24262]: ! f0 9a 6d 15 19 97 fa 34 28 cc 0d 9c fe 26 e3 85 >Mar 28 17:14:36 eal5 pluto[24262]: ! 0f fb 32 f9 84 1c fd d3 ea 66 f0 ca 30 ca 60 4c >Mar 28 17:14:36 eal5 pluto[24262]: ! 9c 9a 67 1b 27 6c 6d 1b 62 35 6f 93 2f 8c 3c af >Mar 28 17:14:36 eal5 pluto[24262]: ! calculating skeyseed using prf=prf-hmac-sha1 integ=auth-hmac-sha1-96 cipherkey=24 >Mar 28 17:14:36 eal5 pluto[24262]: ! Input to SKEYSEED: 85 dc 71 8e bb 53 a0 79 8c 08 5f 02 7f e0 4e 9f >Mar 28 17:14:36 eal5 pluto[24262]: ! Input to SKEYSEED: fc 31 85 5f 00 87 97 36 e3 76 05 eb b8 f9 9c e8 >Mar 28 17:14:36 eal5 pluto[24262]: ! PRF+ input >Mar 28 17:14:36 eal5 pluto[24262]: ! Ni 85 dc 71 8e bb 53 a0 79 8c 08 5f 02 7f e0 4e 9f >Mar 28 17:14:36 eal5 pluto[24262]: ! Nr fc 31 85 5f 00 87 97 36 e3 76 05 eb b8 f9 9c e8 >Mar 28 17:14:36 eal5 pluto[24262]: ! SPIi 2c e3 d4 e5 cd b0 69 89 >Mar 28 17:14:36 eal5 pluto[24262]: ! SPIr 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:36 eal5 pluto[24262]: ! prf+[1]: c5 e8 7e 88 58 0e d6 b5 fa 62 20 4b 11 f2 dc 60 >Mar 28 17:14:36 eal5 pluto[24262]: ! prf+[1]: fa 0c f1 f1 >Mar 28 17:14:36 eal5 pluto[24262]: ! prf+[2]: a9 9a af 62 f9 fa 7a 36 6c ef 93 1b be 23 0c d9 >Mar 28 17:14:36 eal5 pluto[24262]: ! prf+[2]: 32 9c cf bc >Mar 28 17:14:37 eal5 pluto[24262]: ! prf+[3]: 50 73 43 de d5 41 f6 ef d6 2b ef ed 61 e9 20 22 >Mar 28 17:14:37 eal5 pluto[24262]: ! prf+[3]: cd 42 c8 88 >Mar 28 17:14:37 eal5 pluto[24262]: ! prf+[4]: 74 f8 26 87 86 a1 cb d8 02 78 77 d8 a7 5a e8 27 >Mar 28 17:14:37 eal5 pluto[24262]: ! prf+[4]: 1c 9c eb 1f >Mar 28 17:14:37 eal5 pluto[24262]: ! prf+[5]: d7 8b 9c be e7 78 54 61 ac 83 74 7a 72 1b 92 2c >Mar 28 17:14:37 eal5 pluto[24262]: ! prf+[5]: 68 da d6 3a >Mar 28 17:14:37 eal5 pluto[24262]: ! prf+[6]: a8 8c 10 d9 d7 a9 3f 2c 92 f8 dc 18 09 18 56 50 >Mar 28 17:14:37 eal5 pluto[24262]: ! prf+[6]: ff 59 5d 76 >Mar 28 17:14:37 eal5 pluto[24262]: ! prf+[7]: f0 40 cf 2c 27 72 33 c2 0e e9 ae a7 e9 94 10 b5 >Mar 28 17:14:37 eal5 pluto[24262]: ! prf+[7]: 40 c1 76 cf >Mar 28 17:14:37 eal5 pluto[24262]: ! prf+[8]: b8 6c e3 24 41 e7 f6 bd 24 26 68 e0 4b 7a 00 c3 >Mar 28 17:14:37 eal5 pluto[24262]: ! prf+[8]: f9 13 d0 ea >Mar 28 17:14:37 eal5 pluto[24262]: ! shared: fd c3 3c b5 f0 9b cb d4 a1 4b 89 c2 e2 14 90 de >Mar 28 17:14:37 eal5 pluto[24262]: ! shared: f6 59 11 3a 42 1e 14 c3 9a 30 46 ca a2 ed 02 39 >Mar 28 17:14:37 eal5 pluto[24262]: ! shared: fc 90 cb 43 dd 8c b2 a3 03 dc 3b 1f ac 13 61 23 >Mar 28 17:14:37 eal5 pluto[24262]: ! shared: f4 37 9d 2c 14 4e f7 ec a3 80 a9 fd 2b 00 78 5a >Mar 28 17:14:37 eal5 pluto[24262]: ! shared: dc 17 a9 c9 e2 49 fc 60 57 fe 30 6a 4e b7 50 ac >Mar 28 17:14:37 eal5 pluto[24262]: ! shared: c9 99 d5 8f 8b ef 8a d7 46 28 cb 93 7f e0 ad e7 >Mar 28 17:14:37 eal5 pluto[24262]: ! shared: 84 31 99 5d ec 94 79 9d 44 bf 3e 6a e8 7e 06 90 >Mar 28 17:14:37 eal5 pluto[24262]: ! shared: 02 9b 12 fc c2 17 87 a3 73 b6 6d 48 09 6b 6d bb >Mar 28 17:14:37 eal5 pluto[24262]: ! shared: 4c d2 2f 15 05 25 ee 62 dd 64 c7 cc f9 48 cc da >Mar 28 17:14:37 eal5 pluto[24262]: ! shared: f0 9a 6d 15 19 97 fa 34 28 cc 0d 9c fe 26 e3 85 >Mar 28 17:14:37 eal5 pluto[24262]: ! shared: 0f fb 32 f9 84 1c fd d3 ea 66 f0 ca 30 ca 60 4c >Mar 28 17:14:37 eal5 pluto[24262]: ! shared: 9c 9a 67 1b 27 6c 6d 1b 62 35 6f 93 2f 8c 3c af >Mar 28 17:14:37 eal5 pluto[24262]: ! skeyseed: a8 70 78 71 c6 21 fb a7 cc b8 bf 50 03 93 2b 16 >Mar 28 17:14:37 eal5 pluto[24262]: ! skeyseed: 56 e3 5d 0a >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_d: c5 e8 7e 88 58 0e d6 b5 fa 62 20 4b 11 f2 dc 60 >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_d: fa 0c f1 f1 >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_ai: a9 9a af 62 f9 fa 7a 36 6c ef 93 1b be 23 0c d9 >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_ai: 32 9c cf bc >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_ar: 50 73 43 de d5 41 f6 ef d6 2b ef ed 61 e9 20 22 >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_ar: cd 42 c8 88 >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_ei: 74 f8 26 87 86 a1 cb d8 02 78 77 d8 a7 5a e8 27 >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_ei: 1c 9c eb 1f d7 8b 9c be >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_er: e7 78 54 61 ac 83 74 7a 72 1b 92 2c 68 da d6 3a >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_er: a8 8c 10 d9 d7 a9 3f 2c >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_pi: 92 f8 dc 18 09 18 56 50 ff 59 5d 76 f0 40 cf 2c >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_pi: 27 72 33 c2 >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_pr: 0e e9 ae a7 e9 94 10 b5 40 c1 76 cf b8 6c e3 24 >Mar 28 17:14:37 eal5 pluto[24262]: ! SK_pr: 41 e7 f6 bd >Mar 28 17:14:38 eal5 pluto[24248]: | >Mar 28 17:14:38 eal5 pluto[24248]: | helper 2 has finished work (cnt now 1) >Mar 28 17:14:38 eal5 pluto[24248]: | helper 2 replies to id: q#2 >Mar 28 17:14:38 eal5 pluto[24248]: | calling callback function 0xbb6670 >Mar 28 17:14:38 eal5 pluto[24248]: | ikev2 parent inR1outI1: calculating g^{xy}, sending I2 >Mar 28 17:14:38 eal5 pluto[24248]: | processing connection openswan-strongswan >Mar 28 17:14:38 eal5 pluto[24248]: | duplicating state object #1 >Mar 28 17:14:38 eal5 pluto[24248]: | creating state object #2 at 0x84c6700 >Mar 28 17:14:38 eal5 pluto[24248]: | ICOOKIE: 2c e3 d4 e5 cd b0 69 89 >Mar 28 17:14:38 eal5 pluto[24248]: | RCOOKIE: 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:38 eal5 pluto[24248]: | state hash entry 15 >Mar 28 17:14:38 eal5 pluto[24248]: | inserting state object #2 on chain 15 >Mar 28 17:14:38 eal5 pluto[24248]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #2 >Mar 28 17:14:38 eal5 pluto[24248]: | **emit ISAKMP Message: >Mar 28 17:14:38 eal5 pluto[24248]: | initiator cookie: >Mar 28 17:14:38 eal5 pluto[24248]: | 2c e3 d4 e5 cd b0 69 89 >Mar 28 17:14:38 eal5 pluto[24248]: | responder cookie: >Mar 28 17:14:38 eal5 pluto[24248]: | 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:38 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2E >Mar 28 17:14:38 eal5 pluto[24248]: | ISAKMP version: IKEv2 version 2.0 (rfc4306) >Mar 28 17:14:38 eal5 pluto[24248]: | exchange type: ISAKMP_v2_AUTH >Mar 28 17:14:38 eal5 pluto[24248]: | flags: ISAKMP_FLAG_INIT >Mar 28 17:14:38 eal5 pluto[24248]: | message ID: 00 00 00 01 >Mar 28 17:14:38 eal5 pluto[24248]: | ***emit IKEv2 Encryption Payload: >Mar 28 17:14:38 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2IDi >Mar 28 17:14:38 eal5 pluto[24248]: | critical bit: Payload-Critical >Mar 28 17:14:38 eal5 pluto[24248]: | emitting 8 zero bytes of iv into IKEv2 Encryption Payload >Mar 28 17:14:38 eal5 pluto[24248]: | IKEv2 thinking whether to send my certificate: >Mar 28 17:14:38 eal5 pluto[24248]: | my policy has no RSASIG, the policy is : PSK+ENCRYPT+TUNNEL+PFS+UP+!IKEv1+IKEv2ALLOW+IKEv2Init >Mar 28 17:14:38 eal5 pluto[24248]: | sendcert: CERT_ALWAYSSEND and I did not get a certificate request >Mar 28 17:14:38 eal5 pluto[24248]: | so do not send cert. >Mar 28 17:14:38 eal5 pluto[24248]: | I did not send a certificate because digital signatures are not being used. (PSK) >Mar 28 17:14:38 eal5 pluto[24248]: | *****emit IKEv2 Identification Payload: >Mar 28 17:14:38 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2AUTH >Mar 28 17:14:38 eal5 pluto[24248]: | id_type: ID_IPV4_ADDR >Mar 28 17:14:38 eal5 pluto[24248]: | emitting 4 raw bytes of my identity into IKEv2 Identification Payload >Mar 28 17:14:38 eal5 pluto[24248]: | my identity 09 03 be c6 >Mar 28 17:14:38 eal5 pluto[24248]: | emitting length of IKEv2 Identification Payload: 12 >Mar 28 17:14:38 eal5 pluto[24248]: | idhash calc pi 92 f8 dc 18 09 18 56 50 ff 59 5d 76 f0 40 cf 2c >Mar 28 17:14:39 eal5 pluto[24248]: | idhash calc pi 27 72 33 c2 >Mar 28 17:14:39 eal5 pluto[24248]: | idhash calc I2 01 00 00 00 09 03 be c6 >Mar 28 17:14:39 eal5 pluto[24248]: | *****emit IKEv2 Authentication Payload: >Mar 28 17:14:39 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2SA >Mar 28 17:14:39 eal5 pluto[24248]: | auth method: v2_AUTH_SHARED >Mar 28 17:14:39 eal5 pluto[24248]: | started looking for secret for 9.3.190.198->9.47.66.68 of kind PPK_PSK >Mar 28 17:14:39 eal5 pluto[24248]: | actually looking for secret for 9.3.190.198->9.47.66.68 of kind PPK_PSK >Mar 28 17:14:39 eal5 pluto[24248]: | line 1: key type PPK_PSK(9.3.190.198) to type PPK_PSK >Mar 28 17:14:39 eal5 pluto[24248]: | 1: compared key 9.47.66.68 to 9.3.190.198 / 9.47.66.68 -> 2 >Mar 28 17:14:39 eal5 pluto[24248]: | 2: compared key 9.3.190.198 to 9.3.190.198 / 9.47.66.68 -> 6 >Mar 28 17:14:39 eal5 pluto[24248]: | line 1: match=6 >Mar 28 17:14:39 eal5 pluto[24248]: | best_match 0>6 best=0x84c3e78 (line=1) >Mar 28 17:14:39 eal5 pluto[24248]: | concluding with best_match=6 best=0x84c3e78 (lineno=1) >Mar 28 17:14:39 eal5 pluto[24248]: | negotiated prf: oakley_sha hash length: 20 >Mar 28 17:14:39 eal5 pluto[24248]: | inner prf ouput 24 bb 6a e6 51 6d 7b 9e 39 6b ee c5 c5 0b a3 46 >Mar 28 17:14:39 eal5 pluto[24248]: | inner prf ouput 00 bb ea 17 >Mar 28 17:14:39 eal5 pluto[24248]: | inputs to hash1 (first packet) >Mar 28 17:14:39 eal5 pluto[24248]: | 2c e3 d4 e5 cd b0 69 89 00 00 00 00 00 00 00 00 >Mar 28 17:14:39 eal5 pluto[24248]: | 21 20 22 08 00 00 00 00 00 00 02 9c 22 80 01 94 >Mar 28 17:14:39 eal5 pluto[24248]: | 02 00 00 28 01 01 00 04 03 00 00 08 01 00 00 0c >Mar 28 17:14:39 eal5 pluto[24248]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Mar 28 17:14:39 eal5 pluto[24248]: | 00 00 00 08 04 00 00 05 02 00 00 28 02 01 00 04 >Mar 28 17:14:39 eal5 pluto[24248]: | 03 00 00 08 01 00 00 0c 03 00 00 08 03 00 00 02 >Mar 28 17:14:39 eal5 pluto[24248]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 05 >Mar 28 17:14:39 eal5 pluto[24248]: | 02 00 00 28 03 01 00 04 03 00 00 08 01 00 00 03 >Mar 28 17:14:39 eal5 pluto[24248]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Mar 28 17:14:39 eal5 pluto[24248]: | 00 00 00 08 04 00 00 05 02 00 00 28 04 01 00 04 >Mar 28 17:14:39 eal5 pluto[24248]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Mar 28 17:14:39 eal5 pluto[24248]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 05 >Mar 28 17:14:39 eal5 pluto[24248]: | 02 00 00 28 05 01 00 04 03 00 00 08 01 00 00 03 >Mar 28 17:14:39 eal5 pluto[24248]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Mar 28 17:14:39 eal5 pluto[24248]: | 00 00 00 08 04 00 00 02 02 00 00 28 06 01 00 04 >Mar 28 17:14:39 eal5 pluto[24248]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Mar 28 17:14:39 eal5 pluto[24248]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 02 >Mar 28 17:14:39 eal5 pluto[24248]: | 02 00 00 28 07 01 00 04 03 00 00 08 01 00 00 0c >Mar 28 17:14:39 eal5 pluto[24248]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Mar 28 17:14:39 eal5 pluto[24248]: | 00 00 00 08 04 00 00 0e 02 00 00 28 08 01 00 04 >Mar 28 17:14:39 eal5 pluto[24248]: | 03 00 00 08 01 00 00 0c 03 00 00 08 03 00 00 02 >Mar 28 17:14:39 eal5 pluto[24248]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 0e >Mar 28 17:14:40 eal5 pluto[24248]: | 02 00 00 28 09 01 00 04 03 00 00 08 01 00 00 03 >Mar 28 17:14:40 eal5 pluto[24248]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Mar 28 17:14:40 eal5 pluto[24248]: | 00 00 00 08 04 00 00 0e 00 00 00 28 0a 01 00 04 >Mar 28 17:14:40 eal5 pluto[24248]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Mar 28 17:14:40 eal5 pluto[24248]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 0e >Mar 28 17:14:40 eal5 pluto[24248]: | 28 00 00 c8 00 05 00 00 26 58 63 dd 8e 38 d8 3d >Mar 28 17:14:40 eal5 pluto[24248]: | 67 c0 fe d9 c7 4e 62 40 57 15 fe e5 39 0a 4b 0a >Mar 28 17:14:40 eal5 pluto[24248]: | 49 46 e2 1d 2b 74 8d df cc d1 98 f5 4f 90 f0 b4 >Mar 28 17:14:40 eal5 pluto[24248]: | dc 04 97 9d 6b 99 91 d8 ba 4f c2 2d d0 43 0b f7 >Mar 28 17:14:40 eal5 pluto[24248]: | f2 97 0a 4d 06 4b 24 3a 00 d7 ba 60 f8 ae 81 76 >Mar 28 17:14:40 eal5 pluto[24248]: | b0 4a 05 2d f6 4e 66 12 bc d3 d9 cd 98 66 38 f0 >Mar 28 17:14:40 eal5 pluto[24248]: | 89 68 96 7a ac d6 f1 99 54 ea b0 4c c0 75 b3 89 >Mar 28 17:14:40 eal5 pluto[24248]: | da 6d a7 81 b2 a2 12 f7 a3 ae 4c 7e b9 72 a5 b3 >Mar 28 17:14:40 eal5 pluto[24248]: | 3d 0b 39 ee 08 89 ef 91 e6 48 11 5f 4d 96 4f d9 >Mar 28 17:14:40 eal5 pluto[24248]: | 07 bc 0a 9a f1 20 3b b2 f5 99 8a 28 db 1c a0 5b >Mar 28 17:14:40 eal5 pluto[24248]: | 89 24 cd a0 51 72 08 83 e4 10 8a 1e cb da df bd >Mar 28 17:14:40 eal5 pluto[24248]: | db b5 13 8e 0a df 94 07 cf e7 a7 29 49 53 cb f6 >Mar 28 17:14:40 eal5 pluto[24248]: | 33 fe 93 a8 b5 22 95 d3 2b 80 00 14 85 dc 71 8e >Mar 28 17:14:40 eal5 pluto[24248]: | bb 53 a0 79 8c 08 5f 02 7f e0 4e 9f 00 00 00 10 >Mar 28 17:14:40 eal5 pluto[24248]: | 4f 45 5a 44 7e 4d 54 7d 6d 41 6e 6c >Mar 28 17:14:40 eal5 pluto[24248]: | inputs to hash2 (responder nonce) >Mar 28 17:14:40 eal5 pluto[24248]: | fc 31 85 5f 00 87 97 36 e3 76 05 eb b8 f9 9c e8 >Mar 28 17:14:40 eal5 pluto[24248]: | idhash 55 40 f9 2d 04 ae b5 82 24 cd fb 31 d0 c3 63 6a >Mar 28 17:14:40 eal5 pluto[24248]: | idhash a4 24 32 da >Mar 28 17:14:40 eal5 pluto[24248]: | PSK auth octets eb c6 4b fa 86 ab d6 88 d6 8d 47 f0 30 fb 05 8a >Mar 28 17:14:40 eal5 pluto[24248]: | PSK auth octets 77 5c af d4 >Mar 28 17:14:40 eal5 pluto[24248]: | emitting 20 raw bytes of PSK auth into IKEv2 Authentication Payload >Mar 28 17:14:40 eal5 pluto[24248]: | PSK auth eb c6 4b fa 86 ab d6 88 d6 8d 47 f0 30 fb 05 8a >Mar 28 17:14:40 eal5 pluto[24248]: | PSK auth 77 5c af d4 >Mar 28 17:14:40 eal5 pluto[24248]: | emitting length of IKEv2 Authentication Payload: 28 >Mar 28 17:14:40 eal5 pluto[24248]: | empty esp_info, returning defaults >Mar 28 17:14:40 eal5 pluto[24248]: | *****emit IKEv2 Security Association Payload: >Mar 28 17:14:40 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2TSi >Mar 28 17:14:40 eal5 pluto[24248]: | critical bit: Payload-Critical >Mar 28 17:14:40 eal5 pluto[24248]: | netlink_get_spi: allocated 0xe746ed33 for esp.0@9.3.190.198 >Mar 28 17:14:40 eal5 pluto[24248]: | ******emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:41 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_P >Mar 28 17:14:41 eal5 pluto[24248]: | prop #: 1 >Mar 28 17:14:41 eal5 pluto[24248]: | proto ID: 3 >Mar 28 17:14:41 eal5 pluto[24248]: | spi size: 4 >Mar 28 17:14:41 eal5 pluto[24248]: | # transforms: 3 >Mar 28 17:14:41 eal5 pluto[24248]: | emitting 4 raw bytes of our spi into IKEv2 Proposal Substructure Payload >Mar 28 17:14:41 eal5 pluto[24248]: | our spi e7 46 ed 33 >Mar 28 17:14:41 eal5 pluto[24248]: | *******emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:41 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:41 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:41 eal5 pluto[24248]: | transform ID: 12 >Mar 28 17:14:41 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:41 eal5 pluto[24248]: | *******emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:41 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:41 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:41 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:41 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:41 eal5 pluto[24248]: | *******emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:41 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:41 eal5 pluto[24248]: | transform type: 5 >Mar 28 17:14:41 eal5 pluto[24248]: | transform ID: 0 >Mar 28 17:14:41 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:41 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 36 >Mar 28 17:14:41 eal5 pluto[24248]: | ******emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:41 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_P >Mar 28 17:14:41 eal5 pluto[24248]: | prop #: 2 >Mar 28 17:14:41 eal5 pluto[24248]: | proto ID: 3 >Mar 28 17:14:41 eal5 pluto[24248]: | spi size: 4 >Mar 28 17:14:41 eal5 pluto[24248]: | # transforms: 3 >Mar 28 17:14:41 eal5 pluto[24248]: | emitting 4 raw bytes of our spi into IKEv2 Proposal Substructure Payload >Mar 28 17:14:41 eal5 pluto[24248]: | our spi e7 46 ed 33 >Mar 28 17:14:41 eal5 pluto[24248]: | *******emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:41 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:41 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:41 eal5 pluto[24248]: | transform ID: 12 >Mar 28 17:14:41 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:41 eal5 pluto[24248]: | *******emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:41 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:42 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:42 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:42 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:42 eal5 pluto[24248]: | *******emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:42 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:42 eal5 pluto[24248]: | transform type: 5 >Mar 28 17:14:42 eal5 pluto[24248]: | transform ID: 0 >Mar 28 17:14:42 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:42 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 36 >Mar 28 17:14:42 eal5 pluto[24248]: | ******emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:42 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_P >Mar 28 17:14:42 eal5 pluto[24248]: | prop #: 3 >Mar 28 17:14:42 eal5 pluto[24248]: | proto ID: 3 >Mar 28 17:14:42 eal5 pluto[24248]: | spi size: 4 >Mar 28 17:14:42 eal5 pluto[24248]: | # transforms: 3 >Mar 28 17:14:42 eal5 pluto[24248]: | emitting 4 raw bytes of our spi into IKEv2 Proposal Substructure Payload >Mar 28 17:14:42 eal5 pluto[24248]: | our spi e7 46 ed 33 >Mar 28 17:14:42 eal5 pluto[24248]: | *******emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:42 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:42 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:42 eal5 pluto[24248]: | transform ID: 3 >Mar 28 17:14:42 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:42 eal5 pluto[24248]: | *******emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:42 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:42 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:42 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:42 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:42 eal5 pluto[24248]: | *******emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:42 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:42 eal5 pluto[24248]: | transform type: 5 >Mar 28 17:14:42 eal5 pluto[24248]: | transform ID: 0 >Mar 28 17:14:42 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:42 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 36 >Mar 28 17:14:42 eal5 pluto[24248]: | ******emit IKEv2 Proposal Substructure Payload: >Mar 28 17:14:42 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:42 eal5 pluto[24248]: | prop #: 4 >Mar 28 17:14:42 eal5 pluto[24248]: | proto ID: 3 >Mar 28 17:14:42 eal5 pluto[24248]: | spi size: 4 >Mar 28 17:14:42 eal5 pluto[24248]: | # transforms: 3 >Mar 28 17:14:43 eal5 pluto[24248]: | emitting 4 raw bytes of our spi into IKEv2 Proposal Substructure Payload >Mar 28 17:14:43 eal5 pluto[24248]: | our spi e7 46 ed 33 >Mar 28 17:14:43 eal5 pluto[24248]: | *******emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:43 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:43 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:43 eal5 pluto[24248]: | transform ID: 3 >Mar 28 17:14:43 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:43 eal5 pluto[24248]: | *******emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:43 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:43 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:43 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:43 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:43 eal5 pluto[24248]: | *******emit IKEv2 Transform Substructure Payload: >Mar 28 17:14:43 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:43 eal5 pluto[24248]: | transform type: 5 >Mar 28 17:14:43 eal5 pluto[24248]: | transform ID: 0 >Mar 28 17:14:43 eal5 pluto[24248]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Mar 28 17:14:43 eal5 pluto[24248]: | emitting length of IKEv2 Proposal Substructure Payload: 36 >Mar 28 17:14:43 eal5 pluto[24248]: | emitting length of IKEv2 Security Association Payload: 148 >Mar 28 17:14:43 eal5 pluto[24248]: | *****emit IKEv2 Traffic Selectors: >Mar 28 17:14:43 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2TSr >Mar 28 17:14:43 eal5 pluto[24248]: | number of TS: 1 >Mar 28 17:14:43 eal5 pluto[24248]: | ******emit IKEv2 Traffic Selectors: >Mar 28 17:14:43 eal5 pluto[24248]: | TS type: ID_IPV4_ADDR_RANGE >Mar 28 17:14:43 eal5 pluto[24248]: | IP Protocol ID: 0 >Mar 28 17:14:43 eal5 pluto[24248]: | start port: 0 >Mar 28 17:14:43 eal5 pluto[24248]: | end port: 65535 >Mar 28 17:14:43 eal5 pluto[24248]: | emitting 4 raw bytes of ipv4 low into IKEv2 Traffic Selectors >Mar 28 17:14:43 eal5 pluto[24248]: | ipv4 low 09 03 be c6 >Mar 28 17:14:43 eal5 pluto[24248]: | emitting 4 raw bytes of ipv4 high into IKEv2 Traffic Selectors >Mar 28 17:14:43 eal5 pluto[24248]: | ipv4 high 09 03 be c6 >Mar 28 17:14:43 eal5 pluto[24248]: | emitting length of IKEv2 Traffic Selectors: 16 >Mar 28 17:14:43 eal5 pluto[24248]: | emitting length of IKEv2 Traffic Selectors: 24 >Mar 28 17:14:43 eal5 pluto[24248]: | *****emit IKEv2 Traffic Selectors: >Mar 28 17:14:43 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:43 eal5 pluto[24248]: | number of TS: 1 >Mar 28 17:14:43 eal5 pluto[24248]: | ******emit IKEv2 Traffic Selectors: >Mar 28 17:14:43 eal5 pluto[24248]: | TS type: ID_IPV4_ADDR_RANGE >Mar 28 17:14:43 eal5 pluto[24248]: | IP Protocol ID: 0 >Mar 28 17:14:44 eal5 pluto[24248]: | start port: 0 >Mar 28 17:14:44 eal5 pluto[24248]: | end port: 65535 >Mar 28 17:14:44 eal5 pluto[24248]: | emitting 4 raw bytes of ipv4 low into IKEv2 Traffic Selectors >Mar 28 17:14:44 eal5 pluto[24248]: | ipv4 low 09 2f 42 44 >Mar 28 17:14:44 eal5 pluto[24248]: | emitting 4 raw bytes of ipv4 high into IKEv2 Traffic Selectors >Mar 28 17:14:44 eal5 pluto[24248]: | ipv4 high 09 2f 42 44 >Mar 28 17:14:44 eal5 pluto[24248]: | emitting length of IKEv2 Traffic Selectors: 16 >Mar 28 17:14:44 eal5 pluto[24248]: | emitting length of IKEv2 Traffic Selectors: 24 >Mar 28 17:14:44 eal5 pluto[24248]: | emitting 4 raw bytes of padding and length into cleartext >Mar 28 17:14:44 eal5 pluto[24248]: | padding and length 00 01 02 03 >Mar 28 17:14:44 eal5 pluto[24248]: | emitting 12 zero bytes of 96-bits of truncated HMAC into IKEv2 Encryption Payload >Mar 28 17:14:44 eal5 pluto[24248]: | emitting length of IKEv2 Encryption Payload: 264 >Mar 28 17:14:44 eal5 pluto[24248]: | emitting length of ISAKMP Message: 292 >Mar 28 17:14:44 eal5 pluto[24248]: | data before encryption: >Mar 28 17:14:44 eal5 pluto[24248]: | 27 00 00 0c 01 00 00 00 09 03 be c6 21 00 00 1c >Mar 28 17:14:44 eal5 pluto[24248]: | 02 00 00 00 eb c6 4b fa 86 ab d6 88 d6 8d 47 f0 >Mar 28 17:14:44 eal5 pluto[24248]: | 30 fb 05 8a 77 5c af d4 2c 80 00 94 02 00 00 24 >Mar 28 17:14:44 eal5 pluto[24248]: | 01 03 04 03 e7 46 ed 33 03 00 00 08 01 00 00 0c >Mar 28 17:14:44 eal5 pluto[24248]: | 03 00 00 08 03 00 00 02 00 00 00 08 05 00 00 00 >Mar 28 17:14:44 eal5 pluto[24248]: | 02 00 00 24 02 03 04 03 e7 46 ed 33 03 00 00 08 >Mar 28 17:14:44 eal5 pluto[24248]: | 01 00 00 0c 03 00 00 08 03 00 00 02 00 00 00 08 >Mar 28 17:14:44 eal5 pluto[24248]: | 05 00 00 00 02 00 00 24 03 03 04 03 e7 46 ed 33 >Mar 28 17:14:44 eal5 pluto[24248]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Mar 28 17:14:44 eal5 pluto[24248]: | 00 00 00 08 05 00 00 00 00 00 00 24 04 03 04 03 >Mar 28 17:14:44 eal5 pluto[24248]: | e7 46 ed 33 03 00 00 08 01 00 00 03 03 00 00 08 >Mar 28 17:14:44 eal5 pluto[24248]: | 03 00 00 02 00 00 00 08 05 00 00 00 2d 00 00 18 >Mar 28 17:14:44 eal5 pluto[24248]: | 01 00 00 00 07 00 00 10 00 00 ff ff 09 03 be c6 >Mar 28 17:14:44 eal5 pluto[24248]: | 09 03 be c6 00 00 00 18 01 00 00 00 07 00 00 10 >Mar 28 17:14:44 eal5 pluto[24248]: | 00 00 ff ff 09 2f 42 44 09 2f 42 44 00 01 02 03 >Mar 28 17:14:44 eal5 pluto[24248]: | data after encryption: >Mar 28 17:14:44 eal5 pluto[24248]: | a6 e8 de fe 60 04 6e 58 71 50 df 9d 42 92 4e 98 >Mar 28 17:14:44 eal5 pluto[24248]: | 0d b5 2c 3f 4a 4a d1 ce cc 76 60 80 aa ce 0b 0f >Mar 28 17:14:44 eal5 pluto[24248]: | 91 8b 0b de 45 33 0c 36 a6 a5 6b ac 40 07 f6 5a >Mar 28 17:14:44 eal5 pluto[24248]: | ba 54 d0 c2 92 2a 3a d6 26 61 b3 06 ba f5 5d 68 >Mar 28 17:14:44 eal5 pluto[24248]: | c8 f3 6e 41 44 17 4c 14 68 cf 0a ab 31 0a 21 fd >Mar 28 17:14:44 eal5 pluto[24248]: | a8 c6 20 2b 26 28 42 df 84 e7 0a 18 8e ec d3 b5 >Mar 28 17:14:44 eal5 pluto[24248]: | 31 a3 51 e7 3f a5 08 98 d7 50 07 93 09 5e a2 f1 >Mar 28 17:14:44 eal5 pluto[24248]: | ec d7 82 ff ca eb 02 cc eb c5 4d 8b d1 bf 8f 55 >Mar 28 17:14:45 eal5 pluto[24248]: | 0f 92 94 65 03 cf 9e 25 2c 8b 00 7c 64 ce 53 2e >Mar 28 17:14:45 eal5 pluto[24248]: | d0 94 65 f7 ff b3 32 b8 c5 f9 30 96 24 02 e5 47 >Mar 28 17:14:45 eal5 pluto[24248]: | 39 0e f9 f4 50 a5 e9 08 0b cf 9a 6b de 75 df 60 >Mar 28 17:14:45 eal5 pluto[24248]: | 81 10 f9 65 79 1d fe 05 e1 83 5f 29 78 83 95 98 >Mar 28 17:14:45 eal5 pluto[24248]: | 2c 28 7f 42 5e 3f 0e 16 28 ac 91 2a b5 f1 bf 8d >Mar 28 17:14:45 eal5 pluto[24248]: | 83 f9 9c 91 e9 b3 48 96 b8 8e 3c a4 9d 82 dc a7 >Mar 28 17:14:45 eal5 pluto[24248]: | cc 6a 98 5d 3c c8 21 b2 1a e6 d4 50 8a 00 3a 69 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: 2c e3 d4 e5 cd b0 69 89 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: 2e 20 23 08 00 00 00 01 00 00 01 24 23 80 01 08 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: a4 9c ad 93 03 f5 a2 2f a6 e8 de fe 60 04 6e 58 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: 71 50 df 9d 42 92 4e 98 0d b5 2c 3f 4a 4a d1 ce >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: cc 76 60 80 aa ce 0b 0f 91 8b 0b de 45 33 0c 36 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: a6 a5 6b ac 40 07 f6 5a ba 54 d0 c2 92 2a 3a d6 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: 26 61 b3 06 ba f5 5d 68 c8 f3 6e 41 44 17 4c 14 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: 68 cf 0a ab 31 0a 21 fd a8 c6 20 2b 26 28 42 df >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: 84 e7 0a 18 8e ec d3 b5 31 a3 51 e7 3f a5 08 98 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: d7 50 07 93 09 5e a2 f1 ec d7 82 ff ca eb 02 cc >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: eb c5 4d 8b d1 bf 8f 55 0f 92 94 65 03 cf 9e 25 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: 2c 8b 00 7c 64 ce 53 2e d0 94 65 f7 ff b3 32 b8 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: c5 f9 30 96 24 02 e5 47 39 0e f9 f4 50 a5 e9 08 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: 0b cf 9a 6b de 75 df 60 81 10 f9 65 79 1d fe 05 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: e1 83 5f 29 78 83 95 98 2c 28 7f 42 5e 3f 0e 16 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: 28 ac 91 2a b5 f1 bf 8d 83 f9 9c 91 e9 b3 48 96 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: b8 8e 3c a4 9d 82 dc a7 cc 6a 98 5d 3c c8 21 b2 >Mar 28 17:14:45 eal5 pluto[24248]: | data being hmac: 1a e6 d4 50 8a 00 3a 69 >Mar 28 17:14:45 eal5 pluto[24248]: | out calculated auth: >Mar 28 17:14:45 eal5 pluto[24248]: | c2 7a 6b e4 36 9e 45 8c 53 ee 5f 45 >Mar 28 17:14:45 eal5 pluto[24248]: | complete v2 state transition with STF_OK >Mar 28 17:14:45 eal5 pluto[24248]: "openswan-strongswan" #2: transition from state STATE_PARENT_I1 to state STATE_PARENT_I2 >Mar 28 17:14:45 eal5 pluto[24248]: "openswan-strongswan" #2: STATE_PARENT_I2: sent v2I2, expected v2R2 {auth=IKEv2 cipher=oakley_3des_cbc_192 integ=sha1 prf=oakley_sha group=modp1536} >Mar 28 17:14:45 eal5 pluto[24248]: | sending reply packet to 9.47.66.68:500 (from port 500) >Mar 28 17:14:45 eal5 pluto[24248]: | sending 292 bytes for STATE_PARENT_I1 through eth0:500 to 9.47.66.68:500 (using #2) >Mar 28 17:14:45 eal5 pluto[24248]: | 2c e3 d4 e5 cd b0 69 89 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:45 eal5 pluto[24248]: | 2e 20 23 08 00 00 00 01 00 00 01 24 23 80 01 08 >Mar 28 17:14:45 eal5 pluto[24248]: | a4 9c ad 93 03 f5 a2 2f a6 e8 de fe 60 04 6e 58 >Mar 28 17:14:45 eal5 pluto[24248]: | 71 50 df 9d 42 92 4e 98 0d b5 2c 3f 4a 4a d1 ce >Mar 28 17:14:45 eal5 pluto[24248]: | cc 76 60 80 aa ce 0b 0f 91 8b 0b de 45 33 0c 36 >Mar 28 17:14:46 eal5 pluto[24248]: | a6 a5 6b ac 40 07 f6 5a ba 54 d0 c2 92 2a 3a d6 >Mar 28 17:14:46 eal5 pluto[24248]: | 26 61 b3 06 ba f5 5d 68 c8 f3 6e 41 44 17 4c 14 >Mar 28 17:14:46 eal5 pluto[24248]: | 68 cf 0a ab 31 0a 21 fd a8 c6 20 2b 26 28 42 df >Mar 28 17:14:46 eal5 pluto[24248]: | 84 e7 0a 18 8e ec d3 b5 31 a3 51 e7 3f a5 08 98 >Mar 28 17:14:46 eal5 pluto[24248]: | d7 50 07 93 09 5e a2 f1 ec d7 82 ff ca eb 02 cc >Mar 28 17:14:46 eal5 pluto[24248]: | eb c5 4d 8b d1 bf 8f 55 0f 92 94 65 03 cf 9e 25 >Mar 28 17:14:46 eal5 pluto[24248]: | 2c 8b 00 7c 64 ce 53 2e d0 94 65 f7 ff b3 32 b8 >Mar 28 17:14:46 eal5 pluto[24248]: | c5 f9 30 96 24 02 e5 47 39 0e f9 f4 50 a5 e9 08 >Mar 28 17:14:46 eal5 pluto[24248]: | 0b cf 9a 6b de 75 df 60 81 10 f9 65 79 1d fe 05 >Mar 28 17:14:46 eal5 pluto[24248]: | e1 83 5f 29 78 83 95 98 2c 28 7f 42 5e 3f 0e 16 >Mar 28 17:14:46 eal5 pluto[24248]: | 28 ac 91 2a b5 f1 bf 8d 83 f9 9c 91 e9 b3 48 96 >Mar 28 17:14:46 eal5 pluto[24248]: | b8 8e 3c a4 9d 82 dc a7 cc 6a 98 5d 3c c8 21 b2 >Mar 28 17:14:46 eal5 pluto[24248]: | 1a e6 d4 50 8a 00 3a 69 c2 7a 6b e4 36 9e 45 8c >Mar 28 17:14:46 eal5 pluto[24248]: | 53 ee 5f 45 >Mar 28 17:14:46 eal5 pluto[24248]: | * processed 1 messages from cryptographic helpers >Mar 28 17:14:46 eal5 pluto[24248]: | next event EVENT_PENDING_PHASE2 in 77 seconds >Mar 28 17:14:46 eal5 pluto[24248]: | >Mar 28 17:14:46 eal5 pluto[24248]: | *received 196 bytes from 9.47.66.68:500 on eth0 (port=500) >Mar 28 17:14:46 eal5 pluto[24248]: | 2c e3 d4 e5 cd b0 69 89 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:46 eal5 pluto[24248]: | 2e 20 23 20 00 00 00 01 00 00 00 c4 24 00 00 a8 >Mar 28 17:14:46 eal5 pluto[24248]: | b4 41 38 b9 ce ad f3 a9 7f 4a 7f 4f 31 d1 77 e8 >Mar 28 17:14:46 eal5 pluto[24248]: | ba e3 62 85 1a a2 a5 ff 9d 0f f5 2b 3f 6b 19 ec >Mar 28 17:14:46 eal5 pluto[24248]: | a2 5f 5f 3b 5e d3 ee 35 69 03 ea a5 7b ef d3 b1 >Mar 28 17:14:46 eal5 pluto[24248]: | 08 2c 4a 0c 31 75 61 ab 42 90 02 ff 56 ed 5a e3 >Mar 28 17:14:46 eal5 pluto[24248]: | 14 0a e6 73 b8 5a b0 66 9c 96 f5 c2 8f 11 9f a7 >Mar 28 17:14:46 eal5 pluto[24248]: | c3 ea 2a 60 62 da 83 3d 1f d1 8c 66 4e 14 1a ae >Mar 28 17:14:46 eal5 pluto[24248]: | 28 de 57 a5 64 ad 70 0e dd c5 af 41 d0 86 fb c0 >Mar 28 17:14:46 eal5 pluto[24248]: | 77 32 22 4b 6d 2a f6 a3 e2 75 95 91 80 ba 52 2c >Mar 28 17:14:46 eal5 pluto[24248]: | 41 7e 1f e0 42 19 73 3d e5 dc af ab a1 f3 23 29 >Mar 28 17:14:46 eal5 pluto[24248]: | 21 85 e6 29 8b 01 a7 54 ff c2 d9 e9 60 0a 07 6e >Mar 28 17:14:46 eal5 pluto[24248]: | e5 f8 17 e9 >Mar 28 17:14:46 eal5 pluto[24248]: | **parse ISAKMP Message: >Mar 28 17:14:46 eal5 pluto[24248]: | initiator cookie: >Mar 28 17:14:46 eal5 pluto[24248]: | 2c e3 d4 e5 cd b0 69 89 >Mar 28 17:14:46 eal5 pluto[24248]: | responder cookie: >Mar 28 17:14:46 eal5 pluto[24248]: | 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:46 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2E >Mar 28 17:14:46 eal5 pluto[24248]: | ISAKMP version: IKEv2 version 2.0 (rfc4306) >Mar 28 17:14:47 eal5 pluto[24248]: | exchange type: ISAKMP_v2_AUTH >Mar 28 17:14:47 eal5 pluto[24248]: | flags: ISAKMP_FLAG_RESPONSE >Mar 28 17:14:47 eal5 pluto[24248]: | message ID: 00 00 00 01 >Mar 28 17:14:47 eal5 pluto[24248]: | length: 196 >Mar 28 17:14:47 eal5 pluto[24248]: | processing version=2.0 packet with exchange type=ISAKMP_v2_AUTH (35) >Mar 28 17:14:47 eal5 pluto[24248]: | ICOOKIE: 2c e3 d4 e5 cd b0 69 89 >Mar 28 17:14:47 eal5 pluto[24248]: | RCOOKIE: 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:47 eal5 pluto[24248]: | state hash entry 15 >Mar 28 17:14:47 eal5 pluto[24248]: | v2 peer, cookies and msgid match on #2 >Mar 28 17:14:47 eal5 pluto[24248]: | v2 state object #2 found, in STATE_PARENT_I2 >Mar 28 17:14:47 eal5 pluto[24248]: | ***parse IKEv2 Encryption Payload: >Mar 28 17:14:47 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2IDr >Mar 28 17:14:47 eal5 pluto[24248]: | critical bit: Payload-Non-Critical >Mar 28 17:14:47 eal5 pluto[24248]: | length: 168 >Mar 28 17:14:47 eal5 pluto[24248]: | processing payload: ISAKMP_NEXT_v2E (len=168) >Mar 28 17:14:47 eal5 pluto[24248]: | ikev2 parent inR2: calculating g^{xy} in order to decrypt I2 >Mar 28 17:14:47 eal5 pluto[24248]: | data being hmac: 2c e3 d4 e5 cd b0 69 89 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:47 eal5 pluto[24248]: | data being hmac: 2e 20 23 20 00 00 00 01 00 00 00 c4 24 00 00 a8 >Mar 28 17:14:47 eal5 pluto[24248]: | data being hmac: b4 41 38 b9 ce ad f3 a9 7f 4a 7f 4f 31 d1 77 e8 >Mar 28 17:14:47 eal5 pluto[24248]: | data being hmac: ba e3 62 85 1a a2 a5 ff 9d 0f f5 2b 3f 6b 19 ec >Mar 28 17:14:47 eal5 pluto[24248]: | data being hmac: a2 5f 5f 3b 5e d3 ee 35 69 03 ea a5 7b ef d3 b1 >Mar 28 17:14:47 eal5 pluto[24248]: | data being hmac: 08 2c 4a 0c 31 75 61 ab 42 90 02 ff 56 ed 5a e3 >Mar 28 17:14:47 eal5 pluto[24248]: | data being hmac: 14 0a e6 73 b8 5a b0 66 9c 96 f5 c2 8f 11 9f a7 >Mar 28 17:14:47 eal5 pluto[24248]: | data being hmac: c3 ea 2a 60 62 da 83 3d 1f d1 8c 66 4e 14 1a ae >Mar 28 17:14:47 eal5 pluto[24248]: | data being hmac: 28 de 57 a5 64 ad 70 0e dd c5 af 41 d0 86 fb c0 >Mar 28 17:14:47 eal5 pluto[24248]: | data being hmac: 77 32 22 4b 6d 2a f6 a3 e2 75 95 91 80 ba 52 2c >Mar 28 17:14:47 eal5 pluto[24248]: | data being hmac: 41 7e 1f e0 42 19 73 3d e5 dc af ab a1 f3 23 29 >Mar 28 17:14:47 eal5 pluto[24248]: | data being hmac: 21 85 e6 29 8b 01 a7 54 >Mar 28 17:14:47 eal5 pluto[24248]: | R2 calculated auth: ff c2 d9 e9 60 0a 07 6e e5 f8 17 e9 >Mar 28 17:14:47 eal5 pluto[24248]: | R2 provided auth: ff c2 d9 e9 60 0a 07 6e e5 f8 17 e9 >Mar 28 17:14:47 eal5 pluto[24248]: | authenticator matched >Mar 28 17:14:47 eal5 pluto[24248]: | data before decryption: >Mar 28 17:14:47 eal5 pluto[24248]: | 7f 4a 7f 4f 31 d1 77 e8 ba e3 62 85 1a a2 a5 ff >Mar 28 17:14:47 eal5 pluto[24248]: | 9d 0f f5 2b 3f 6b 19 ec a2 5f 5f 3b 5e d3 ee 35 >Mar 28 17:14:47 eal5 pluto[24248]: | 69 03 ea a5 7b ef d3 b1 08 2c 4a 0c 31 75 61 ab >Mar 28 17:14:47 eal5 pluto[24248]: | 42 90 02 ff 56 ed 5a e3 14 0a e6 73 b8 5a b0 66 >Mar 28 17:14:47 eal5 pluto[24248]: | 9c 96 f5 c2 8f 11 9f a7 c3 ea 2a 60 62 da 83 3d >Mar 28 17:14:47 eal5 pluto[24248]: | 1f d1 8c 66 4e 14 1a ae 28 de 57 a5 64 ad 70 0e >Mar 28 17:14:48 eal5 pluto[24248]: | dd c5 af 41 d0 86 fb c0 77 32 22 4b 6d 2a f6 a3 >Mar 28 17:14:48 eal5 pluto[24248]: | e2 75 95 91 80 ba 52 2c 41 7e 1f e0 42 19 73 3d >Mar 28 17:14:48 eal5 pluto[24248]: | e5 dc af ab a1 f3 23 29 21 85 e6 29 8b 01 a7 54 >Mar 28 17:14:48 eal5 pluto[24248]: | decrypted payload: 27 00 00 0c 01 00 00 00 09 2f 42 44 21 00 00 1c >Mar 28 17:14:48 eal5 pluto[24248]: | decrypted payload: 02 00 00 00 22 d6 8b 64 c6 08 93 0c 7f 67 76 67 >Mar 28 17:14:48 eal5 pluto[24248]: | decrypted payload: 16 91 6a 95 c0 dc 1f cc 2c 00 00 28 00 00 00 24 >Mar 28 17:14:48 eal5 pluto[24248]: | decrypted payload: 01 03 04 03 cd 83 32 41 03 00 00 08 01 00 00 03 >Mar 28 17:14:48 eal5 pluto[24248]: | decrypted payload: 03 00 00 08 03 00 00 02 00 00 00 08 05 00 00 00 >Mar 28 17:14:48 eal5 pluto[24248]: | decrypted payload: 2d 00 00 18 01 00 00 00 07 00 00 10 00 00 ff ff >Mar 28 17:14:48 eal5 pluto[24248]: | decrypted payload: 09 03 be c6 09 03 be c6 29 00 00 18 01 00 00 00 >Mar 28 17:14:48 eal5 pluto[24248]: | decrypted payload: 07 00 00 10 00 00 ff ff 09 2f 42 44 09 2f 42 44 >Mar 28 17:14:48 eal5 pluto[24248]: | decrypted payload: 00 00 00 0c 00 00 40 13 00 00 26 d8 7e 32 61 03 >Mar 28 17:14:48 eal5 pluto[24248]: | striping 4 bytes as pad >Mar 28 17:14:48 eal5 pluto[24248]: | **parse IKEv2 Identification Payload: >Mar 28 17:14:48 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2AUTH >Mar 28 17:14:48 eal5 pluto[24248]: | length: 12 >Mar 28 17:14:48 eal5 pluto[24248]: | id_type: ID_IPV4_ADDR >Mar 28 17:14:48 eal5 pluto[24248]: | processing payload: ISAKMP_NEXT_v2IDr (len=12) >Mar 28 17:14:48 eal5 pluto[24248]: | **parse IKEv2 Authentication Payload: >Mar 28 17:14:48 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2SA >Mar 28 17:14:48 eal5 pluto[24248]: | length: 28 >Mar 28 17:14:48 eal5 pluto[24248]: | auth method: v2_AUTH_SHARED >Mar 28 17:14:48 eal5 pluto[24248]: | processing payload: ISAKMP_NEXT_v2AUTH (len=28) >Mar 28 17:14:48 eal5 pluto[24248]: | **parse IKEv2 Security Association Payload: >Mar 28 17:14:48 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2TSi >Mar 28 17:14:48 eal5 pluto[24248]: | critical bit: Payload-Non-Critical >Mar 28 17:14:48 eal5 pluto[24248]: | length: 40 >Mar 28 17:14:48 eal5 pluto[24248]: | processing payload: ISAKMP_NEXT_v2SA (len=40) >Mar 28 17:14:48 eal5 pluto[24248]: | **parse IKEv2 Traffic Selectors: >Mar 28 17:14:48 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2TSr >Mar 28 17:14:48 eal5 pluto[24248]: | length: 24 >Mar 28 17:14:48 eal5 pluto[24248]: | number of TS: 1 >Mar 28 17:14:48 eal5 pluto[24248]: | processing payload: ISAKMP_NEXT_v2TSi (len=24) >Mar 28 17:14:48 eal5 pluto[24248]: | **parse IKEv2 Traffic Selectors: >Mar 28 17:14:48 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_v2N >Mar 28 17:14:48 eal5 pluto[24248]: | length: 24 >Mar 28 17:14:48 eal5 pluto[24248]: | number of TS: 1 >Mar 28 17:14:48 eal5 pluto[24248]: | processing payload: ISAKMP_NEXT_v2TSr (len=24) >Mar 28 17:14:49 eal5 pluto[24248]: | **parse IKEv2 Notify Payload: >Mar 28 17:14:49 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:49 eal5 pluto[24248]: | critical bit: Payload-Non-Critical >Mar 28 17:14:49 eal5 pluto[24248]: | length: 12 >Mar 28 17:14:49 eal5 pluto[24248]: | Protocol ID: PROTO_RESERVED >Mar 28 17:14:49 eal5 pluto[24248]: | SPI size: 0 >Mar 28 17:14:49 eal5 pluto[24248]: | Notify Message Type: 16403?? >Mar 28 17:14:49 eal5 pluto[24248]: | processing payload: ISAKMP_NEXT_v2N (len=12) >Mar 28 17:14:49 eal5 pluto[24248]: packet from 9.47.66.68:500: IKEv2 mode peer ID is ID_IPV4_ADDR: '9.47.66.68' >Mar 28 17:14:49 eal5 pluto[24248]: | idhash verify pr 0e e9 ae a7 e9 94 10 b5 40 c1 76 cf b8 6c e3 24 >Mar 28 17:14:49 eal5 pluto[24248]: | idhash verify pr 41 e7 f6 bd >Mar 28 17:14:49 eal5 pluto[24248]: | idhash auth R2 01 00 00 00 09 2f 42 44 >Mar 28 17:14:49 eal5 pluto[24248]: | started looking for secret for 9.3.190.198->9.47.66.68 of kind PPK_PSK >Mar 28 17:14:49 eal5 pluto[24248]: | actually looking for secret for 9.3.190.198->9.47.66.68 of kind PPK_PSK >Mar 28 17:14:49 eal5 pluto[24248]: | line 1: key type PPK_PSK(9.3.190.198) to type PPK_PSK >Mar 28 17:14:49 eal5 pluto[24248]: | 1: compared key 9.47.66.68 to 9.3.190.198 / 9.47.66.68 -> 2 >Mar 28 17:14:49 eal5 pluto[24248]: | 2: compared key 9.3.190.198 to 9.3.190.198 / 9.47.66.68 -> 6 >Mar 28 17:14:49 eal5 pluto[24248]: | line 1: match=6 >Mar 28 17:14:49 eal5 pluto[24248]: | best_match 0>6 best=0x84c3e78 (line=1) >Mar 28 17:14:49 eal5 pluto[24248]: | concluding with best_match=6 best=0x84c3e78 (lineno=1) >Mar 28 17:14:49 eal5 pluto[24248]: | negotiated prf: oakley_sha hash length: 20 >Mar 28 17:14:49 eal5 pluto[24248]: | inner prf ouput 24 bb 6a e6 51 6d 7b 9e 39 6b ee c5 c5 0b a3 46 >Mar 28 17:14:49 eal5 pluto[24248]: | inner prf ouput 00 bb ea 17 >Mar 28 17:14:49 eal5 pluto[24248]: | inputs to hash1 (first packet) >Mar 28 17:14:49 eal5 pluto[24248]: | 2c e3 d4 e5 cd b0 69 89 84 55 6c f2 43 75 00 b3 >Mar 28 17:14:49 eal5 pluto[24248]: | 21 20 22 20 00 00 00 00 00 00 01 24 22 00 00 2c >Mar 28 17:14:49 eal5 pluto[24248]: | 00 00 00 28 01 01 00 04 03 00 00 08 01 00 00 03 >Mar 28 17:14:49 eal5 pluto[24248]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Mar 28 17:14:49 eal5 pluto[24248]: | 00 00 00 08 04 00 00 05 28 00 00 c8 00 05 00 00 >Mar 28 17:14:49 eal5 pluto[24248]: | 87 5e d0 b4 48 92 a2 ac 0e b2 6b 12 84 42 52 92 >Mar 28 17:14:49 eal5 pluto[24248]: | 92 59 0c 6a d8 e8 e6 82 6b 26 09 f5 17 2a 68 d7 >Mar 28 17:14:49 eal5 pluto[24248]: | ea aa d7 ee 8e 00 22 fd 6e d1 0e 1f 10 b3 de 15 >Mar 28 17:14:49 eal5 pluto[24248]: | fb a7 0f b7 e2 60 7f e2 f5 f4 25 cf 95 dc f4 a4 >Mar 28 17:14:49 eal5 pluto[24248]: | 87 d4 3d e7 b6 30 11 42 7e b3 9a ea bb 28 f2 26 >Mar 28 17:14:49 eal5 pluto[24248]: | 10 21 06 9b 79 0c 7d b6 bc 3a e5 97 ac ad ca 07 >Mar 28 17:14:49 eal5 pluto[24248]: | 6a fa f7 e0 cf b1 37 67 5e 5d 30 6c fa 9a f2 4b >Mar 28 17:14:49 eal5 pluto[24248]: | bc c6 e5 82 b3 94 49 d3 9c 14 b0 d2 6a 0e f4 2c >Mar 28 17:14:49 eal5 pluto[24248]: | 04 de 6b 9d da 5c 32 14 17 ba 55 84 1e b4 35 21 >Mar 28 17:14:49 eal5 pluto[24248]: | f5 41 f4 39 7c da 39 65 c0 ed af 17 16 39 16 fd >Mar 28 17:14:50 eal5 pluto[24248]: | f6 45 ce 3d f0 03 e9 d5 fb 07 1a 83 2a 1d ed 29 >Mar 28 17:14:50 eal5 pluto[24248]: | 33 36 9c 34 cb b0 51 03 a8 14 9a 52 8d 41 56 56 >Mar 28 17:14:50 eal5 pluto[24248]: | 00 00 00 14 fc 31 85 5f 00 87 97 36 e3 76 05 eb >Mar 28 17:14:50 eal5 pluto[24248]: | b8 f9 9c e8 >Mar 28 17:14:50 eal5 pluto[24248]: | inputs to hash2 (initiator nonce) >Mar 28 17:14:50 eal5 pluto[24248]: | 85 dc 71 8e bb 53 a0 79 8c 08 5f 02 7f e0 4e 9f >Mar 28 17:14:50 eal5 pluto[24248]: | idhash 30 d4 eb 7d 1f 3b 32 e0 33 d7 97 9b 30 ab 72 f2 >Mar 28 17:14:50 eal5 pluto[24248]: | idhash 0d a3 a5 17 >Mar 28 17:14:50 eal5 pluto[24248]: | Received PSK auth octets >Mar 28 17:14:50 eal5 pluto[24248]: | 22 d6 8b 64 c6 08 93 0c 7f 67 76 67 16 91 6a 95 >Mar 28 17:14:50 eal5 pluto[24248]: | c0 dc 1f cc >Mar 28 17:14:50 eal5 pluto[24248]: | Calculated PSK auth octets >Mar 28 17:14:50 eal5 pluto[24248]: | 22 d6 8b 64 c6 08 93 0c 7f 67 76 67 16 91 6a 95 >Mar 28 17:14:50 eal5 pluto[24248]: | c0 dc 1f cc >Mar 28 17:14:50 eal5 pluto[24248]: | empty esp_info, returning defaults >Mar 28 17:14:50 eal5 pluto[24248]: | ***parse IKEv2 Proposal Substructure Payload: >Mar 28 17:14:50 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:50 eal5 pluto[24248]: | length: 36 >Mar 28 17:14:50 eal5 pluto[24248]: | prop #: 1 >Mar 28 17:14:50 eal5 pluto[24248]: | proto ID: 3 >Mar 28 17:14:50 eal5 pluto[24248]: | spi size: 4 >Mar 28 17:14:50 eal5 pluto[24248]: | # transforms: 3 >Mar 28 17:14:50 eal5 pluto[24248]: | parsing 4 raw bytes of IKEv2 Proposal Substructure Payload into CHILD SA SPI >Mar 28 17:14:50 eal5 pluto[24248]: | CHILD SA SPI cd 83 32 41 >Mar 28 17:14:50 eal5 pluto[24248]: | SPI received: cd833241 >Mar 28 17:14:50 eal5 pluto[24248]: | ****parse IKEv2 Transform Substructure Payload: >Mar 28 17:14:50 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:50 eal5 pluto[24248]: | length: 8 >Mar 28 17:14:50 eal5 pluto[24248]: | transform type: 1 >Mar 28 17:14:50 eal5 pluto[24248]: | transform ID: 3 >Mar 28 17:14:50 eal5 pluto[24248]: | ****parse IKEv2 Transform Substructure Payload: >Mar 28 17:14:50 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_T >Mar 28 17:14:50 eal5 pluto[24248]: | length: 8 >Mar 28 17:14:50 eal5 pluto[24248]: | transform type: 3 >Mar 28 17:14:50 eal5 pluto[24248]: | transform ID: 2 >Mar 28 17:14:50 eal5 pluto[24248]: | ****parse IKEv2 Transform Substructure Payload: >Mar 28 17:14:50 eal5 pluto[24248]: | next payload type: ISAKMP_NEXT_NONE >Mar 28 17:14:50 eal5 pluto[24248]: | length: 8 >Mar 28 17:14:50 eal5 pluto[24248]: | transform type: 5 >Mar 28 17:14:51 eal5 pluto[24248]: | transform ID: 0 >Mar 28 17:14:51 eal5 pluto[24248]: | proposal 1 encr= (policy:aes-cbc vs offered:3des) >Mar 28 17:14:51 eal5 pluto[24248]: | failed integ=(policy:auth-hmac-sha1-96 vs offered:auth-hmac-sha1-96) >Mar 28 17:14:51 eal5 pluto[24248]: | failed esn= (policy:esn-disabled vs offered:esn-disabled) >Mar 28 17:14:51 eal5 pluto[24248]: | proposal 1 encr= (policy:aes-cbc vs offered:3des) >Mar 28 17:14:51 eal5 pluto[24248]: | failed integ=(policy:auth-hmac-sha1-96 vs offered:auth-hmac-sha1-96) >Mar 28 17:14:51 eal5 pluto[24248]: | failed esn= (policy:esn-disabled vs offered:esn-disabled) >Mar 28 17:14:51 eal5 pluto[24248]: | kernel_alg_esp_info():transid=3, auth=2, ei=0xc6f6a8, enckeylen=24, authkeylen=20, encryptalg=3, authalg=3 >Mar 28 17:14:51 eal5 pluto[24248]: | prf+[0]: 4c 75 ac 9c 90 a6 db fb cb 47 7b 50 10 6e 30 03 >Mar 28 17:14:51 eal5 pluto[24248]: | prf+[0]: bb 97 d5 c7 >Mar 28 17:14:51 eal5 pluto[24248]: | prf+[1]: e9 5f cf 52 9d 12 e8 6d ab be 2a 2b 03 b2 d4 03 >Mar 28 17:14:51 eal5 pluto[24248]: | prf+[1]: 6d 93 21 a8 >Mar 28 17:14:51 eal5 pluto[24248]: | prf+[2]: ba a8 ec 4f a6 ed 07 59 d4 74 4f 2d b8 af 72 15 >Mar 28 17:14:51 eal5 pluto[24248]: | prf+[2]: 06 2b 03 fe >Mar 28 17:14:51 eal5 pluto[24248]: | prf+[3]: bb 4a 55 39 cf a5 4a fd 99 58 89 6f 95 8a 6b 7c >Mar 28 17:14:51 eal5 pluto[24248]: | prf+[3]: 9b c6 82 e6 >Mar 28 17:14:51 eal5 pluto[24248]: | prf+[4]: 4c 69 6b 4e bf 41 4a 26 43 75 22 7b 03 e6 cc 05 >Mar 28 17:14:51 eal5 pluto[24248]: | prf+[4]: 84 3c 5f ff >Mar 28 17:14:51 eal5 pluto[24248]: | our keymat 4c 75 ac 9c 90 a6 db fb cb 47 7b 50 10 6e 30 03 >Mar 28 17:14:51 eal5 pluto[24248]: | our keymat bb 97 d5 c7 e9 5f cf 52 9d 12 e8 6d ab be 2a 2b >Mar 28 17:14:51 eal5 pluto[24248]: | our keymat 03 b2 d4 03 6d 93 21 a8 ba a8 ec 4f >Mar 28 17:14:51 eal5 pluto[24248]: | peer keymat a6 ed 07 59 d4 74 4f 2d b8 af 72 15 06 2b 03 fe >Mar 28 17:14:51 eal5 pluto[24248]: | peer keymat bb 4a 55 39 cf a5 4a fd 99 58 89 6f 95 8a 6b 7c >Mar 28 17:14:51 eal5 pluto[24248]: | peer keymat 9b c6 82 e6 4c 69 6b 4e bf 41 4a 26 >Mar 28 17:14:51 eal5 pluto[24248]: | install_ipsec_sa() for #2: inbound and outbound >Mar 28 17:14:51 eal5 pluto[24248]: | route owner of "openswan-strongswan" unrouted: NULL; eroute owner: NULL >Mar 28 17:14:51 eal5 pluto[24248]: | could_route called for openswan-strongswan (kind=CK_PERMANENT) >Mar 28 17:14:51 eal5 pluto[24248]: | looking for alg with transid: 3 keylen: 192 auth: 2 >Mar 28 17:14:51 eal5 pluto[24248]: | checking transid: 11 keylen: 0 auth: 1 >Mar 28 17:14:51 eal5 pluto[24248]: | checking transid: 11 keylen: 0 auth: 2 >Mar 28 17:14:51 eal5 pluto[24248]: | checking transid: 2 keylen: 8 auth: 0 >Mar 28 17:14:51 eal5 pluto[24248]: | checking transid: 2 keylen: 8 auth: 1 >Mar 28 17:14:51 eal5 pluto[24248]: | checking transid: 2 keylen: 8 auth: 2 >Mar 28 17:14:51 eal5 pluto[24248]: | checking transid: 3 keylen: 24 auth: 0 >Mar 28 17:14:51 eal5 pluto[24248]: | checking transid: 3 keylen: 24 auth: 1 >Mar 28 17:14:51 eal5 pluto[24248]: | checking transid: 3 keylen: 24 auth: 2 >Mar 28 17:14:52 eal5 pluto[24248]: | set up outoing SA, ref=0/4294901761 >Mar 28 17:14:52 eal5 pluto[24248]: | looking for alg with transid: 3 keylen: 192 auth: 2 >Mar 28 17:14:52 eal5 pluto[24248]: | checking transid: 11 keylen: 0 auth: 1 >Mar 28 17:14:52 eal5 pluto[24248]: | checking transid: 11 keylen: 0 auth: 2 >Mar 28 17:14:52 eal5 pluto[24248]: | checking transid: 2 keylen: 8 auth: 0 >Mar 28 17:14:52 eal5 pluto[24248]: | checking transid: 2 keylen: 8 auth: 1 >Mar 28 17:14:52 eal5 pluto[24248]: | checking transid: 2 keylen: 8 auth: 2 >Mar 28 17:14:52 eal5 pluto[24248]: | checking transid: 3 keylen: 24 auth: 0 >Mar 28 17:14:52 eal5 pluto[24248]: | checking transid: 3 keylen: 24 auth: 1 >Mar 28 17:14:52 eal5 pluto[24248]: | checking transid: 3 keylen: 24 auth: 2 >Mar 28 17:14:52 eal5 pluto[24248]: | add inbound eroute 9.47.66.68/32:0 --0-> 9.3.190.198/32:0 => tun.10000@9.3.190.198 (raw_eroute) >Mar 28 17:14:52 eal5 pluto[24248]: | raw_eroute result=1 >Mar 28 17:14:52 eal5 pluto[24248]: | set up incoming SA, ref=0/4294901761 >Mar 28 17:14:52 eal5 pluto[24248]: | sr for #2: unrouted >Mar 28 17:14:52 eal5 pluto[24248]: | route owner of "openswan-strongswan" unrouted: NULL; eroute owner: NULL >Mar 28 17:14:52 eal5 pluto[24248]: | route_and_eroute with c: openswan-strongswan (next: none) ero:null esr:{(nil)} ro:null rosr:{(nil)} and state: 2 >Mar 28 17:14:52 eal5 pluto[24248]: | eroute_connection add eroute 9.3.190.198/32:0 --0-> 9.47.66.68/32:0 => tun.0@9.47.66.68 (raw_eroute) >Mar 28 17:14:52 eal5 pluto[24248]: | raw_eroute result=1 >Mar 28 17:14:52 eal5 pluto[24248]: | command executing up-host >Mar 28 17:14:52 eal5 pluto[24248]: | executing up-host: 2>&1 PLUTO_VERSION='2.0' PLUTO_VERB='up-host' PLUTO_CONNECTION='openswan-strongswan' PLUTO_NEXT_HOP='9.47.66.68' PLUTO_INTERFACE='eth0' PLUTO_ME='9.3.190.198' PLUTO_MY_ID='9.3.190.198' PLUTO_MY_CLIENT='9.3.190.198/32' PLUTO_MY_CLIENT_NET='9.3.190.198' PLUTO_MY_CLIENT_MASK='255.255.255.255' PLUTO_MY_PORT='0' PLUTO_MY_PROTOCOL='0' PLUTO_PEER='9.47.66.68' PLUTO_PEER_ID='9.47.66.68' PLUTO_PEER_CLIENT='9.47.66.68/32' PLUTO_PEER_CLIENT_NET='9.47.66.68' PLUTO_PEER_CLIENT_MASK='255.255.255.255' PLUTO_PEER_PORT='0' PLUTO_PEER_PROTOCOL='0' PLUTO_PEER_CA='' PLUTO_STACK='netkey' PLUTO_CONN_POLICY='PSK+ENCRYPT+TUNNEL+PFS+UP+!IKEv1+IKEv2ALLOW+IKEv2Init' PLUTO_XAUTH_USERNAME='' ipsec _updown >Mar 28 17:14:52 eal5 pluto[24248]: | route_and_eroute: firewall_notified: true >Mar 28 17:14:52 eal5 pluto[24248]: | command executing prepare-host >Mar 28 17:14:52 eal5 pluto[24248]: | executing prepare-host: 2>&1 PLUTO_VERSION='2.0' PLUTO_VERB='prepare-host' PLUTO_CONNECTION='openswan-strongswan' PLUTO_NEXT_HOP='9.47.66.68' PLUTO_INTERFACE='eth0' PLUTO_ME='9.3.190.198' PLUTO_MY_ID='9.3.190.198' PLUTO_MY_CLIENT='9.3.190.198/32' PLUTO_MY_CLIENT_NET='9.3.190.198' PLUTO_MY_CLIENT_MASK='255.255.255.255' PLUTO_MY_PORT='0' PLUTO_MY_PROTOCOL='0' PLUTO_PEER='9.47.66.68' PLUTO_PEER_ID='9.47.66.68' PLUTO_PEER_CLIENT='9.47.66.68/32' PLUTO_PEER_CLIENT_NET='9.47.66.68' PLUTO_PEER_CLIENT_MASK='255.255.255.255' PLUTO_PEER_PORT='0' PLUTO_PEER_PROTOCOL='0' PLUTO_PEER_CA='' PLUTO_STACK='netkey' PLUTO_CONN_POLICY='PSK+ENCRYPT+TUNNEL+PFS+UP+!IKEv1+IKEv2ALLOW+IKEv2Init' PLUTO_XAUTH_USERNAME='' ipsec _updown >Mar 28 17:14:52 eal5 pluto[24248]: | command executing route-host >Mar 28 17:14:52 eal5 pluto[24248]: | executing route-host: 2>&1 PLUTO_VERSION='2.0' PLUTO_VERB='route-host' PLUTO_CONNECTION='openswan-strongswan' PLUTO_NEXT_HOP='9.47.66.68' PLUTO_INTERFACE='eth0' PLUTO_ME='9.3.190.198' PLUTO_MY_ID='9.3.190.198' PLUTO_MY_CLIENT='9.3.190.198/32' PLUTO_MY_CLIENT_NET='9.3.190.198' PLUTO_MY_CLIENT_MASK='255.255.255.255' PLUTO_MY_PORT='0' PLUTO_MY_PROTOCOL='0' PLUTO_PEER='9.47.66.68' PLUTO_PEER_ID='9.47.66.68' PLUTO_PEER_CLIENT='9.47.66.68/32' PLUTO_PEER_CLIENT_NET='9.47.66.68' PLUTO_PEER_CLIENT_MASK='255.255.255.255' PLUTO_PEER_PORT='0' PLUTO_PEER_PROTOCOL='0' PLUTO_PEER_CA='' PLUTO_STACK='netkey' PLUTO_CONN_POLICY='PSK+ENCRYPT+TUNNEL+PFS+UP+!IKEv1+IKEv2ALLOW+IKEv2Init' PLUTO_XAUTH_USERNAME='' ipsec _updown >Mar 28 17:14:52 eal5 pluto[24248]: | route_and_eroute: instance "openswan-strongswan", setting eroute_owner {spd=0x84c2f28,sr=0x84c2f28} to #2 (was #0) (newest_ipsec_sa=#2) >Mar 28 17:14:52 eal5 pluto[24248]: | complete v2 state transition with STF_OK >Mar 28 17:14:52 eal5 pluto[24248]: "openswan-strongswan" #2: transition from state STATE_PARENT_I2 to state STATE_PARENT_I3 >Mar 28 17:14:52 eal5 pluto[24248]: "openswan-strongswan" #2: negotiated tunnel [9.3.190.198,9.3.190.198] -> [9.47.66.68,9.47.66.68] >Mar 28 17:14:52 eal5 pluto[24248]: "openswan-strongswan" #2: STATE_PARENT_I3: PARENT SA established tunnel mode {ESP=>0xcd833241 <0xe746ed33 xfrm=3DES_192-HMAC_SHA1 NATOA=none NATD=none DPD=none} >Mar 28 17:14:52 eal5 pluto[24248]: | releasing whack for #2 (sock=22) >Mar 28 17:14:52 eal5 pluto[24248]: | releasing whack for #1 (sock=21) >Mar 28 17:14:52 eal5 pluto[24248]: | * processed 0 messages from cryptographic helpers >Mar 28 17:14:52 eal5 pluto[24248]: | next event EVENT_PENDING_PHASE2 in 71 seconds >Mar 28 17:16:03 eal5 pluto[24248]: | >Mar 28 17:16:03 eal5 pluto[24248]: | *time to handle event >Mar 28 17:16:03 eal5 pluto[24248]: | handling event EVENT_PENDING_PHASE2 >Mar 28 17:16:03 eal5 pluto[24248]: | event after this is EVENT_CRYPTO_FAILED in 213 seconds >Mar 28 17:16:03 eal5 pluto[24248]: | inserting event EVENT_PENDING_PHASE2, timeout in 120 seconds >Mar 28 17:16:03 eal5 pluto[24248]: | pending review: connection "openswan-strongswan" checked >Mar 28 17:16:03 eal5 pluto[24248]: | next event EVENT_PENDING_PHASE2 in 120 seconds >Mar 28 17:18:03 eal5 pluto[24248]: | >Mar 28 17:18:03 eal5 pluto[24248]: | *time to handle event >Mar 28 17:18:03 eal5 pluto[24248]: | handling event EVENT_PENDING_PHASE2 >Mar 28 17:18:03 eal5 pluto[24248]: | event after this is EVENT_CRYPTO_FAILED in 93 seconds >Mar 28 17:18:03 eal5 pluto[24248]: | inserting event EVENT_PENDING_PHASE2, timeout in 120 seconds >Mar 28 17:18:03 eal5 pluto[24248]: | pending review: connection "openswan-strongswan" checked >Mar 28 17:18:03 eal5 pluto[24248]: | next event EVENT_CRYPTO_FAILED in 93 seconds for #1 >Mar 28 17:19:36 eal5 pluto[24248]: | >Mar 28 17:19:36 eal5 pluto[24248]: | *time to handle event >Mar 28 17:19:36 eal5 pluto[24248]: | handling event EVENT_CRYPTO_FAILED >Mar 28 17:19:36 eal5 pluto[24248]: | event after this is EVENT_PENDING_PHASE2 in 27 seconds >Mar 28 17:19:36 eal5 pluto[24248]: | processing connection openswan-strongswan >Mar 28 17:19:36 eal5 pluto[24248]: | event crypto_failed on state #1, aborting >Mar 28 17:19:36 eal5 pluto[24248]: | deleting state #1 >Mar 28 17:19:36 eal5 pluto[24248]: | processing connection openswan-strongswan >Mar 28 17:19:36 eal5 pluto[24248]: | no suspended cryptographic state for 1 >Mar 28 17:19:36 eal5 pluto[24248]: | next event EVENT_PENDING_PHASE2 in 27 seconds >Mar 28 17:20:03 eal5 pluto[24248]: | >Mar 28 17:20:03 eal5 pluto[24248]: | *time to handle event >Mar 28 17:20:03 eal5 pluto[24248]: | handling event EVENT_PENDING_PHASE2 >Mar 28 17:20:03 eal5 pluto[24248]: | event after this is EVENT_REINIT_SECRET in 3240 seconds >Mar 28 17:20:03 eal5 pluto[24248]: | inserting event EVENT_PENDING_PHASE2, timeout in 120 seconds >Mar 28 17:20:03 eal5 pluto[24248]: | pending review: connection "openswan-strongswan" checked >Mar 28 17:20:03 eal5 pluto[24248]: | next event EVENT_PENDING_PHASE2 in 120 seconds >Mar 28 17:22:03 eal5 pluto[24248]: | >Mar 28 17:22:03 eal5 pluto[24248]: | *time to handle event >Mar 28 17:22:03 eal5 pluto[24248]: | handling event EVENT_PENDING_PHASE2 >Mar 28 17:22:03 eal5 pluto[24248]: | event after this is EVENT_REINIT_SECRET in 3120 seconds >Mar 28 17:22:03 eal5 pluto[24248]: | inserting event EVENT_PENDING_PHASE2, timeout in 120 seconds >Mar 28 17:22:03 eal5 pluto[24248]: | pending review: connection "openswan-strongswan" checked >Mar 28 17:22:03 eal5 pluto[24248]: | 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=299704">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 439771
:
299701
|
299702
|
299703
| 299704 |
299705
|
299706
|
300892
|
303405
|
303894
|
303895
|
303896
|
303963
|
303964
|
304445
|
305744
|
305745
|
305940